{"level":"warn","ts":"2026-02-25T06:06:55.350804Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T06:06:55.35091Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.238: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.238:2380","--initial-cluster=instance=https://199.204.45.238:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.238:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.238: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-02-25T06:06:55.350987Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T06:06:55.351008Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-02-25T06:06:55.35104Z","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-02-25T06:06:55.351818Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"]} {"level":"info","ts":"2026-02-25T06:06:55.35191Z","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.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.238: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-02-25T06:06:55.356245Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.061491ms"} {"level":"info","ts":"2026-02-25T06:06:55.363315Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d7dbc9f5893cc1f6","cluster-id":"823d1d08ab21430c"} {"level":"info","ts":"2026-02-25T06:06:55.363455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T06:06:55.363498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 0"} {"level":"info","ts":"2026-02-25T06:06:55.363533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d7dbc9f5893cc1f6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T06:06:55.363558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 1"} {"level":"info","ts":"2026-02-25T06:06:55.363613Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"warn","ts":"2026-02-25T06:06:55.371322Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T06:06:55.373011Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T06:06:55.374768Z","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-02-25T06:06:55.377018Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d7dbc9f5893cc1f6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T06:06:55.377191Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d7dbc9f5893cc1f6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T06:06:55.377207Z","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-02-25T06:06:55.377652Z","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-02-25T06:06:55.377703Z","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-02-25T06:06:55.378921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"info","ts":"2026-02-25T06:06:55.379093Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","added-peer-id":"d7dbc9f5893cc1f6","added-peer-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-02-25T06:06:55.381133Z","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-02-25T06:06:55.381233Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-02-25T06:06:55.381271Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-02-25T06:06:55.381358Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d7dbc9f5893cc1f6","initial-advertise-peer-urls":["https://199.204.45.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T06:06:55.3814Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T06:06:56.064357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T06:06:56.064483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T06:06:56.064511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgPreVoteResp from d7dbc9f5893cc1f6 at term 1"} {"level":"info","ts":"2026-02-25T06:06:56.064532Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became candidate at term 2"} {"level":"info","ts":"2026-02-25T06:06:56.064553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgVoteResp from d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-02-25T06:06:56.064621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became leader at term 2"} {"level":"info","ts":"2026-02-25T06:06:56.064647Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d7dbc9f5893cc1f6 elected leader d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-02-25T06:06:56.065546Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T06:06:56.066488Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d7dbc9f5893cc1f6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.238:2379]}","request-path":"/0/members/d7dbc9f5893cc1f6/attributes","cluster-id":"823d1d08ab21430c","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T06:06:56.06666Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T06:06:56.0667Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T06:06:56.066837Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T06:06:56.066896Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T06:06:56.066866Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T06:06:56.067046Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T06:06:56.067081Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T06:06:56.069867Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T06:06:56.070771Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.238:2379"} {"level":"warn","ts":"2026-02-25T06:07:04.796702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.128075ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:07:04.796819Z","caller":"traceutil/trace.go:171","msg":"trace[1956749752] linearizableReadLoop","detail":"{readStateIndex:336; appliedIndex:335; }","duration":"137.082702ms","start":"2026-02-25T06:07:04.659719Z","end":"2026-02-25T06:07:04.796801Z","steps":["trace[1956749752] 'read index received' (duration: 17.178827ms)","trace[1956749752] 'applied index is now lower than readState.Index' (duration: 119.902735ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:07:04.796931Z","caller":"traceutil/trace.go:171","msg":"trace[1730969828] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"154.429003ms","start":"2026-02-25T06:07:04.64247Z","end":"2026-02-25T06:07:04.796899Z","steps":["trace[1730969828] 'process raft request' (duration: 34.491528ms)","trace[1730969828] 'compare' (duration: 118.962941ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:07:04.797171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.362378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-02-25T06:07:04.797233Z","caller":"traceutil/trace.go:171","msg":"trace[211999541] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:324; }","duration":"137.548434ms","start":"2026-02-25T06:07:04.659658Z","end":"2026-02-25T06:07:04.797206Z","steps":["trace[211999541] 'agreement among raft nodes before linearized reading' (duration: 137.240686ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:05.463248Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976530650515736078,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T06:07:05.964156Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976530650515736078,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T06:07:06.163315Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.362422778s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T06:07:06.229195Z","caller":"traceutil/trace.go:171","msg":"trace[1969220668] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"1.428347088s","start":"2026-02-25T06:07:04.800819Z","end":"2026-02-25T06:07:06.229166Z","steps":["trace[1969220668] 'process raft request' (duration: 1.362790847s)","trace[1969220668] 'compare' (duration: 65.449388ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:07:06.229348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:04.800805Z","time spent":"1.428492822s","remote":"127.0.0.1:51426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1731,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T06:07:06.233821Z","caller":"traceutil/trace.go:171","msg":"trace[2142000136] linearizableReadLoop","detail":"{readStateIndex:339; appliedIndex:336; }","duration":"1.270666354s","start":"2026-02-25T06:07:04.962795Z","end":"2026-02-25T06:07:06.233461Z","steps":["trace[2142000136] 'read index received' (duration: 1.200830896s)","trace[2142000136] 'applied index is now lower than readState.Index' (duration: 69.833798ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:07:06.233649Z","caller":"traceutil/trace.go:171","msg":"trace[1768342211] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"1.426954794s","start":"2026-02-25T06:07:04.80664Z","end":"2026-02-25T06:07:06.233595Z","steps":["trace[1768342211] 'process raft request' (duration: 1.426372789s)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:06.23396Z","caller":"traceutil/trace.go:171","msg":"trace[618273731] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"1.427478217s","start":"2026-02-25T06:07:04.806285Z","end":"2026-02-25T06:07:06.233763Z","steps":["trace[618273731] 'process raft request' (duration: 1.426610965s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:06.234551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:04.806261Z","time spent":"1.427843216s","remote":"127.0.0.1:51830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3620,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T06:07:06.235015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:04.80663Z","time spent":"1.427397435s","remote":"127.0.0.1:51494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4005,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:07:06.236003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.878748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T06:07:06.23607Z","caller":"traceutil/trace.go:171","msg":"trace[830654912] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:327; }","duration":"748.976951ms","start":"2026-02-25T06:07:05.487072Z","end":"2026-02-25T06:07:06.236049Z","steps":["trace[830654912] 'agreement among raft nodes before linearized reading' (duration: 748.737584ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:06.236126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:05.487047Z","time spent":"749.058073ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T06:07:06.236679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.273666609s","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-02-25T06:07:06.2368Z","caller":"traceutil/trace.go:171","msg":"trace[1021303803] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:327; }","duration":"1.274031568s","start":"2026-02-25T06:07:04.962724Z","end":"2026-02-25T06:07:06.236756Z","steps":["trace[1021303803] 'agreement among raft nodes before linearized reading' (duration: 1.2708831s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:06.236929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:04.962691Z","time spent":"1.274183822s","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T06:07:06.23736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.069449326s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-proxy-ds5rt.1897684524a46f78\" ","response":"range_response_count:1 size:769"} {"level":"info","ts":"2026-02-25T06:07:06.23743Z","caller":"traceutil/trace.go:171","msg":"trace[1569250163] range","detail":"{range_begin:/registry/events/kube-system/kube-proxy-ds5rt.1897684524a46f78; range_end:; response_count:1; response_revision:327; }","duration":"1.069558559s","start":"2026-02-25T06:07:05.167846Z","end":"2026-02-25T06:07:06.237404Z","steps":["trace[1569250163] 'agreement among raft nodes before linearized reading' (duration: 1.066913203s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:06.237483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:05.167818Z","time spent":"1.069652031s","remote":"127.0.0.1:51400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":793,"request content":"key:\"/registry/events/kube-system/kube-proxy-ds5rt.1897684524a46f78\" "} {"level":"warn","ts":"2026-02-25T06:07:06.237006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.075227ms","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-02-25T06:07:06.237693Z","caller":"traceutil/trace.go:171","msg":"trace[976524296] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:327; }","duration":"628.645586ms","start":"2026-02-25T06:07:05.609029Z","end":"2026-02-25T06:07:06.237674Z","steps":["trace[976524296] 'agreement among raft nodes before linearized reading' (duration: 626.678896ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:06.237742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:05.609006Z","time spent":"628.715357ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T06:07:06.463895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.863528ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:07:06.464164Z","caller":"traceutil/trace.go:171","msg":"trace[322889887] linearizableReadLoop","detail":"{readStateIndex:345; appliedIndex:340; }","duration":"207.270458ms","start":"2026-02-25T06:07:06.256874Z","end":"2026-02-25T06:07:06.464145Z","steps":["trace[322889887] 'read index received' (duration: 82.016981ms)","trace[322889887] 'applied index is now lower than readState.Index' (duration: 125.252437ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:07:06.464241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.377441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-25T06:07:06.464275Z","caller":"traceutil/trace.go:171","msg":"trace[1210833512] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:333; }","duration":"207.459213ms","start":"2026-02-25T06:07:06.256806Z","end":"2026-02-25T06:07:06.464265Z","steps":["trace[1210833512] 'agreement among raft nodes before linearized reading' (duration: 207.397552ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:06.464328Z","caller":"traceutil/trace.go:171","msg":"trace[625455216] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"222.522468ms","start":"2026-02-25T06:07:06.241781Z","end":"2026-02-25T06:07:06.464304Z","steps":["trace[625455216] 'process raft request' (duration: 222.246881ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:06.464335Z","caller":"traceutil/trace.go:171","msg":"trace[1316418632] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"223.038162ms","start":"2026-02-25T06:07:06.241252Z","end":"2026-02-25T06:07:06.46429Z","steps":["trace[1316418632] 'process raft request' (duration: 97.689322ms)","trace[1316418632] 'compare' (duration: 124.757075ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:07:06.464473Z","caller":"traceutil/trace.go:171","msg":"trace[1615467047] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"216.972909ms","start":"2026-02-25T06:07:06.247489Z","end":"2026-02-25T06:07:06.464461Z","steps":["trace[1615467047] 'process raft request' (duration: 216.61654ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:06.464523Z","caller":"traceutil/trace.go:171","msg":"trace[1760856358] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"222.876576ms","start":"2026-02-25T06:07:06.241588Z","end":"2026-02-25T06:07:06.464464Z","steps":["trace[1760856358] 'process raft request' (duration: 222.389334ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:06.464557Z","caller":"traceutil/trace.go:171","msg":"trace[736563734] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"219.00483ms","start":"2026-02-25T06:07:06.245432Z","end":"2026-02-25T06:07:06.464436Z","steps":["trace[736563734] 'process raft request' (duration: 218.634991ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:06.626809Z","caller":"traceutil/trace.go:171","msg":"trace[402781403] linearizableReadLoop","detail":"{readStateIndex:346; appliedIndex:345; }","duration":"156.036784ms","start":"2026-02-25T06:07:06.470751Z","end":"2026-02-25T06:07:06.626787Z","steps":["trace[402781403] 'read index received' (duration: 124.282154ms)","trace[402781403] 'applied index is now lower than readState.Index' (duration: 31.75357ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:07:06.626843Z","caller":"traceutil/trace.go:171","msg":"trace[1317328115] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"156.31363ms","start":"2026-02-25T06:07:06.470501Z","end":"2026-02-25T06:07:06.626814Z","steps":["trace[1317328115] 'process raft request' (duration: 124.594961ms)","trace[1317328115] 'compare' (duration: 31.566546ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:07:06.626988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.234018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3684"} {"level":"info","ts":"2026-02-25T06:07:06.627026Z","caller":"traceutil/trace.go:171","msg":"trace[1335969910] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:334; }","duration":"156.278779ms","start":"2026-02-25T06:07:06.470734Z","end":"2026-02-25T06:07:06.627013Z","steps":["trace[1335969910] 'agreement among raft nodes before linearized reading' (duration: 156.164796ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:06.630074Z","caller":"traceutil/trace.go:171","msg":"trace[2132642234] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"156.791442ms","start":"2026-02-25T06:07:06.473242Z","end":"2026-02-25T06:07:06.630034Z","steps":["trace[2132642234] 'process raft request' (duration: 156.542676ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:07.897168Z","caller":"traceutil/trace.go:171","msg":"trace[1647936779] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"120.681304ms","start":"2026-02-25T06:07:07.776462Z","end":"2026-02-25T06:07:07.897143Z","steps":["trace[1647936779] 'process raft request' (duration: 120.503109ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:07.939476Z","caller":"traceutil/trace.go:171","msg":"trace[1791581602] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"147.818529ms","start":"2026-02-25T06:07:07.791631Z","end":"2026-02-25T06:07:07.939449Z","steps":["trace[1791581602] 'process raft request' (duration: 133.262576ms)","trace[1791581602] 'compare' (duration: 14.300157ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:07:08.595509Z","caller":"traceutil/trace.go:171","msg":"trace[1214050401] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"124.276343ms","start":"2026-02-25T06:07:08.471203Z","end":"2026-02-25T06:07:08.595479Z","steps":["trace[1214050401] 'process raft request' (duration: 124.103269ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:08.602318Z","caller":"traceutil/trace.go:171","msg":"trace[1852573981] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"130.701271ms","start":"2026-02-25T06:07:08.471592Z","end":"2026-02-25T06:07:08.602294Z","steps":["trace[1852573981] 'process raft request' (duration: 130.566758ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:16.905557Z","caller":"traceutil/trace.go:171","msg":"trace[1408303604] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"226.793704ms","start":"2026-02-25T06:07:16.67874Z","end":"2026-02-25T06:07:16.905534Z","steps":["trace[1408303604] 'process raft request' (duration: 217.699318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:16.905674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.403403ms","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-02-25T06:07:16.905725Z","caller":"traceutil/trace.go:171","msg":"trace[1031521501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:413; }","duration":"195.495324ms","start":"2026-02-25T06:07:16.710209Z","end":"2026-02-25T06:07:16.905704Z","steps":["trace[1031521501] 'agreement among raft nodes before linearized reading' (duration: 195.411952ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:16.905572Z","caller":"traceutil/trace.go:171","msg":"trace[487115508] linearizableReadLoop","detail":"{readStateIndex:429; appliedIndex:428; }","duration":"195.121146ms","start":"2026-02-25T06:07:16.710262Z","end":"2026-02-25T06:07:16.905383Z","steps":["trace[487115508] 'read index received' (duration: 186.130712ms)","trace[487115508] 'applied index is now lower than readState.Index' (duration: 8.989134ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:07:22.023739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.240331ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:07:22.023856Z","caller":"traceutil/trace.go:171","msg":"trace[542717038] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:425; }","duration":"214.367714ms","start":"2026-02-25T06:07:21.80947Z","end":"2026-02-25T06:07:22.023838Z","steps":["trace[542717038] 'range keys from in-memory index tree' (duration: 214.224161ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:23.198268Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976530650515736272,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T06:07:23.28275Z","caller":"traceutil/trace.go:171","msg":"trace[829779432] linearizableReadLoop","detail":"{readStateIndex:443; appliedIndex:442; }","duration":"584.944998ms","start":"2026-02-25T06:07:22.697792Z","end":"2026-02-25T06:07:23.282737Z","steps":["trace[829779432] 'read index received' (duration: 584.834915ms)","trace[829779432] 'applied index is now lower than readState.Index' (duration: 109.463µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:07:23.282855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.117396ms","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-02-25T06:07:23.282873Z","caller":"traceutil/trace.go:171","msg":"trace[39196674] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:426; }","duration":"338.189837ms","start":"2026-02-25T06:07:22.944678Z","end":"2026-02-25T06:07:23.282868Z","steps":["trace[39196674] 'agreement among raft nodes before linearized reading' (duration: 338.139836ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:23.282854Z","caller":"traceutil/trace.go:171","msg":"trace[524048080] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"928.757805ms","start":"2026-02-25T06:07:22.354052Z","end":"2026-02-25T06:07:23.28281Z","steps":["trace[524048080] 'process raft request' (duration: 928.606861ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:23.282954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.469605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-25T06:07:23.282889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:22.944649Z","time spent":"338.236219ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T06:07:23.283018Z","caller":"traceutil/trace.go:171","msg":"trace[572316765] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:426; }","duration":"332.550437ms","start":"2026-02-25T06:07:22.950449Z","end":"2026-02-25T06:07:23.283Z","steps":["trace[572316765] 'agreement among raft nodes before linearized reading' (duration: 332.452374ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:23.282978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.165794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-25T06:07:23.283017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:22.354032Z","time spent":"928.897209ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:07:23.283055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:22.950438Z","time spent":"332.610098ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T06:07:23.283097Z","caller":"traceutil/trace.go:171","msg":"trace[2082568779] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:426; }","duration":"585.339768ms","start":"2026-02-25T06:07:22.697724Z","end":"2026-02-25T06:07:23.283064Z","steps":["trace[2082568779] 'agreement among raft nodes before linearized reading' (duration: 585.104872ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:23.283162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:22.6977Z","time spent":"585.448321ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T06:07:27.889088Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976530650515736356,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T06:07:28.029781Z","caller":"traceutil/trace.go:171","msg":"trace[368871217] linearizableReadLoop","detail":"{readStateIndex:486; appliedIndex:485; }","duration":"640.877902ms","start":"2026-02-25T06:07:27.388887Z","end":"2026-02-25T06:07:28.029765Z","steps":["trace[368871217] 'read index received' (duration: 640.709818ms)","trace[368871217] 'applied index is now lower than readState.Index' (duration: 167.274µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:07:28.029839Z","caller":"traceutil/trace.go:171","msg":"trace[1427950946] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"721.786095ms","start":"2026-02-25T06:07:27.308036Z","end":"2026-02-25T06:07:28.029822Z","steps":["trace[1427950946] 'process raft request' (duration: 721.634962ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:28.029889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.000844ms","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-02-25T06:07:28.029914Z","caller":"traceutil/trace.go:171","msg":"trace[1527114844] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:468; }","duration":"641.062256ms","start":"2026-02-25T06:07:27.388843Z","end":"2026-02-25T06:07:28.029906Z","steps":["trace[1527114844] 'agreement among raft nodes before linearized reading' (duration: 641.009885ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:28.029922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:27.308018Z","time spent":"721.853566ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:07:28.029943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:27.388817Z","time spent":"641.121977ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T06:07:28.030035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.506253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-25T06:07:28.030031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.854951ms","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-02-25T06:07:28.030075Z","caller":"traceutil/trace.go:171","msg":"trace[1156822209] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:468; }","duration":"635.564894ms","start":"2026-02-25T06:07:27.394502Z","end":"2026-02-25T06:07:28.030067Z","steps":["trace[1156822209] 'agreement among raft nodes before linearized reading' (duration: 635.498223ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:28.0301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:27.394494Z","time spent":"635.600316ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T06:07:28.030112Z","caller":"traceutil/trace.go:171","msg":"trace[392592473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:468; }","duration":"635.989334ms","start":"2026-02-25T06:07:27.394102Z","end":"2026-02-25T06:07:28.030092Z","steps":["trace[392592473] 'agreement among raft nodes before linearized reading' (duration: 635.80941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:28.030184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:27.394081Z","time spent":"636.084825ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T06:07:28.662191Z","caller":"traceutil/trace.go:171","msg":"trace[1087695767] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"103.370609ms","start":"2026-02-25T06:07:28.55879Z","end":"2026-02-25T06:07:28.662161Z","steps":["trace[1087695767] 'process raft request' (duration: 103.264957ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:34.399598Z","caller":"traceutil/trace.go:171","msg":"trace[649409995] linearizableReadLoop","detail":"{readStateIndex:528; appliedIndex:527; }","duration":"398.949347ms","start":"2026-02-25T06:07:34.000627Z","end":"2026-02-25T06:07:34.399576Z","steps":["trace[649409995] 'read index received' (duration: 398.827704ms)","trace[649409995] 'applied index is now lower than readState.Index' (duration: 120.883µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:07:34.399662Z","caller":"traceutil/trace.go:171","msg":"trace[2126237845] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"537.332868ms","start":"2026-02-25T06:07:33.8623Z","end":"2026-02-25T06:07:34.399633Z","steps":["trace[2126237845] 'process raft request' (duration: 537.132444ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:34.399744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.10303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:07:34.399771Z","caller":"traceutil/trace.go:171","msg":"trace[1787063318] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:509; }","duration":"399.212832ms","start":"2026-02-25T06:07:34.000547Z","end":"2026-02-25T06:07:34.39976Z","steps":["trace[1787063318] 'agreement among raft nodes before linearized reading' (duration: 399.149781ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:34.399799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:34.000526Z","time spent":"399.265164ms","remote":"127.0.0.1:51334","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-02-25T06:07:34.399827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:33.862278Z","time spent":"537.456511ms","remote":"127.0.0.1:51504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:07:34.399877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.435298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:07:34.399936Z","caller":"traceutil/trace.go:171","msg":"trace[259691912] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:509; }","duration":"141.53291ms","start":"2026-02-25T06:07:34.258383Z","end":"2026-02-25T06:07:34.399916Z","steps":["trace[259691912] 'agreement among raft nodes before linearized reading' (duration: 141.453209ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:34.400435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.600679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-25T06:07:34.40068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.442111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-25T06:07:34.400657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.876827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T06:07:34.400699Z","caller":"traceutil/trace.go:171","msg":"trace[1429621828] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:509; }","duration":"286.891155ms","start":"2026-02-25T06:07:34.113763Z","end":"2026-02-25T06:07:34.400654Z","steps":["trace[1429621828] 'agreement among raft nodes before linearized reading' (duration: 286.133499ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:34.400752Z","caller":"traceutil/trace.go:171","msg":"trace[1380827159] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:509; }","duration":"285.242759ms","start":"2026-02-25T06:07:34.115477Z","end":"2026-02-25T06:07:34.40072Z","steps":["trace[1380827159] 'agreement among raft nodes before linearized reading' (duration: 284.342639ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:34.401136Z","caller":"traceutil/trace.go:171","msg":"trace[827928560] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:509; }","duration":"288.03388ms","start":"2026-02-25T06:07:34.112701Z","end":"2026-02-25T06:07:34.400735Z","steps":["trace[827928560] 'agreement among raft nodes before linearized reading' (duration: 287.213462ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:34.401841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.969163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-25T06:07:34.402042Z","caller":"traceutil/trace.go:171","msg":"trace[1894425667] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:509; }","duration":"338.149037ms","start":"2026-02-25T06:07:34.06377Z","end":"2026-02-25T06:07:34.401919Z","steps":["trace[1894425667] 'agreement among raft nodes before linearized reading' (duration: 335.998049ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:34.402125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:34.063749Z","time spent":"338.361581ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T06:07:35.193195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.359016ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:07:35.193499Z","caller":"traceutil/trace.go:171","msg":"trace[1881308660] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"785.305946ms","start":"2026-02-25T06:07:34.408168Z","end":"2026-02-25T06:07:35.193474Z","steps":["trace[1881308660] 'process raft request' (duration: 785.162273ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:35.193552Z","caller":"traceutil/trace.go:171","msg":"trace[2023409245] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"786.262007ms","start":"2026-02-25T06:07:34.40724Z","end":"2026-02-25T06:07:35.193502Z","steps":["trace[2023409245] 'process raft request' (duration: 59.452551ms)","trace[2023409245] 'compare' (duration: 726.224273ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:07:35.193619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:34.408157Z","time spent":"785.410898ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:07:35.193713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:34.407207Z","time spent":"786.42587ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:07:35.193725Z","caller":"traceutil/trace.go:171","msg":"trace[1395780820] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"784.562111ms","start":"2026-02-25T06:07:34.409137Z","end":"2026-02-25T06:07:35.193699Z","steps":["trace[1395780820] 'process raft request' (duration: 784.281474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:35.193863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:34.409125Z","time spent":"784.665033ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:07:35.836909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.263894ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-25T06:07:35.837038Z","caller":"traceutil/trace.go:171","msg":"trace[149410230] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:529; }","duration":"1.321932518s","start":"2026-02-25T06:07:34.515088Z","end":"2026-02-25T06:07:35.83702Z","steps":["trace[149410230] 'read index received' (duration: 64.681µs)","trace[149410230] 'applied index is now lower than readState.Index' (duration: 1.321866367s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:07:35.83718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.123365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:07:35.837218Z","caller":"traceutil/trace.go:171","msg":"trace[587971153] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:513; }","duration":"836.208017ms","start":"2026-02-25T06:07:35.000998Z","end":"2026-02-25T06:07:35.837206Z","steps":["trace[587971153] 'agreement among raft nodes before linearized reading' (duration: 836.134916ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:35.837252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:35.000974Z","time spent":"836.270559ms","remote":"127.0.0.1:51338","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-02-25T06:07:35.837245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.322143614s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:07:35.837358Z","caller":"traceutil/trace.go:171","msg":"trace[1418011164] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:513; }","duration":"1.322301117s","start":"2026-02-25T06:07:34.515036Z","end":"2026-02-25T06:07:35.837337Z","steps":["trace[1418011164] 'agreement among raft nodes before linearized reading' (duration: 1.322140954s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:35.8374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:34.515017Z","time spent":"1.322374208s","remote":"127.0.0.1:51422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":29,"request content":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" "} {"level":"warn","ts":"2026-02-25T06:07:37.500666Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976530650515736450,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T06:07:37.742584Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.006160405s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T06:07:37.743038Z","caller":"traceutil/trace.go:171","msg":"trace[1257655368] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:533; }","duration":"742.644747ms","start":"2026-02-25T06:07:37.000365Z","end":"2026-02-25T06:07:37.74301Z","steps":["trace[1257655368] 'read index received' (duration: 742.37889ms)","trace[1257655368] 'applied index is now lower than readState.Index' (duration: 264.747µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:07:37.743151Z","caller":"traceutil/trace.go:171","msg":"trace[47089687] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"1.302845208s","start":"2026-02-25T06:07:36.440266Z","end":"2026-02-25T06:07:37.743111Z","steps":["trace[47089687] 'process raft request' (duration: 1.302579421s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:37.743208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.81736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:07:37.743243Z","caller":"traceutil/trace.go:171","msg":"trace[1752083643] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:514; }","duration":"742.907851ms","start":"2026-02-25T06:07:37.000325Z","end":"2026-02-25T06:07:37.743232Z","steps":["trace[1752083643] 'agreement among raft nodes before linearized reading' (duration: 742.811109ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:37.743278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:37.000306Z","time spent":"742.964693ms","remote":"127.0.0.1:51334","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-02-25T06:07:37.74329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:36.440238Z","time spent":"1.302965231s","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:07:37.743305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.979909ms","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-02-25T06:07:37.743357Z","caller":"traceutil/trace.go:171","msg":"trace[1631640602] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:514; }","duration":"546.067271ms","start":"2026-02-25T06:07:37.197274Z","end":"2026-02-25T06:07:37.743342Z","steps":["trace[1631640602] 'agreement among raft nodes before linearized reading' (duration: 545.970909ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:37.743392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:37.197258Z","time spent":"546.125692ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T06:07:37.743393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.180811ms","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-02-25T06:07:37.743461Z","caller":"traceutil/trace.go:171","msg":"trace[562480477] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:514; }","duration":"545.284294ms","start":"2026-02-25T06:07:37.198161Z","end":"2026-02-25T06:07:37.743445Z","steps":["trace[562480477] 'agreement among raft nodes before linearized reading' (duration: 545.132951ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:37.743459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.596032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-cainjector\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T06:07:37.743455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.303685ms","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-02-25T06:07:37.743495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:37.198146Z","time spent":"545.342235ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T06:07:37.743503Z","caller":"traceutil/trace.go:171","msg":"trace[634447552] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-cainjector; range_end:; response_count:0; response_revision:514; }","duration":"136.662193ms","start":"2026-02-25T06:07:37.606826Z","end":"2026-02-25T06:07:37.743488Z","steps":["trace[634447552] 'agreement among raft nodes before linearized reading' (duration: 136.592992ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:37.743534Z","caller":"traceutil/trace.go:171","msg":"trace[962268140] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:514; }","duration":"545.413697ms","start":"2026-02-25T06:07:37.1981Z","end":"2026-02-25T06:07:37.743513Z","steps":["trace[962268140] 'agreement among raft nodes before linearized reading' (duration: 545.220032ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:37.743588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:37.198089Z","time spent":"545.488918ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T06:07:39.174967Z","caller":"traceutil/trace.go:171","msg":"trace[128190931] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:659; }","duration":"157.175606ms","start":"2026-02-25T06:07:39.017758Z","end":"2026-02-25T06:07:39.174934Z","steps":["trace[128190931] 'read index received' (duration: 128.285248ms)","trace[128190931] 'applied index is now lower than readState.Index' (duration: 28.889218ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:07:39.175061Z","caller":"traceutil/trace.go:171","msg":"trace[1111050964] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"183.543747ms","start":"2026-02-25T06:07:38.991484Z","end":"2026-02-25T06:07:39.175028Z","steps":["trace[1111050964] 'process raft request' (duration: 154.514447ms)","trace[1111050964] 'compare' (duration: 28.789575ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:07:39.17521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.437662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.238\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T06:07:39.175294Z","caller":"traceutil/trace.go:171","msg":"trace[1629348456] range","detail":"{range_begin:/registry/masterleases/199.204.45.238; range_end:; response_count:1; response_revision:640; }","duration":"157.567915ms","start":"2026-02-25T06:07:39.017705Z","end":"2026-02-25T06:07:39.175273Z","steps":["trace[1629348456] 'agreement among raft nodes before linearized reading' (duration: 157.33435ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:40.039649Z","caller":"traceutil/trace.go:171","msg":"trace[1900102672] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"137.308338ms","start":"2026-02-25T06:07:39.902291Z","end":"2026-02-25T06:07:40.0396Z","steps":["trace[1900102672] 'process raft request' (duration: 137.068383ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:40.039731Z","caller":"traceutil/trace.go:171","msg":"trace[1125652613] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"136.176463ms","start":"2026-02-25T06:07:39.903534Z","end":"2026-02-25T06:07:40.039711Z","steps":["trace[1125652613] 'process raft request' (duration: 135.981049ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:40.039563Z","caller":"traceutil/trace.go:171","msg":"trace[812431250] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"137.178475ms","start":"2026-02-25T06:07:39.902365Z","end":"2026-02-25T06:07:40.039543Z","steps":["trace[812431250] 'process raft request' (duration: 137.111124ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:40.040104Z","caller":"traceutil/trace.go:171","msg":"trace[2105688598] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"127.932031ms","start":"2026-02-25T06:07:39.912159Z","end":"2026-02-25T06:07:40.040091Z","steps":["trace[2105688598] 'process raft request' (duration: 127.47527ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:40.094261Z","caller":"traceutil/trace.go:171","msg":"trace[1924833234] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"134.495425ms","start":"2026-02-25T06:07:39.959736Z","end":"2026-02-25T06:07:40.094232Z","steps":["trace[1924833234] 'process raft request' (duration: 134.354502ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:40.724853Z","caller":"traceutil/trace.go:171","msg":"trace[1341874989] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"189.377127ms","start":"2026-02-25T06:07:40.535433Z","end":"2026-02-25T06:07:40.72481Z","steps":["trace[1341874989] 'process raft request' (duration: 189.239484ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:43.351345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.946682ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:07:43.351455Z","caller":"traceutil/trace.go:171","msg":"trace[311837479] linearizableReadLoop","detail":"{readStateIndex:725; appliedIndex:724; }","duration":"218.374296ms","start":"2026-02-25T06:07:43.133062Z","end":"2026-02-25T06:07:43.351437Z","steps":["trace[311837479] 'read index received' (duration: 2.226359ms)","trace[311837479] 'applied index is now lower than readState.Index' (duration: 216.146877ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:07:43.351499Z","caller":"traceutil/trace.go:171","msg":"trace[1939796757] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"246.221479ms","start":"2026-02-25T06:07:43.105246Z","end":"2026-02-25T06:07:43.351467Z","steps":["trace[1939796757] 'process raft request' (duration: 30.089343ms)","trace[1939796757] 'compare' (duration: 215.739457ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:07:43.351641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.57295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-02-25T06:07:43.351675Z","caller":"traceutil/trace.go:171","msg":"trace[36137553] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:704; }","duration":"218.647722ms","start":"2026-02-25T06:07:43.133016Z","end":"2026-02-25T06:07:43.351663Z","steps":["trace[36137553] 'agreement among raft nodes before linearized reading' (duration: 218.504168ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:44.100264Z","caller":"traceutil/trace.go:171","msg":"trace[1452698767] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"188.062027ms","start":"2026-02-25T06:07:43.912175Z","end":"2026-02-25T06:07:44.100237Z","steps":["trace[1452698767] 'process raft request' (duration: 131.625923ms)","trace[1452698767] 'compare' (duration: 56.215849ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:07:44.101147Z","caller":"traceutil/trace.go:171","msg":"trace[963693384] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"176.039951ms","start":"2026-02-25T06:07:43.925091Z","end":"2026-02-25T06:07:44.101131Z","steps":["trace[963693384] 'process raft request' (duration: 175.801376ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:44.102139Z","caller":"traceutil/trace.go:171","msg":"trace[1922213512] linearizableReadLoop","detail":"{readStateIndex:732; appliedIndex:730; }","duration":"169.298184ms","start":"2026-02-25T06:07:43.932819Z","end":"2026-02-25T06:07:44.102117Z","steps":["trace[1922213512] 'read index received' (duration: 111.05557ms)","trace[1922213512] 'applied index is now lower than readState.Index' (duration: 58.241794ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:07:44.102186Z","caller":"traceutil/trace.go:171","msg":"trace[1438405184] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"169.390616ms","start":"2026-02-25T06:07:43.932768Z","end":"2026-02-25T06:07:44.102158Z","steps":["trace[1438405184] 'process raft request' (duration: 168.343342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:44.102336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.967959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:07:44.102424Z","caller":"traceutil/trace.go:171","msg":"trace[1443941710] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:712; }","duration":"102.094982ms","start":"2026-02-25T06:07:44.000309Z","end":"2026-02-25T06:07:44.102404Z","steps":["trace[1443941710] 'agreement among raft nodes before linearized reading' (duration: 101.967209ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:44.102437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.534799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-02-25T06:07:44.10254Z","caller":"traceutil/trace.go:171","msg":"trace[98829237] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:712; }","duration":"169.724043ms","start":"2026-02-25T06:07:43.932794Z","end":"2026-02-25T06:07:44.102519Z","steps":["trace[98829237] 'agreement among raft nodes before linearized reading' (duration: 169.444936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:44.43439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.765132ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:07:44.434644Z","caller":"traceutil/trace.go:171","msg":"trace[1706434795] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"263.915978ms","start":"2026-02-25T06:07:44.17069Z","end":"2026-02-25T06:07:44.434606Z","steps":["trace[1706434795] 'process raft request' (duration: 55.886312ms)","trace[1706434795] 'compare' (duration: 207.599657ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:07:44.434593Z","caller":"traceutil/trace.go:171","msg":"trace[428260538] linearizableReadLoop","detail":"{readStateIndex:737; appliedIndex:736; }","duration":"246.334712ms","start":"2026-02-25T06:07:44.188244Z","end":"2026-02-25T06:07:44.434579Z","steps":["trace[428260538] 'read index received' (duration: 38.399637ms)","trace[428260538] 'applied index is now lower than readState.Index' (duration: 207.934155ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:07:44.434933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.667909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-25T06:07:44.435024Z","caller":"traceutil/trace.go:171","msg":"trace[1035913309] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:716; }","duration":"246.814282ms","start":"2026-02-25T06:07:44.188188Z","end":"2026-02-25T06:07:44.435002Z","steps":["trace[1035913309] 'agreement among raft nodes before linearized reading' (duration: 246.535106ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:44.436003Z","caller":"traceutil/trace.go:171","msg":"trace[102996120] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"244.333648ms","start":"2026-02-25T06:07:44.191472Z","end":"2026-02-25T06:07:44.435805Z","steps":["trace[102996120] 'process raft request' (duration: 243.681123ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:44.484941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.840808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-02-25T06:07:44.485046Z","caller":"traceutil/trace.go:171","msg":"trace[1659201390] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:717; }","duration":"151.973711ms","start":"2026-02-25T06:07:44.333021Z","end":"2026-02-25T06:07:44.484994Z","steps":["trace[1659201390] 'agreement among raft nodes before linearized reading' (duration: 151.783616ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:46.609985Z","caller":"traceutil/trace.go:171","msg":"trace[71139374] linearizableReadLoop","detail":"{readStateIndex:778; appliedIndex:777; }","duration":"129.962406ms","start":"2026-02-25T06:07:46.479995Z","end":"2026-02-25T06:07:46.609957Z","steps":["trace[71139374] 'read index received' (duration: 125.644021ms)","trace[71139374] 'applied index is now lower than readState.Index' (duration: 4.316875ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:07:46.610071Z","caller":"traceutil/trace.go:171","msg":"trace[1500649985] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"151.03735ms","start":"2026-02-25T06:07:46.459001Z","end":"2026-02-25T06:07:46.610039Z","steps":["trace[1500649985] 'process raft request' (duration: 146.726024ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:46.610241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.219003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:1887"} {"level":"warn","ts":"2026-02-25T06:07:46.610312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.013248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-25T06:07:46.610366Z","caller":"traceutil/trace.go:171","msg":"trace[658745128] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:755; }","duration":"130.390666ms","start":"2026-02-25T06:07:46.479954Z","end":"2026-02-25T06:07:46.610344Z","steps":["trace[658745128] 'agreement among raft nodes before linearized reading' (duration: 130.1382ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:46.61036Z","caller":"traceutil/trace.go:171","msg":"trace[79395651] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:755; }","duration":"121.104441ms","start":"2026-02-25T06:07:46.489244Z","end":"2026-02-25T06:07:46.610348Z","steps":["trace[79395651] 'agreement among raft nodes before linearized reading' (duration: 120.880656ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:52.185504Z","caller":"traceutil/trace.go:171","msg":"trace[1644470972] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"126.013539ms","start":"2026-02-25T06:07:52.059459Z","end":"2026-02-25T06:07:52.185473Z","steps":["trace[1644470972] 'process raft request' (duration: 125.456757ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:55.1836Z","caller":"traceutil/trace.go:171","msg":"trace[1067276542] linearizableReadLoop","detail":"{readStateIndex:883; appliedIndex:882; }","duration":"183.569217ms","start":"2026-02-25T06:07:55.000005Z","end":"2026-02-25T06:07:55.183575Z","steps":["trace[1067276542] 'read index received' (duration: 183.490726ms)","trace[1067276542] 'applied index is now lower than readState.Index' (duration: 77.441µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:07:55.183732Z","caller":"traceutil/trace.go:171","msg":"trace[149964612] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"208.349193ms","start":"2026-02-25T06:07:54.975345Z","end":"2026-02-25T06:07:55.183694Z","steps":["trace[149964612] 'process raft request' (duration: 208.082167ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:55.183809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.791462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:07:55.183858Z","caller":"traceutil/trace.go:171","msg":"trace[1922039912] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:858; }","duration":"183.897344ms","start":"2026-02-25T06:07:54.999949Z","end":"2026-02-25T06:07:55.183846Z","steps":["trace[1922039912] 'agreement among raft nodes before linearized reading' (duration: 183.728521ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:07:57.784787Z","caller":"traceutil/trace.go:171","msg":"trace[2036119365] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"781.058083ms","start":"2026-02-25T06:07:57.003698Z","end":"2026-02-25T06:07:57.784756Z","steps":["trace[2036119365] 'process raft request' (duration: 780.992361ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:57.785007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:57.003688Z","time spent":"781.224456ms","remote":"127.0.0.1:51830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4459,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:07:57.785049Z","caller":"traceutil/trace.go:171","msg":"trace[1877230341] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"781.936361ms","start":"2026-02-25T06:07:57.002978Z","end":"2026-02-25T06:07:57.784915Z","steps":["trace[1877230341] 'process raft request' (duration: 781.518162ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:07:57.785488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:07:57.00296Z","time spent":"782.2969ms","remote":"127.0.0.1:51612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1602,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:07:58.559943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.34793ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:07:58.560065Z","caller":"traceutil/trace.go:171","msg":"trace[1966609218] linearizableReadLoop","detail":"{readStateIndex:908; appliedIndex:907; }","duration":"223.768434ms","start":"2026-02-25T06:07:58.336278Z","end":"2026-02-25T06:07:58.560046Z","steps":["trace[1966609218] 'read index received' (duration: 90.125857ms)","trace[1966609218] 'applied index is now lower than readState.Index' (duration: 133.641727ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:07:58.560082Z","caller":"traceutil/trace.go:171","msg":"trace[1442351689] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"225.39101ms","start":"2026-02-25T06:07:58.334663Z","end":"2026-02-25T06:07:58.560054Z","steps":["trace[1442351689] 'process raft request' (duration: 91.833545ms)","trace[1442351689] 'compare' (duration: 133.224387ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:07:58.560217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.942888ms","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-02-25T06:07:58.560255Z","caller":"traceutil/trace.go:171","msg":"trace[1349044164] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:883; }","duration":"223.996019ms","start":"2026-02-25T06:07:58.336248Z","end":"2026-02-25T06:07:58.560244Z","steps":["trace[1349044164] 'agreement among raft nodes before linearized reading' (duration: 223.845886ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:08:04.766135Z","caller":"traceutil/trace.go:171","msg":"trace[733563033] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"430.292418ms","start":"2026-02-25T06:08:04.335819Z","end":"2026-02-25T06:08:04.766111Z","steps":["trace[733563033] 'process raft request' (duration: 430.144955ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:08:04.766287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:08:04.335804Z","time spent":"430.405301ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:08:04.769484Z","caller":"traceutil/trace.go:171","msg":"trace[1071861827] linearizableReadLoop","detail":"{readStateIndex:952; appliedIndex:950; }","duration":"187.200418ms","start":"2026-02-25T06:08:04.582229Z","end":"2026-02-25T06:08:04.769429Z","steps":["trace[1071861827] 'read index received' (duration: 183.791193ms)","trace[1071861827] 'applied index is now lower than readState.Index' (duration: 3.407725ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:08:04.769665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.438163ms","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-02-25T06:08:04.769725Z","caller":"traceutil/trace.go:171","msg":"trace[1388471779] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:924; }","duration":"187.549636ms","start":"2026-02-25T06:08:04.582162Z","end":"2026-02-25T06:08:04.769712Z","steps":["trace[1388471779] 'agreement among raft nodes before linearized reading' (duration: 187.395192ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:08:04.769773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.326473ms","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-02-25T06:08:04.769844Z","caller":"traceutil/trace.go:171","msg":"trace[2053457216] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:924; }","duration":"169.438436ms","start":"2026-02-25T06:08:04.600387Z","end":"2026-02-25T06:08:04.769825Z","steps":["trace[2053457216] 'agreement among raft nodes before linearized reading' (duration: 169.215731ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:08:43.572704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.002986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:08:43.573171Z","caller":"traceutil/trace.go:171","msg":"trace[1805726016] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:0; response_revision:1457; }","duration":"108.513467ms","start":"2026-02-25T06:08:43.464633Z","end":"2026-02-25T06:08:43.573147Z","steps":["trace[1805726016] 'range keys from in-memory index tree' (duration: 107.915734ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:08:43.572742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.867835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:08:43.573405Z","caller":"traceutil/trace.go:171","msg":"trace[1368040823] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1457; }","duration":"109.54844ms","start":"2026-02-25T06:08:43.463827Z","end":"2026-02-25T06:08:43.573375Z","steps":["trace[1368040823] 'range keys from in-memory index tree' (duration: 108.756323ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:08:43.572787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.739892ms","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:5"} {"level":"info","ts":"2026-02-25T06:08:43.573488Z","caller":"traceutil/trace.go:171","msg":"trace[397554110] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1457; }","duration":"109.502519ms","start":"2026-02-25T06:08:43.463965Z","end":"2026-02-25T06:08:43.573468Z","steps":["trace[397554110] 'count revisions from in-memory index tree' (duration: 108.67384ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:08:50.462617Z","caller":"traceutil/trace.go:171","msg":"trace[326233528] transaction","detail":"{read_only:false; response_revision:1569; number_of_response:1; }","duration":"126.679749ms","start":"2026-02-25T06:08:50.335892Z","end":"2026-02-25T06:08:50.462572Z","steps":["trace[326233528] 'process raft request' (duration: 63.931378ms)","trace[326233528] 'compare' (duration: 62.616828ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:08:51.047807Z","caller":"traceutil/trace.go:171","msg":"trace[612685546] linearizableReadLoop","detail":"{readStateIndex:1633; appliedIndex:1632; }","duration":"425.908326ms","start":"2026-02-25T06:08:50.621877Z","end":"2026-02-25T06:08:51.047785Z","steps":["trace[612685546] 'read index received' (duration: 425.853965ms)","trace[612685546] 'applied index is now lower than readState.Index' (duration: 53.141µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:08:51.047866Z","caller":"traceutil/trace.go:171","msg":"trace[898581034] transaction","detail":"{read_only:false; response_revision:1570; number_of_response:1; }","duration":"453.392006ms","start":"2026-02-25T06:08:50.594439Z","end":"2026-02-25T06:08:51.047831Z","steps":["trace[898581034] 'process raft request' (duration: 453.193062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:08:51.048022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:08:50.594406Z","time spent":"453.527529ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:08:51.048108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.197922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5324"} {"level":"info","ts":"2026-02-25T06:08:51.048202Z","caller":"traceutil/trace.go:171","msg":"trace[841939802] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1570; }","duration":"426.357926ms","start":"2026-02-25T06:08:50.621826Z","end":"2026-02-25T06:08:51.048184Z","steps":["trace[841939802] 'agreement among raft nodes before linearized reading' (duration: 426.062619ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:08:51.048277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:08:50.621803Z","time spent":"426.429558ms","remote":"127.0.0.1:34412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5348,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-02-25T06:08:51.066237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.969355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T06:08:51.066244Z","caller":"traceutil/trace.go:171","msg":"trace[989667638] transaction","detail":"{read_only:false; response_revision:1571; number_of_response:1; }","duration":"321.167064ms","start":"2026-02-25T06:08:50.745027Z","end":"2026-02-25T06:08:51.066194Z","steps":["trace[989667638] 'process raft request' (duration: 320.835765ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:08:51.066281Z","caller":"traceutil/trace.go:171","msg":"trace[703450388] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1571; }","duration":"168.059927ms","start":"2026-02-25T06:08:50.898208Z","end":"2026-02-25T06:08:51.066268Z","steps":["trace[703450388] 'agreement among raft nodes before linearized reading' (duration: 167.976945ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:08:51.066281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.711615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1d9413cc-f98a-4639-b0d9-aa01a5f89187\" ","response":"range_response_count:1 size:3583"} {"level":"warn","ts":"2026-02-25T06:08:51.066491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:08:50.744998Z","time spent":"321.369448ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:08:51.066612Z","caller":"traceutil/trace.go:171","msg":"trace[12002533] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1d9413cc-f98a-4639-b0d9-aa01a5f89187; range_end:; response_count:1; response_revision:1571; }","duration":"404.074243ms","start":"2026-02-25T06:08:50.662497Z","end":"2026-02-25T06:08:51.066571Z","steps":["trace[12002533] 'agreement among raft nodes before linearized reading' (duration: 403.511981ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:08:51.066716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:08:50.662476Z","time spent":"404.205035ms","remote":"127.0.0.1:51504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3607,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1d9413cc-f98a-4639-b0d9-aa01a5f89187\" "} {"level":"info","ts":"2026-02-25T06:08:51.200781Z","caller":"traceutil/trace.go:171","msg":"trace[1403558418] transaction","detail":"{read_only:false; response_revision:1575; number_of_response:1; }","duration":"113.248482ms","start":"2026-02-25T06:08:51.087503Z","end":"2026-02-25T06:08:51.200751Z","steps":["trace[1403558418] 'process raft request' (duration: 54.431798ms)","trace[1403558418] 'compare' (duration: 58.682991ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:08:51.37168Z","caller":"traceutil/trace.go:171","msg":"trace[1571387904] transaction","detail":"{read_only:false; response_revision:1577; number_of_response:1; }","duration":"125.744589ms","start":"2026-02-25T06:08:51.24591Z","end":"2026-02-25T06:08:51.371655Z","steps":["trace[1571387904] 'process raft request' (duration: 66.959476ms)","trace[1571387904] 'compare' (duration: 58.680151ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:08:51.965473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.776906ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:08:51.96561Z","caller":"traceutil/trace.go:171","msg":"trace[2018013449] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1577; }","duration":"155.924679ms","start":"2026-02-25T06:08:51.809665Z","end":"2026-02-25T06:08:51.96559Z","steps":["trace[2018013449] 'range keys from in-memory index tree' (duration: 155.760125ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:08:57.470977Z","caller":"traceutil/trace.go:171","msg":"trace[1543402346] transaction","detail":"{read_only:false; response_revision:1628; number_of_response:1; }","duration":"238.958289ms","start":"2026-02-25T06:08:57.23198Z","end":"2026-02-25T06:08:57.470939Z","steps":["trace[1543402346] 'process raft request' (duration: 238.787096ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:09:04.189333Z","caller":"traceutil/trace.go:171","msg":"trace[1519238532] transaction","detail":"{read_only:false; response_revision:1674; number_of_response:1; }","duration":"178.765885ms","start":"2026-02-25T06:09:04.010535Z","end":"2026-02-25T06:09:04.189301Z","steps":["trace[1519238532] 'process raft request' (duration: 178.52511ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:09:04.233336Z","caller":"traceutil/trace.go:171","msg":"trace[237822677] transaction","detail":"{read_only:false; response_revision:1675; number_of_response:1; }","duration":"115.067012ms","start":"2026-02-25T06:09:04.11824Z","end":"2026-02-25T06:09:04.233307Z","steps":["trace[237822677] 'process raft request' (duration: 114.821117ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:07.752749Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976530650515738774,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T06:09:07.890533Z","caller":"traceutil/trace.go:171","msg":"trace[451937339] transaction","detail":"{read_only:false; response_revision:1699; number_of_response:1; }","duration":"741.125857ms","start":"2026-02-25T06:09:07.149379Z","end":"2026-02-25T06:09:07.890504Z","steps":["trace[451937339] 'process raft request' (duration: 740.957634ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:07.890702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:09:07.149355Z","time spent":"741.25883ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:09:07.913838Z","caller":"traceutil/trace.go:171","msg":"trace[113493154] transaction","detail":"{read_only:false; response_revision:1700; number_of_response:1; }","duration":"725.836609ms","start":"2026-02-25T06:09:07.187975Z","end":"2026-02-25T06:09:07.913812Z","steps":["trace[113493154] 'process raft request' (duration: 725.652555ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:09:07.914009Z","caller":"traceutil/trace.go:171","msg":"trace[1164821749] linearizableReadLoop","detail":"{readStateIndex:1768; appliedIndex:1765; }","duration":"662.122635ms","start":"2026-02-25T06:09:07.251856Z","end":"2026-02-25T06:09:07.913978Z","steps":["trace[1164821749] 'read index received' (duration: 638.533772ms)","trace[1164821749] 'applied index is now lower than readState.Index' (duration: 23.586323ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:09:07.914083Z","caller":"traceutil/trace.go:171","msg":"trace[1234283941] transaction","detail":"{read_only:false; response_revision:1701; number_of_response:1; }","duration":"411.407974ms","start":"2026-02-25T06:09:07.502657Z","end":"2026-02-25T06:09:07.914065Z","steps":["trace[1234283941] 'process raft request' (duration: 411.107048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:07.914227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.363711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5324"} {"level":"warn","ts":"2026-02-25T06:09:07.914245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:09:07.50263Z","time spent":"411.528287ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:09:07.914279Z","caller":"traceutil/trace.go:171","msg":"trace[853685388] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1701; }","duration":"662.468684ms","start":"2026-02-25T06:09:07.251793Z","end":"2026-02-25T06:09:07.914262Z","steps":["trace[853685388] 'agreement among raft nodes before linearized reading' (duration: 662.29076ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:07.914318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:09:07.251767Z","time spent":"662.542785ms","remote":"127.0.0.1:34412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5348,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-02-25T06:09:07.914314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.401175ms","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-02-25T06:09:07.914379Z","caller":"traceutil/trace.go:171","msg":"trace[1579934027] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1701; }","duration":"627.500457ms","start":"2026-02-25T06:09:07.286862Z","end":"2026-02-25T06:09:07.914363Z","steps":["trace[1579934027] 'agreement among raft nodes before linearized reading' (duration: 627.309163ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:07.914425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:09:07.286829Z","time spent":"627.587329ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T06:09:07.914624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:09:07.187948Z","time spent":"726.002972ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:09:40.925204Z","caller":"traceutil/trace.go:171","msg":"trace[2021568915] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"113.190081ms","start":"2026-02-25T06:09:40.81199Z","end":"2026-02-25T06:09:40.92518Z","steps":["trace[2021568915] 'process raft request' (duration: 113.060188ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:09:41.470607Z","caller":"traceutil/trace.go:171","msg":"trace[1125740979] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"196.93865ms","start":"2026-02-25T06:09:41.273634Z","end":"2026-02-25T06:09:41.470572Z","steps":["trace[1125740979] 'process raft request' (duration: 196.620603ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:09:42.326367Z","caller":"traceutil/trace.go:171","msg":"trace[1292284492] linearizableReadLoop","detail":"{readStateIndex:1943; appliedIndex:1942; }","duration":"381.781199ms","start":"2026-02-25T06:09:41.944559Z","end":"2026-02-25T06:09:42.32634Z","steps":["trace[1292284492] 'read index received' (duration: 381.675317ms)","trace[1292284492] 'applied index is now lower than readState.Index' (duration: 104.962µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:09:42.32642Z","caller":"traceutil/trace.go:171","msg":"trace[1819169435] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"400.92584ms","start":"2026-02-25T06:09:41.925468Z","end":"2026-02-25T06:09:42.326394Z","steps":["trace[1819169435] 'process raft request' (duration: 400.664624ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:42.32657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.977194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T06:09:42.326663Z","caller":"traceutil/trace.go:171","msg":"trace[1885365854] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1869; }","duration":"382.114087ms","start":"2026-02-25T06:09:41.944528Z","end":"2026-02-25T06:09:42.326642Z","steps":["trace[1885365854] 'agreement among raft nodes before linearized reading' (duration: 381.953763ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:42.326713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:09:41.944508Z","time spent":"382.195068ms","remote":"127.0.0.1:51494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":31,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-02-25T06:09:42.326895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:09:41.925447Z","time spent":"401.040492ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:09:42.355665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.753807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T06:09:42.35571Z","caller":"traceutil/trace.go:171","msg":"trace[669001902] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"261.317875ms","start":"2026-02-25T06:09:42.094364Z","end":"2026-02-25T06:09:42.355682Z","steps":["trace[669001902] 'process raft request' (duration: 261.111331ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:42.355761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.466212ms","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-02-25T06:09:42.355772Z","caller":"traceutil/trace.go:171","msg":"trace[1341982906] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"150.330734ms","start":"2026-02-25T06:09:42.205419Z","end":"2026-02-25T06:09:42.355749Z","steps":["trace[1341982906] 'process raft request' (duration: 150.14195ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:09:42.355809Z","caller":"traceutil/trace.go:171","msg":"trace[1688611283] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1871; }","duration":"160.534453ms","start":"2026-02-25T06:09:42.195262Z","end":"2026-02-25T06:09:42.355796Z","steps":["trace[1688611283] 'agreement among raft nodes before linearized reading' (duration: 160.377469ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:09:42.355737Z","caller":"traceutil/trace.go:171","msg":"trace[1832125583] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1871; }","duration":"364.852699ms","start":"2026-02-25T06:09:41.990871Z","end":"2026-02-25T06:09:42.355723Z","steps":["trace[1832125583] 'agreement among raft nodes before linearized reading' (duration: 364.729617ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:42.355956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:09:41.990858Z","time spent":"365.077664ms","remote":"127.0.0.1:51640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":31,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-25T06:09:42.356041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.531766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-02-25T06:09:42.356084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.983222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5324"} {"level":"info","ts":"2026-02-25T06:09:42.356118Z","caller":"traceutil/trace.go:171","msg":"trace[1469634052] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1871; }","duration":"239.645119ms","start":"2026-02-25T06:09:42.116455Z","end":"2026-02-25T06:09:42.3561Z","steps":["trace[1469634052] 'agreement among raft nodes before linearized reading' (duration: 239.448384ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:09:42.356132Z","caller":"traceutil/trace.go:171","msg":"trace[1909525474] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1871; }","duration":"165.059484ms","start":"2026-02-25T06:09:42.191058Z","end":"2026-02-25T06:09:42.356117Z","steps":["trace[1909525474] 'agreement among raft nodes before linearized reading' (duration: 164.704206ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:42.356123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.59535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T06:09:42.356245Z","caller":"traceutil/trace.go:171","msg":"trace[495499639] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1871; }","duration":"240.688112ms","start":"2026-02-25T06:09:42.115478Z","end":"2026-02-25T06:09:42.356166Z","steps":["trace[495499639] 'agreement among raft nodes before linearized reading' (duration: 240.431306ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:09:42.573859Z","caller":"traceutil/trace.go:171","msg":"trace[946436457] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"212.853158ms","start":"2026-02-25T06:09:42.360977Z","end":"2026-02-25T06:09:42.573831Z","steps":["trace[946436457] 'process raft request' (duration: 212.674404ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:09:42.574198Z","caller":"traceutil/trace.go:171","msg":"trace[1263682781] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"212.845547ms","start":"2026-02-25T06:09:42.360919Z","end":"2026-02-25T06:09:42.573764Z","steps":["trace[1263682781] 'process raft request' (duration: 123.797189ms)","trace[1263682781] 'compare' (duration: 88.640629ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:09:48.853239Z","caller":"traceutil/trace.go:171","msg":"trace[899861435] linearizableReadLoop","detail":"{readStateIndex:1974; appliedIndex:1973; }","duration":"455.129406ms","start":"2026-02-25T06:09:48.398081Z","end":"2026-02-25T06:09:48.85321Z","steps":["trace[899861435] 'read index received' (duration: 454.987593ms)","trace[899861435] 'applied index is now lower than readState.Index' (duration: 140.763µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:09:48.853336Z","caller":"traceutil/trace.go:171","msg":"trace[944562171] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"478.47542ms","start":"2026-02-25T06:09:48.374803Z","end":"2026-02-25T06:09:48.853279Z","steps":["trace[944562171] 'process raft request' (duration: 478.233805ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:48.853517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:09:48.374768Z","time spent":"478.651383ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:09:48.853544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.442583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5324"} {"level":"info","ts":"2026-02-25T06:09:48.853607Z","caller":"traceutil/trace.go:171","msg":"trace[1834895098] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1899; }","duration":"455.563706ms","start":"2026-02-25T06:09:48.398022Z","end":"2026-02-25T06:09:48.853586Z","steps":["trace[1834895098] 'agreement among raft nodes before linearized reading' (duration: 455.344031ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:48.853665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:09:48.397997Z","time spent":"455.654908ms","remote":"127.0.0.1:34412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5348,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-02-25T06:09:49.163496Z","caller":"traceutil/trace.go:171","msg":"trace[1215370442] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"570.953935ms","start":"2026-02-25T06:09:48.59252Z","end":"2026-02-25T06:09:49.163473Z","steps":["trace[1215370442] 'process raft request' (duration: 570.752781ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:49.163662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:09:48.59249Z","time spent":"571.070407ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:09:49.163473Z","caller":"traceutil/trace.go:171","msg":"trace[1859535920] linearizableReadLoop","detail":"{readStateIndex:1975; appliedIndex:1974; }","duration":"310.107789ms","start":"2026-02-25T06:09:48.853334Z","end":"2026-02-25T06:09:49.163442Z","steps":["trace[1859535920] 'read index received' (duration: 309.865964ms)","trace[1859535920] 'applied index is now lower than readState.Index' (duration: 240.225µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:09:49.163935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.742965ms","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-02-25T06:09:49.164041Z","caller":"traceutil/trace.go:171","msg":"trace[1618925274] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1900; }","duration":"764.96859ms","start":"2026-02-25T06:09:48.399054Z","end":"2026-02-25T06:09:49.164022Z","steps":["trace[1618925274] 'agreement among raft nodes before linearized reading' (duration: 764.479108ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:49.164216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.490146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-25T06:09:49.164147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:09:48.399021Z","time spent":"765.109484ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T06:09:49.164297Z","caller":"traceutil/trace.go:171","msg":"trace[941581292] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1900; }","duration":"566.606789ms","start":"2026-02-25T06:09:48.597662Z","end":"2026-02-25T06:09:49.164268Z","steps":["trace[941581292] 'agreement among raft nodes before linearized reading' (duration: 566.388534ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:49.164294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.266455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T06:09:49.164381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.615635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.189768647071bc38\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-02-25T06:09:49.164606Z","caller":"traceutil/trace.go:171","msg":"trace[1120526590] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1900; }","duration":"163.570162ms","start":"2026-02-25T06:09:49.000968Z","end":"2026-02-25T06:09:49.164538Z","steps":["trace[1120526590] 'agreement among raft nodes before linearized reading' (duration: 163.243284ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:09:49.164657Z","caller":"traceutil/trace.go:171","msg":"trace[654841760] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.189768647071bc38; range_end:; response_count:1; response_revision:1900; }","duration":"209.917762ms","start":"2026-02-25T06:09:48.954717Z","end":"2026-02-25T06:09:49.164634Z","steps":["trace[654841760] 'agreement among raft nodes before linearized reading' (duration: 209.434301ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:49.164416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:09:48.597632Z","time spent":"566.769202ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T06:09:49.164709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.561476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T06:09:49.164911Z","caller":"traceutil/trace.go:171","msg":"trace[39534492] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1900; }","duration":"202.908894ms","start":"2026-02-25T06:09:48.961958Z","end":"2026-02-25T06:09:49.164866Z","steps":["trace[39534492] 'agreement among raft nodes before linearized reading' (duration: 202.27335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:49.164447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.053292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.238\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T06:09:49.165069Z","caller":"traceutil/trace.go:171","msg":"trace[1460357305] range","detail":"{range_begin:/registry/masterleases/199.204.45.238; range_end:; response_count:1; response_revision:1900; }","duration":"139.689646ms","start":"2026-02-25T06:09:49.025346Z","end":"2026-02-25T06:09:49.165036Z","steps":["trace[1460357305] 'agreement among raft nodes before linearized reading' (duration: 138.878878ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:49.165391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.341792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-25T06:09:49.165516Z","caller":"traceutil/trace.go:171","msg":"trace[1989263905] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1900; }","duration":"567.110949ms","start":"2026-02-25T06:09:48.598354Z","end":"2026-02-25T06:09:49.165465Z","steps":["trace[1989263905] 'agreement among raft nodes before linearized reading' (duration: 565.537934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:49.165631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:09:48.59834Z","time spent":"567.273312ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T06:09:49.691042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.298737ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-02-25T06:09:49.691146Z","caller":"traceutil/trace.go:171","msg":"trace[1836881212] linearizableReadLoop","detail":"{readStateIndex:1976; appliedIndex:1975; }","duration":"524.595445ms","start":"2026-02-25T06:09:49.166532Z","end":"2026-02-25T06:09:49.691128Z","steps":["trace[1836881212] 'read index received' (duration: 295.141935ms)","trace[1836881212] 'applied index is now lower than readState.Index' (duration: 229.45192ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:09:49.691157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:09:49.166267Z","time spent":"524.884122ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-25T06:09:49.691237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.698208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:09:49.691263Z","caller":"traceutil/trace.go:171","msg":"trace[1183922778] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1900; }","duration":"524.753968ms","start":"2026-02-25T06:09:49.1665Z","end":"2026-02-25T06:09:49.691254Z","steps":["trace[1183922778] 'agreement among raft nodes before linearized reading' (duration: 524.698147ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:49.691293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:09:49.166467Z","time spent":"524.81937ms","remote":"127.0.0.1:51334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T06:09:49.697689Z","caller":"traceutil/trace.go:171","msg":"trace[1291347629] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"530.551529ms","start":"2026-02-25T06:09:49.167109Z","end":"2026-02-25T06:09:49.697661Z","steps":["trace[1291347629] 'process raft request' (duration: 530.294833ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:09:49.69777Z","caller":"traceutil/trace.go:171","msg":"trace[1001328631] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"527.304726ms","start":"2026-02-25T06:09:49.170443Z","end":"2026-02-25T06:09:49.697748Z","steps":["trace[1001328631] 'process raft request' (duration: 527.259485ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:49.697979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:09:49.170432Z","time spent":"527.47649ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:09:49.698214Z","caller":"traceutil/trace.go:171","msg":"trace[1999583277] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"529.286981ms","start":"2026-02-25T06:09:49.168698Z","end":"2026-02-25T06:09:49.697985Z","steps":["trace[1999583277] 'process raft request' (duration: 528.890222ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:49.697872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:09:49.167081Z","time spent":"530.711022ms","remote":"127.0.0.1:51400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:09:49.698269Z","caller":"traceutil/trace.go:171","msg":"trace[1029459080] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"528.187906ms","start":"2026-02-25T06:09:49.170063Z","end":"2026-02-25T06:09:49.698251Z","steps":["trace[1029459080] 'process raft request' (duration: 527.604993ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:09:49.698334Z","caller":"traceutil/trace.go:171","msg":"trace[1511868083] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"528.717707ms","start":"2026-02-25T06:09:49.169594Z","end":"2026-02-25T06:09:49.698312Z","steps":["trace[1511868083] 'process raft request' (duration: 528.040452ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:49.698355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:09:49.168677Z","time spent":"529.583577ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:09:49.69837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:09:49.170047Z","time spent":"528.267067ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:09:49.698448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:09:49.169568Z","time spent":"528.82463ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:09:49.704307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.455252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T06:09:49.704344Z","caller":"traceutil/trace.go:171","msg":"trace[1863868083] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1907; }","duration":"123.513173ms","start":"2026-02-25T06:09:49.580822Z","end":"2026-02-25T06:09:49.704335Z","steps":["trace[1863868083] 'agreement among raft nodes before linearized reading' (duration: 123.41551ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:09:49.704415Z","caller":"traceutil/trace.go:171","msg":"trace[797004536] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"209.184716ms","start":"2026-02-25T06:09:49.495212Z","end":"2026-02-25T06:09:49.704397Z","steps":["trace[797004536] 'process raft request' (duration: 208.849547ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:49.704605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.72306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T06:09:49.704637Z","caller":"traceutil/trace.go:171","msg":"trace[260849339] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:1907; }","duration":"394.817682ms","start":"2026-02-25T06:09:49.30981Z","end":"2026-02-25T06:09:49.704628Z","steps":["trace[260849339] 'agreement among raft nodes before linearized reading' (duration: 394.73936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:49.704658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:09:49.309793Z","time spent":"394.859863ms","remote":"127.0.0.1:43024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":2,"response size":31,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"warn","ts":"2026-02-25T06:09:49.704633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.384414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T06:09:49.704751Z","caller":"traceutil/trace.go:171","msg":"trace[1966479960] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1907; }","duration":"186.595739ms","start":"2026-02-25T06:09:49.518123Z","end":"2026-02-25T06:09:49.704719Z","steps":["trace[1966479960] 'agreement among raft nodes before linearized reading' (duration: 186.336973ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:49.704848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.032581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T06:09:49.704875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.456667ms","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-02-25T06:09:49.704949Z","caller":"traceutil/trace.go:171","msg":"trace[1661498298] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1907; }","duration":"170.560298ms","start":"2026-02-25T06:09:49.534368Z","end":"2026-02-25T06:09:49.704928Z","steps":["trace[1661498298] 'agreement among raft nodes before linearized reading' (duration: 170.301253ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:09:49.705065Z","caller":"traceutil/trace.go:171","msg":"trace[615115425] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1907; }","duration":"102.206945ms","start":"2026-02-25T06:09:49.602696Z","end":"2026-02-25T06:09:49.704903Z","steps":["trace[615115425] 'agreement among raft nodes before linearized reading' (duration: 101.629592ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:09:50.173692Z","caller":"traceutil/trace.go:171","msg":"trace[925502983] linearizableReadLoop","detail":"{readStateIndex:1984; appliedIndex:1983; }","duration":"462.846629ms","start":"2026-02-25T06:09:49.710809Z","end":"2026-02-25T06:09:50.173656Z","steps":["trace[925502983] 'read index received' (duration: 367.262523ms)","trace[925502983] 'applied index is now lower than readState.Index' (duration: 95.582226ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:09:50.173763Z","caller":"traceutil/trace.go:171","msg":"trace[658588540] transaction","detail":"{read_only:false; response_revision:1908; number_of_response:1; }","duration":"464.293291ms","start":"2026-02-25T06:09:49.709436Z","end":"2026-02-25T06:09:50.173729Z","steps":["trace[658588540] 'process raft request' (duration: 368.617794ms)","trace[658588540] 'compare' (duration: 95.273308ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:09:50.173915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:09:49.709411Z","time spent":"464.421375ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:09:50.174031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.123295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-02-25T06:09:50.174148Z","caller":"traceutil/trace.go:171","msg":"trace[932969547] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1908; }","duration":"463.331019ms","start":"2026-02-25T06:09:49.710784Z","end":"2026-02-25T06:09:50.174115Z","steps":["trace[932969547] 'agreement among raft nodes before linearized reading' (duration: 463.079373ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:50.174237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:09:49.710775Z","time spent":"463.447382ms","remote":"127.0.0.1:51612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-02-25T06:09:50.174633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.727699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T06:09:50.174865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.167118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5324"} {"level":"info","ts":"2026-02-25T06:09:50.174946Z","caller":"traceutil/trace.go:171","msg":"trace[513336915] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1908; }","duration":"313.274342ms","start":"2026-02-25T06:09:49.861653Z","end":"2026-02-25T06:09:50.174927Z","steps":["trace[513336915] 'agreement among raft nodes before linearized reading' (duration: 313.023055ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:09:50.174679Z","caller":"traceutil/trace.go:171","msg":"trace[701482204] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:1908; }","duration":"459.78904ms","start":"2026-02-25T06:09:49.714879Z","end":"2026-02-25T06:09:50.174668Z","steps":["trace[701482204] 'agreement among raft nodes before linearized reading' (duration: 459.718469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:50.174986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:09:49.861635Z","time spent":"313.341523ms","remote":"127.0.0.1:34412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5348,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-02-25T06:09:50.175049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:09:49.71487Z","time spent":"460.140429ms","remote":"127.0.0.1:51800","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-02-25T06:10:04.104997Z","caller":"traceutil/trace.go:171","msg":"trace[1534179923] transaction","detail":"{read_only:false; response_revision:1989; number_of_response:1; }","duration":"100.076427ms","start":"2026-02-25T06:10:04.00489Z","end":"2026-02-25T06:10:04.104967Z","steps":["trace[1534179923] 'process raft request' (duration: 35.783513ms)","trace[1534179923] 'compare' (duration: 64.081939ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:10:22.231401Z","caller":"traceutil/trace.go:171","msg":"trace[1891185185] linearizableReadLoop","detail":"{readStateIndex:2327; appliedIndex:2326; }","duration":"331.220495ms","start":"2026-02-25T06:10:21.900159Z","end":"2026-02-25T06:10:22.23138Z","steps":["trace[1891185185] 'read index received' (duration: 331.069421ms)","trace[1891185185] 'applied index is now lower than readState.Index' (duration: 150.264µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:10:22.231491Z","caller":"traceutil/trace.go:171","msg":"trace[1662764275] transaction","detail":"{read_only:false; response_revision:2237; number_of_response:1; }","duration":"338.061238ms","start":"2026-02-25T06:10:21.893389Z","end":"2026-02-25T06:10:22.23145Z","steps":["trace[1662764275] 'process raft request' (duration: 337.845774ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:22.231598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.752214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T06:10:22.231632Z","caller":"traceutil/trace.go:171","msg":"trace[1083500167] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2237; }","duration":"330.815745ms","start":"2026-02-25T06:10:21.900808Z","end":"2026-02-25T06:10:22.231623Z","steps":["trace[1083500167] 'agreement among raft nodes before linearized reading' (duration: 330.694133ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:22.23166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:21.9008Z","time spent":"330.854316ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T06:10:22.231671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:21.893363Z","time spent":"338.216042ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:10:22.231662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.872973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T06:10:22.231686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.498662ms","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-02-25T06:10:22.231753Z","caller":"traceutil/trace.go:171","msg":"trace[1436738974] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2237; }","duration":"231.013536ms","start":"2026-02-25T06:10:22.000716Z","end":"2026-02-25T06:10:22.231729Z","steps":["trace[1436738974] 'agreement among raft nodes before linearized reading' (duration: 230.846712ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:22.23177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.205736ms","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-02-25T06:10:22.23178Z","caller":"traceutil/trace.go:171","msg":"trace[946276766] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2237; }","duration":"331.634054ms","start":"2026-02-25T06:10:21.900125Z","end":"2026-02-25T06:10:22.231759Z","steps":["trace[946276766] 'agreement among raft nodes before linearized reading' (duration: 331.392769ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:10:22.231798Z","caller":"traceutil/trace.go:171","msg":"trace[210126936] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2237; }","duration":"328.253388ms","start":"2026-02-25T06:10:21.903538Z","end":"2026-02-25T06:10:22.231792Z","steps":["trace[210126936] 'agreement among raft nodes before linearized reading' (duration: 328.189427ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:22.23183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:21.903528Z","time spent":"328.297149ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T06:10:22.231828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:21.900113Z","time spent":"331.706196ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T06:10:22.231825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.270557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-25T06:10:22.231919Z","caller":"traceutil/trace.go:171","msg":"trace[1919365269] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2237; }","duration":"323.365299ms","start":"2026-02-25T06:10:21.90851Z","end":"2026-02-25T06:10:22.231876Z","steps":["trace[1919365269] 'agreement among raft nodes before linearized reading' (duration: 323.121303ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:22.231975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:21.9085Z","time spent":"323.463521ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T06:10:22.231977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.573614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-02-25T06:10:22.232079Z","caller":"traceutil/trace.go:171","msg":"trace[714792547] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2237; }","duration":"180.717537ms","start":"2026-02-25T06:10:22.05134Z","end":"2026-02-25T06:10:22.232058Z","steps":["trace[714792547] 'agreement among raft nodes before linearized reading' (duration: 180.41911ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:10:55.87373Z","caller":"traceutil/trace.go:171","msg":"trace[1366826547] linearizableReadLoop","detail":"{readStateIndex:2522; appliedIndex:2521; }","duration":"421.218371ms","start":"2026-02-25T06:10:55.452493Z","end":"2026-02-25T06:10:55.873712Z","steps":["trace[1366826547] 'read index received' (duration: 421.063827ms)","trace[1366826547] 'applied index is now lower than readState.Index' (duration: 153.834µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:10:55.873794Z","caller":"traceutil/trace.go:171","msg":"trace[479280847] transaction","detail":"{read_only:false; response_revision:2425; number_of_response:1; }","duration":"432.802942ms","start":"2026-02-25T06:10:55.440961Z","end":"2026-02-25T06:10:55.873764Z","steps":["trace[479280847] 'process raft request' (duration: 432.642568ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:55.873874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.334864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T06:10:55.873911Z","caller":"traceutil/trace.go:171","msg":"trace[977415969] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:2425; }","duration":"421.450906ms","start":"2026-02-25T06:10:55.452448Z","end":"2026-02-25T06:10:55.873899Z","steps":["trace[977415969] 'agreement among raft nodes before linearized reading' (duration: 421.343374ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:55.873956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:55.452429Z","time spent":"421.500878ms","remote":"127.0.0.1:43012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":2,"response size":31,"request content":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true "} {"level":"warn","ts":"2026-02-25T06:10:55.873962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:55.440929Z","time spent":"432.960046ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:10:56.310997Z","caller":"traceutil/trace.go:171","msg":"trace[1793535605] linearizableReadLoop","detail":"{readStateIndex:2523; appliedIndex:2522; }","duration":"253.8936ms","start":"2026-02-25T06:10:56.05708Z","end":"2026-02-25T06:10:56.310974Z","steps":["trace[1793535605] 'read index received' (duration: 253.586853ms)","trace[1793535605] 'applied index is now lower than readState.Index' (duration: 305.727µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:10:56.311057Z","caller":"traceutil/trace.go:171","msg":"trace[805364310] transaction","detail":"{read_only:false; response_revision:2426; number_of_response:1; }","duration":"256.497871ms","start":"2026-02-25T06:10:56.054528Z","end":"2026-02-25T06:10:56.311026Z","steps":["trace[805364310] 'process raft request' (duration: 256.250475ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:56.311196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.084745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1315"} {"level":"info","ts":"2026-02-25T06:10:56.311347Z","caller":"traceutil/trace.go:171","msg":"trace[1517137452] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2426; }","duration":"254.154336ms","start":"2026-02-25T06:10:56.057071Z","end":"2026-02-25T06:10:56.311225Z","steps":["trace[1517137452] 'agreement among raft nodes before linearized reading' (duration: 253.999493ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:57.555877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.030424096s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:10:57.556163Z","caller":"traceutil/trace.go:171","msg":"trace[726403635] linearizableReadLoop","detail":"{readStateIndex:2525; appliedIndex:2523; }","duration":"1.08863926s","start":"2026-02-25T06:10:56.467498Z","end":"2026-02-25T06:10:57.556137Z","steps":["trace[726403635] 'read index received' (duration: 57.821114ms)","trace[726403635] 'applied index is now lower than readState.Index' (duration: 1.030816696s)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:10:57.556216Z","caller":"traceutil/trace.go:171","msg":"trace[544998938] transaction","detail":"{read_only:false; response_revision:2427; number_of_response:1; }","duration":"1.239067064s","start":"2026-02-25T06:10:56.317103Z","end":"2026-02-25T06:10:57.55617Z","steps":["trace[544998938] 'process raft request' (duration: 208.193048ms)","trace[544998938] 'compare' (duration: 1.030230521s)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:10:57.556268Z","caller":"traceutil/trace.go:171","msg":"trace[177673989] transaction","detail":"{read_only:false; response_revision:2428; number_of_response:1; }","duration":"1.212804039s","start":"2026-02-25T06:10:56.343419Z","end":"2026-02-25T06:10:57.556223Z","steps":["trace[177673989] 'process raft request' (duration: 1.212620694s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:57.556363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:56.317089Z","time spent":"1.239186678s","remote":"127.0.0.1:51400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":753,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:10:57.556408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.088899666s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-25T06:10:57.556474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:56.343398Z","time spent":"1.212966954s","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:10:57.556548Z","caller":"traceutil/trace.go:171","msg":"trace[316589561] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2428; }","duration":"1.089013239s","start":"2026-02-25T06:10:56.467454Z","end":"2026-02-25T06:10:57.556468Z","steps":["trace[316589561] 'agreement among raft nodes before linearized reading' (duration: 1.088774863s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:57.556645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:56.467438Z","time spent":"1.089193203s","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T06:10:57.859775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.530352ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:10:57.860033Z","caller":"traceutil/trace.go:171","msg":"trace[678880429] linearizableReadLoop","detail":"{readStateIndex:2527; appliedIndex:2525; }","duration":"303.80672ms","start":"2026-02-25T06:10:57.556205Z","end":"2026-02-25T06:10:57.860012Z","steps":["trace[678880429] 'read index received' (duration: 174.964321ms)","trace[678880429] 'applied index is now lower than readState.Index' (duration: 128.841189ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:10:57.860167Z","caller":"traceutil/trace.go:171","msg":"trace[428199794] transaction","detail":"{read_only:false; response_revision:2430; number_of_response:1; }","duration":"753.670771ms","start":"2026-02-25T06:10:57.106459Z","end":"2026-02-25T06:10:57.86013Z","steps":["trace[428199794] 'process raft request' (duration: 753.424255ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:10:57.860206Z","caller":"traceutil/trace.go:171","msg":"trace[660790107] transaction","detail":"{read_only:false; response_revision:2429; number_of_response:1; }","duration":"937.716374ms","start":"2026-02-25T06:10:56.922416Z","end":"2026-02-25T06:10:57.860132Z","steps":["trace[660790107] 'process raft request' (duration: 808.737691ms)","trace[660790107] 'compare' (duration: 128.407319ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:10:57.860336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:57.106432Z","time spent":"753.825574ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":538,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:10:57.86037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:56.922388Z","time spent":"937.902068ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:10:57.860401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"859.582843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:10:57.86047Z","caller":"traceutil/trace.go:171","msg":"trace[1212993486] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2431; }","duration":"859.692986ms","start":"2026-02-25T06:10:57.000754Z","end":"2026-02-25T06:10:57.860447Z","steps":["trace[1212993486] 'agreement among raft nodes before linearized reading' (duration: 859.413419ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:10:57.86044Z","caller":"traceutil/trace.go:171","msg":"trace[2023812279] transaction","detail":"{read_only:false; response_revision:2431; number_of_response:1; }","duration":"298.470314ms","start":"2026-02-25T06:10:57.561915Z","end":"2026-02-25T06:10:57.860386Z","steps":["trace[2023812279] 'process raft request' (duration: 298.052395ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:57.860486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.271646168s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-25T06:10:57.860491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.268091746s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:10:57.860546Z","caller":"traceutil/trace.go:171","msg":"trace[253944175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2431; }","duration":"1.271706s","start":"2026-02-25T06:10:56.588824Z","end":"2026-02-25T06:10:57.86053Z","steps":["trace[253944175] 'agreement among raft nodes before linearized reading' (duration: 1.271314441s)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:10:57.86065Z","caller":"traceutil/trace.go:171","msg":"trace[1357230481] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:2431; }","duration":"1.26826478s","start":"2026-02-25T06:10:56.592315Z","end":"2026-02-25T06:10:57.860579Z","steps":["trace[1357230481] 'agreement among raft nodes before linearized reading' (duration: 1.267769768s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:57.860683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.386432429s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-25T06:10:57.860749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:56.5923Z","time spent":"1.268398783s","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"info","ts":"2026-02-25T06:10:57.860749Z","caller":"traceutil/trace.go:171","msg":"trace[811373047] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2431; }","duration":"1.386550511s","start":"2026-02-25T06:10:56.47418Z","end":"2026-02-25T06:10:57.860731Z","steps":["trace[811373047] 'agreement among raft nodes before linearized reading' (duration: 1.385962318s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:57.860715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.38522677s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-25T06:10:57.860656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:56.588775Z","time spent":"1.271867153s","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T06:10:57.86081Z","caller":"traceutil/trace.go:171","msg":"trace[1493500876] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2431; }","duration":"1.385341552s","start":"2026-02-25T06:10:56.475456Z","end":"2026-02-25T06:10:57.860797Z","steps":["trace[1493500876] 'agreement among raft nodes before linearized reading' (duration: 1.384710147s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:57.860807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:56.474149Z","time spent":"1.386644793s","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T06:10:57.860501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.386027689s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-25T06:10:57.860853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:56.475445Z","time spent":"1.385397444s","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T06:10:57.860896Z","caller":"traceutil/trace.go:171","msg":"trace[1626755137] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2431; }","duration":"1.386468319s","start":"2026-02-25T06:10:56.474413Z","end":"2026-02-25T06:10:57.860881Z","steps":["trace[1626755137] 'agreement among raft nodes before linearized reading' (duration: 1.385754222s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:57.860517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:57.00073Z","time spent":"859.778867ms","remote":"127.0.0.1:51338","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-02-25T06:10:57.860766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.148216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-02-25T06:10:57.86082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.999842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:10:57.861046Z","caller":"traceutil/trace.go:171","msg":"trace[1199179452] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:2431; }","duration":"161.267828ms","start":"2026-02-25T06:10:57.699762Z","end":"2026-02-25T06:10:57.86103Z","steps":["trace[1199179452] 'agreement among raft nodes before linearized reading' (duration: 160.551232ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:10:57.861065Z","caller":"traceutil/trace.go:171","msg":"trace[630233327] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2431; }","duration":"493.451183ms","start":"2026-02-25T06:10:57.367558Z","end":"2026-02-25T06:10:57.861009Z","steps":["trace[630233327] 'agreement among raft nodes before linearized reading' (duration: 492.636244ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:57.860949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:56.474389Z","time spent":"1.386548201s","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T06:10:57.861198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:57.36753Z","time spent":"493.650288ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":554,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-02-25T06:10:58.395492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.020706ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:10:58.395594Z","caller":"traceutil/trace.go:171","msg":"trace[1419710553] transaction","detail":"{read_only:false; response_revision:2432; number_of_response:1; }","duration":"529.977859ms","start":"2026-02-25T06:10:57.865597Z","end":"2026-02-25T06:10:58.395574Z","steps":["trace[1419710553] 'process raft request' (duration: 274.79252ms)","trace[1419710553] 'compare' (duration: 254.876452ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:10:58.395655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:57.865575Z","time spent":"530.05252ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:10:58.443859Z","caller":"traceutil/trace.go:171","msg":"trace[1632930305] transaction","detail":"{read_only:false; response_revision:2433; number_of_response:1; }","duration":"578.204748ms","start":"2026-02-25T06:10:57.865618Z","end":"2026-02-25T06:10:58.443823Z","steps":["trace[1632930305] 'process raft request' (duration: 578.038185ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:58.444129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:57.865603Z","time spent":"578.403413ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:10:58.444159Z","caller":"traceutil/trace.go:171","msg":"trace[1352942679] transaction","detail":"{read_only:false; response_revision:2434; number_of_response:1; }","duration":"578.482795ms","start":"2026-02-25T06:10:57.865659Z","end":"2026-02-25T06:10:58.444142Z","steps":["trace[1352942679] 'process raft request' (duration: 578.126527ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:10:58.444383Z","caller":"traceutil/trace.go:171","msg":"trace[1632693271] transaction","detail":"{read_only:false; response_revision:2435; number_of_response:1; }","duration":"578.640609ms","start":"2026-02-25T06:10:57.865711Z","end":"2026-02-25T06:10:58.444352Z","steps":["trace[1632693271] 'process raft request' (duration: 578.329611ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:58.44442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:57.865645Z","time spent":"578.678499ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:10:58.444457Z","caller":"traceutil/trace.go:171","msg":"trace[1857652646] transaction","detail":"{read_only:false; response_revision:2436; number_of_response:1; }","duration":"578.276771ms","start":"2026-02-25T06:10:57.866167Z","end":"2026-02-25T06:10:58.444443Z","steps":["trace[1857652646] 'process raft request' (duration: 578.080035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:58.444522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:57.865687Z","time spent":"578.768002ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:10:58.444535Z","caller":"traceutil/trace.go:171","msg":"trace[1141873492] linearizableReadLoop","detail":"{readStateIndex:2534; appliedIndex:2528; }","duration":"393.382158ms","start":"2026-02-25T06:10:58.051134Z","end":"2026-02-25T06:10:58.444516Z","steps":["trace[1141873492] 'read index received' (duration: 89.333343ms)","trace[1141873492] 'applied index is now lower than readState.Index' (duration: 304.048135ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:10:58.444561Z","caller":"traceutil/trace.go:171","msg":"trace[1503831211] transaction","detail":"{read_only:false; response_revision:2437; number_of_response:1; }","duration":"566.993807ms","start":"2026-02-25T06:10:57.877552Z","end":"2026-02-25T06:10:58.444546Z","steps":["trace[1503831211] 'process raft request' (duration: 566.797192ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:58.444613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:57.866153Z","time spent":"578.369314ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:10:58.444649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:57.877532Z","time spent":"567.075428ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:10:58.444797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.668025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-02-25T06:10:58.44484Z","caller":"traceutil/trace.go:171","msg":"trace[333286975] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2437; }","duration":"393.711626ms","start":"2026-02-25T06:10:58.051117Z","end":"2026-02-25T06:10:58.444829Z","steps":["trace[333286975] 'agreement among raft nodes before linearized reading' (duration: 393.569282ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:58.444866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:58.051063Z","time spent":"393.796907ms","remote":"127.0.0.1:51520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1393,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-02-25T06:11:04.662845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.690527ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-25T06:11:04.66315Z","caller":"traceutil/trace.go:171","msg":"trace[1346125757] transaction","detail":"{read_only:false; response_revision:2462; number_of_response:1; }","duration":"190.933454ms","start":"2026-02-25T06:11:04.472191Z","end":"2026-02-25T06:11:04.663125Z","steps":["trace[1346125757] 'process raft request' (duration: 190.77164ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:04.663139Z","caller":"traceutil/trace.go:171","msg":"trace[380428765] linearizableReadLoop","detail":"{readStateIndex:2560; appliedIndex:2559; }","duration":"243.081665ms","start":"2026-02-25T06:11:04.420027Z","end":"2026-02-25T06:11:04.663109Z","steps":["trace[380428765] 'read index received' (duration: 99.116822ms)","trace[380428765] 'applied index is now lower than readState.Index' (duration: 143.962123ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:11:04.889081Z","caller":"traceutil/trace.go:171","msg":"trace[699733014] linearizableReadLoop","detail":"{readStateIndex:2562; appliedIndex:2561; }","duration":"225.810672ms","start":"2026-02-25T06:11:04.663245Z","end":"2026-02-25T06:11:04.889056Z","steps":["trace[699733014] 'read index received' (duration: 225.491744ms)","trace[699733014] 'applied index is now lower than readState.Index' (duration: 318.208µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:11:04.889106Z","caller":"traceutil/trace.go:171","msg":"trace[1962149204] transaction","detail":"{read_only:false; response_revision:2463; number_of_response:1; }","duration":"276.637593ms","start":"2026-02-25T06:11:04.612441Z","end":"2026-02-25T06:11:04.889079Z","steps":["trace[1962149204] 'process raft request' (duration: 276.303645ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:04.88915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.100002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T06:11:04.88922Z","caller":"traceutil/trace.go:171","msg":"trace[1006155706] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2462; }","duration":"469.212254ms","start":"2026-02-25T06:11:04.419985Z","end":"2026-02-25T06:11:04.889197Z","steps":["trace[1006155706] 'agreement among raft nodes before linearized reading' (duration: 243.29669ms)","trace[1006155706] 'range keys from in-memory index tree' (duration: 225.71564ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:11:04.889248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.061443ms","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-02-25T06:11:04.889276Z","caller":"traceutil/trace.go:171","msg":"trace[1275556292] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2463; }","duration":"385.133695ms","start":"2026-02-25T06:11:04.504134Z","end":"2026-02-25T06:11:04.889267Z","steps":["trace[1275556292] 'agreement among raft nodes before linearized reading' (duration: 385.011062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:04.889286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:04.419965Z","time spent":"469.307047ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T06:11:04.889314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:04.504121Z","time spent":"385.184085ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T06:11:04.88958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.384354ms","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-02-25T06:11:04.889615Z","caller":"traceutil/trace.go:171","msg":"trace[1765037712] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2463; }","duration":"383.437475ms","start":"2026-02-25T06:11:04.506169Z","end":"2026-02-25T06:11:04.889606Z","steps":["trace[1765037712] 'agreement among raft nodes before linearized reading' (duration: 383.351283ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:04.889642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:04.506158Z","time spent":"383.478486ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T06:11:04.889815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.633233ms","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-02-25T06:11:04.889866Z","caller":"traceutil/trace.go:171","msg":"trace[1384236650] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2463; }","duration":"384.721315ms","start":"2026-02-25T06:11:04.505133Z","end":"2026-02-25T06:11:04.889854Z","steps":["trace[1384236650] 'agreement among raft nodes before linearized reading' (duration: 384.550921ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:04.8899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:04.505124Z","time spent":"384.768316ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T06:12:49.766325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.627063ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:12:49.766971Z","caller":"traceutil/trace.go:171","msg":"trace[193605752] transaction","detail":"{read_only:false; response_revision:3132; number_of_response:1; }","duration":"267.496261ms","start":"2026-02-25T06:12:49.499388Z","end":"2026-02-25T06:12:49.766884Z","steps":["trace[193605752] 'process raft request' (duration: 79.179363ms)","trace[193605752] 'compare' (duration: 187.347807ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:12:49.76775Z","caller":"traceutil/trace.go:171","msg":"trace[1577352755] transaction","detail":"{read_only:false; response_revision:3133; number_of_response:1; }","duration":"267.554092ms","start":"2026-02-25T06:12:49.500177Z","end":"2026-02-25T06:12:49.767731Z","steps":["trace[1577352755] 'process raft request' (duration: 266.616952ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:13:18.929171Z","caller":"traceutil/trace.go:171","msg":"trace[1943955342] transaction","detail":"{read_only:false; response_revision:3303; number_of_response:1; }","duration":"269.469264ms","start":"2026-02-25T06:13:18.659673Z","end":"2026-02-25T06:13:18.929143Z","steps":["trace[1943955342] 'process raft request' (duration: 269.314711ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:13:24.495951Z","caller":"traceutil/trace.go:171","msg":"trace[1767085777] linearizableReadLoop","detail":"{readStateIndex:3460; appliedIndex:3459; }","duration":"187.355428ms","start":"2026-02-25T06:13:24.308569Z","end":"2026-02-25T06:13:24.495924Z","steps":["trace[1767085777] 'read index received' (duration: 187.217325ms)","trace[1767085777] 'applied index is now lower than readState.Index' (duration: 137.183µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:13:24.495997Z","caller":"traceutil/trace.go:171","msg":"trace[811903048] transaction","detail":"{read_only:false; response_revision:3328; number_of_response:1; }","duration":"402.98017ms","start":"2026-02-25T06:13:24.092986Z","end":"2026-02-25T06:13:24.495967Z","steps":["trace[811903048] 'process raft request' (duration: 402.786675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:13:24.496103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.519281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T06:13:24.496146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:13:24.092963Z","time spent":"403.101763ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:13:24.496158Z","caller":"traceutil/trace.go:171","msg":"trace[489541395] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:3328; }","duration":"187.636343ms","start":"2026-02-25T06:13:24.30851Z","end":"2026-02-25T06:13:24.496147Z","steps":["trace[489541395] 'agreement among raft nodes before linearized reading' (duration: 187.538591ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:13:24.535602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.086012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:13:24.535658Z","caller":"traceutil/trace.go:171","msg":"trace[1777884670] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:3329; }","duration":"199.207185ms","start":"2026-02-25T06:13:24.336436Z","end":"2026-02-25T06:13:24.535643Z","steps":["trace[1777884670] 'agreement among raft nodes before linearized reading' (duration: 199.114703ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:14:43.939373Z","caller":"traceutil/trace.go:171","msg":"trace[789373182] linearizableReadLoop","detail":"{readStateIndex:4554; appliedIndex:4553; }","duration":"119.256798ms","start":"2026-02-25T06:14:43.820088Z","end":"2026-02-25T06:14:43.939345Z","steps":["trace[789373182] 'read index received' (duration: 75.567878ms)","trace[789373182] 'applied index is now lower than readState.Index' (duration: 43.68795ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:14:43.939424Z","caller":"traceutil/trace.go:171","msg":"trace[1819840685] transaction","detail":"{read_only:false; response_revision:4378; number_of_response:1; }","duration":"119.883302ms","start":"2026-02-25T06:14:43.819486Z","end":"2026-02-25T06:14:43.939369Z","steps":["trace[1819840685] 'process raft request' (duration: 76.084249ms)","trace[1819840685] 'compare' (duration: 43.651449ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:14:43.939651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.529014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/monitoring/loki\" ","response":"range_response_count:1 size:5258"} {"level":"info","ts":"2026-02-25T06:14:43.939722Z","caller":"traceutil/trace.go:171","msg":"trace[9813270] range","detail":"{range_begin:/registry/statefulsets/monitoring/loki; range_end:; response_count:1; response_revision:4378; }","duration":"119.645606ms","start":"2026-02-25T06:14:43.820061Z","end":"2026-02-25T06:14:43.939707Z","steps":["trace[9813270] 'agreement among raft nodes before linearized reading' (duration: 119.401651ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:14:43.940503Z","caller":"traceutil/trace.go:171","msg":"trace[1738323072] transaction","detail":"{read_only:false; response_revision:4379; number_of_response:1; }","duration":"119.789998ms","start":"2026-02-25T06:14:43.820698Z","end":"2026-02-25T06:14:43.940488Z","steps":["trace[1738323072] 'process raft request' (duration: 119.113783ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:14:46.271923Z","caller":"traceutil/trace.go:171","msg":"trace[1419376482] linearizableReadLoop","detail":"{readStateIndex:4607; appliedIndex:4606; }","duration":"380.244401ms","start":"2026-02-25T06:14:45.891648Z","end":"2026-02-25T06:14:46.271893Z","steps":["trace[1419376482] 'read index received' (duration: 380.125568ms)","trace[1419376482] 'applied index is now lower than readState.Index' (duration: 117.543µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:14:46.272036Z","caller":"traceutil/trace.go:171","msg":"trace[1888202843] transaction","detail":"{read_only:false; response_revision:4430; number_of_response:1; }","duration":"387.516803ms","start":"2026-02-25T06:14:45.884487Z","end":"2026-02-25T06:14:46.272004Z","steps":["trace[1888202843] 'process raft request' (duration: 387.190316ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:14:46.272106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.437196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T06:14:46.272146Z","caller":"traceutil/trace.go:171","msg":"trace[261514791] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:4430; }","duration":"380.527627ms","start":"2026-02-25T06:14:45.891606Z","end":"2026-02-25T06:14:46.272134Z","steps":["trace[261514791] 'agreement among raft nodes before linearized reading' (duration: 380.429505ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:14:46.272191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:14:45.891591Z","time spent":"380.589569ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":13,"response size":31,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-02-25T06:14:46.27222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:14:45.884466Z","time spent":"387.651156ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:14:46.310879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.242519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1a1ac71f-af7d-4bf1-abcc-f6e25f8730c1\" ","response":"range_response_count:1 size:3557"} {"level":"info","ts":"2026-02-25T06:14:46.310884Z","caller":"traceutil/trace.go:171","msg":"trace[1702728335] transaction","detail":"{read_only:false; response_revision:4431; number_of_response:1; }","duration":"390.847697ms","start":"2026-02-25T06:14:45.920005Z","end":"2026-02-25T06:14:46.310852Z","steps":["trace[1702728335] 'process raft request' (duration: 390.638361ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:14:46.310933Z","caller":"traceutil/trace.go:171","msg":"trace[1898263522] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1a1ac71f-af7d-4bf1-abcc-f6e25f8730c1; range_end:; response_count:1; response_revision:4431; }","duration":"415.3269ms","start":"2026-02-25T06:14:45.895592Z","end":"2026-02-25T06:14:46.310918Z","steps":["trace[1898263522] 'agreement among raft nodes before linearized reading' (duration: 415.170597ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:14:46.310975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:14:45.895577Z","time spent":"415.392471ms","remote":"127.0.0.1:51504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3581,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1a1ac71f-af7d-4bf1-abcc-f6e25f8730c1\" "} {"level":"warn","ts":"2026-02-25T06:14:46.31099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.613508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T06:14:46.311033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:14:45.919986Z","time spent":"390.96641ms","remote":"127.0.0.1:51504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11352,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:14:46.311027Z","caller":"traceutil/trace.go:171","msg":"trace[1461820209] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:4431; }","duration":"225.708291ms","start":"2026-02-25T06:14:46.085307Z","end":"2026-02-25T06:14:46.311015Z","steps":["trace[1461820209] 'agreement among raft nodes before linearized reading' (duration: 225.63173ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:14:46.311057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.261915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T06:14:46.311111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.232031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-25T06:14:46.311128Z","caller":"traceutil/trace.go:171","msg":"trace[1934142179] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4431; }","duration":"312.283303ms","start":"2026-02-25T06:14:45.998839Z","end":"2026-02-25T06:14:46.311123Z","steps":["trace[1934142179] 'agreement among raft nodes before linearized reading' (duration: 312.234541ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:14:46.311139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:14:45.998821Z","time spent":"312.315273ms","remote":"127.0.0.1:51604","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-02-25T06:14:46.310899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.932188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:14:46.311136Z","caller":"traceutil/trace.go:171","msg":"trace[1036494647] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4431; }","duration":"309.371228ms","start":"2026-02-25T06:14:46.001746Z","end":"2026-02-25T06:14:46.311117Z","steps":["trace[1036494647] 'agreement among raft nodes before linearized reading' (duration: 309.217625ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:14:46.311169Z","caller":"traceutil/trace.go:171","msg":"trace[1863734707] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:0; response_revision:4431; }","duration":"272.273614ms","start":"2026-02-25T06:14:46.038885Z","end":"2026-02-25T06:14:46.311158Z","steps":["trace[1863734707] 'agreement among raft nodes before linearized reading' (duration: 271.935617ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:14:46.311198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:14:46.001737Z","time spent":"309.45473ms","remote":"127.0.0.1:51338","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-02-25T06:14:46.311283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.918913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1a1ac71f-af7d-4bf1-abcc-f6e25f8730c1\" ","response":"range_response_count:1 size:3557"} {"level":"info","ts":"2026-02-25T06:14:46.311333Z","caller":"traceutil/trace.go:171","msg":"trace[581700868] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1a1ac71f-af7d-4bf1-abcc-f6e25f8730c1; range_end:; response_count:1; response_revision:4431; }","duration":"301.987394ms","start":"2026-02-25T06:14:46.009333Z","end":"2026-02-25T06:14:46.31132Z","steps":["trace[581700868] 'agreement among raft nodes before linearized reading' (duration: 301.82945ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:14:46.311368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:14:46.009323Z","time spent":"302.038195ms","remote":"127.0.0.1:51504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3581,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1a1ac71f-af7d-4bf1-abcc-f6e25f8730c1\" "} {"level":"warn","ts":"2026-02-25T06:14:46.311484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.764139ms","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-02-25T06:14:46.311611Z","caller":"traceutil/trace.go:171","msg":"trace[322688480] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4431; }","duration":"315.949614ms","start":"2026-02-25T06:14:45.995633Z","end":"2026-02-25T06:14:46.311583Z","steps":["trace[322688480] 'agreement among raft nodes before linearized reading' (duration: 315.510364ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:14:46.311677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:14:45.995616Z","time spent":"316.050175ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T06:14:57.465193Z","caller":"traceutil/trace.go:171","msg":"trace[1763450657] linearizableReadLoop","detail":"{readStateIndex:4847; appliedIndex:4846; }","duration":"257.075525ms","start":"2026-02-25T06:14:57.208095Z","end":"2026-02-25T06:14:57.46517Z","steps":["trace[1763450657] 'read index received' (duration: 256.83498ms)","trace[1763450657] 'applied index is now lower than readState.Index' (duration: 239.585µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:14:57.465467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.352732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-4nk9z\" ","response":"range_response_count:1 size:6386"} {"level":"info","ts":"2026-02-25T06:14:57.465526Z","caller":"traceutil/trace.go:171","msg":"trace[870747376] range","detail":"{range_begin:/registry/pods/openstack/keepalived-4nk9z; range_end:; response_count:1; response_revision:4664; }","duration":"257.427893ms","start":"2026-02-25T06:14:57.20807Z","end":"2026-02-25T06:14:57.465498Z","steps":["trace[870747376] 'agreement among raft nodes before linearized reading' (duration: 257.228609ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:14:57.465957Z","caller":"traceutil/trace.go:171","msg":"trace[1497246955] transaction","detail":"{read_only:false; response_revision:4664; number_of_response:1; }","duration":"311.352362ms","start":"2026-02-25T06:14:57.154587Z","end":"2026-02-25T06:14:57.465939Z","steps":["trace[1497246955] 'process raft request' (duration: 310.456671ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:14:57.466079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:14:57.154556Z","time spent":"311.451435ms","remote":"127.0.0.1:51504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7902,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:15:04.917027Z","caller":"traceutil/trace.go:171","msg":"trace[433519398] linearizableReadLoop","detail":"{readStateIndex:5009; appliedIndex:5007; }","duration":"298.231147ms","start":"2026-02-25T06:15:04.618767Z","end":"2026-02-25T06:15:04.916998Z","steps":["trace[433519398] 'read index received' (duration: 23.791482ms)","trace[433519398] 'applied index is now lower than readState.Index' (duration: 274.438395ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:15:04.917046Z","caller":"traceutil/trace.go:171","msg":"trace[1827243092] transaction","detail":"{read_only:false; response_revision:4824; number_of_response:1; }","duration":"329.003686ms","start":"2026-02-25T06:15:04.588018Z","end":"2026-02-25T06:15:04.917021Z","steps":["trace[1827243092] 'process raft request' (duration: 321.518309ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:15:04.917369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:15:04.588004Z","time spent":"329.16238ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:15:04.917423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.631296ms","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-02-25T06:15:04.917536Z","caller":"traceutil/trace.go:171","msg":"trace[823969518] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4824; }","duration":"298.79077ms","start":"2026-02-25T06:15:04.61872Z","end":"2026-02-25T06:15:04.91751Z","steps":["trace[823969518] 'agreement among raft nodes before linearized reading' (duration: 298.38899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:15:04.965852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.578783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-06666429-ffa2-4574-a5bd-5c9819cb3471\" ","response":"range_response_count:1 size:3602"} {"level":"info","ts":"2026-02-25T06:15:04.965933Z","caller":"traceutil/trace.go:171","msg":"trace[2031067994] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-06666429-ffa2-4574-a5bd-5c9819cb3471; range_end:; response_count:1; response_revision:4826; }","duration":"226.735877ms","start":"2026-02-25T06:15:04.739178Z","end":"2026-02-25T06:15:04.965913Z","steps":["trace[2031067994] 'agreement among raft nodes before linearized reading' (duration: 226.494111ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:15:07.677457Z","caller":"traceutil/trace.go:171","msg":"trace[2044500581] linearizableReadLoop","detail":"{readStateIndex:5024; appliedIndex:5023; }","duration":"153.870405ms","start":"2026-02-25T06:15:07.523564Z","end":"2026-02-25T06:15:07.677434Z","steps":["trace[2044500581] 'read index received' (duration: 153.672841ms)","trace[2044500581] 'applied index is now lower than readState.Index' (duration: 196.254µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:15:07.677583Z","caller":"traceutil/trace.go:171","msg":"trace[1122438824] transaction","detail":"{read_only:false; response_revision:4839; number_of_response:1; }","duration":"154.377856ms","start":"2026-02-25T06:15:07.52319Z","end":"2026-02-25T06:15:07.677568Z","steps":["trace[1122438824] 'process raft request' (duration: 154.10205ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:15:07.677757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.276187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T06:15:07.677817Z","caller":"traceutil/trace.go:171","msg":"trace[403619888] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4839; }","duration":"151.39764ms","start":"2026-02-25T06:15:07.526405Z","end":"2026-02-25T06:15:07.677802Z","steps":["trace[403619888] 'agreement among raft nodes before linearized reading' (duration: 151.228286ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:15:07.678069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.486519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-4nk9z\" ","response":"range_response_count:1 size:6386"} {"level":"info","ts":"2026-02-25T06:15:07.678175Z","caller":"traceutil/trace.go:171","msg":"trace[930947400] range","detail":"{range_begin:/registry/pods/openstack/keepalived-4nk9z; range_end:; response_count:1; response_revision:4839; }","duration":"154.666673ms","start":"2026-02-25T06:15:07.523482Z","end":"2026-02-25T06:15:07.678149Z","steps":["trace[930947400] 'agreement among raft nodes before linearized reading' (duration: 154.343196ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:15:07.909618Z","caller":"traceutil/trace.go:171","msg":"trace[1559030554] linearizableReadLoop","detail":"{readStateIndex:5025; appliedIndex:5024; }","duration":"226.342388ms","start":"2026-02-25T06:15:07.683255Z","end":"2026-02-25T06:15:07.909598Z","steps":["trace[1559030554] 'read index received' (duration: 224.958886ms)","trace[1559030554] 'applied index is now lower than readState.Index' (duration: 1.382612ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:15:07.909776Z","caller":"traceutil/trace.go:171","msg":"trace[609070632] transaction","detail":"{read_only:false; response_revision:4840; number_of_response:1; }","duration":"227.463284ms","start":"2026-02-25T06:15:07.682276Z","end":"2026-02-25T06:15:07.909739Z","steps":["trace[609070632] 'process raft request' (duration: 226.029591ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:15:07.909867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.603883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:78967"} {"level":"info","ts":"2026-02-25T06:15:07.909898Z","caller":"traceutil/trace.go:171","msg":"trace[268656917] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:4840; }","duration":"226.648995ms","start":"2026-02-25T06:15:07.68324Z","end":"2026-02-25T06:15:07.909889Z","steps":["trace[268656917] 'agreement among raft nodes before linearized reading' (duration: 226.42954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:15:13.079932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.772888ms","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-02-25T06:15:13.080024Z","caller":"traceutil/trace.go:171","msg":"trace[1046298896] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4939; }","duration":"126.917601ms","start":"2026-02-25T06:15:12.953083Z","end":"2026-02-25T06:15:13.080001Z","steps":["trace[1046298896] 'range keys from in-memory index tree' (duration: 126.578423ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:15:13.095882Z","caller":"traceutil/trace.go:171","msg":"trace[120432576] transaction","detail":"{read_only:false; response_revision:4940; number_of_response:1; }","duration":"107.779514ms","start":"2026-02-25T06:15:12.988058Z","end":"2026-02-25T06:15:13.095838Z","steps":["trace[120432576] 'process raft request' (duration: 107.61808ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:15:13.09669Z","caller":"traceutil/trace.go:171","msg":"trace[171821586] transaction","detail":"{read_only:false; response_revision:4941; number_of_response:1; }","duration":"106.214238ms","start":"2026-02-25T06:15:12.990445Z","end":"2026-02-25T06:15:13.09666Z","steps":["trace[171821586] 'process raft request' (duration: 105.954932ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:15:18.154404Z","caller":"traceutil/trace.go:171","msg":"trace[1606130576] linearizableReadLoop","detail":"{readStateIndex:5186; appliedIndex:5185; }","duration":"194.505415ms","start":"2026-02-25T06:15:17.959867Z","end":"2026-02-25T06:15:18.154372Z","steps":["trace[1606130576] 'read index received' (duration: 194.400773ms)","trace[1606130576] 'applied index is now lower than readState.Index' (duration: 103.612µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:15:18.154468Z","caller":"traceutil/trace.go:171","msg":"trace[936302069] transaction","detail":"{read_only:false; response_revision:4995; number_of_response:1; }","duration":"197.945743ms","start":"2026-02-25T06:15:17.956485Z","end":"2026-02-25T06:15:18.154431Z","steps":["trace[936302069] 'process raft request' (duration: 197.729548ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:15:18.154678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.699494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T06:15:18.154762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.870653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:80546"} {"level":"info","ts":"2026-02-25T06:15:18.154803Z","caller":"traceutil/trace.go:171","msg":"trace[422796691] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:4995; }","duration":"194.952426ms","start":"2026-02-25T06:15:17.959841Z","end":"2026-02-25T06:15:18.154793Z","steps":["trace[422796691] 'agreement among raft nodes before linearized reading' (duration: 194.68691ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:15:18.1548Z","caller":"traceutil/trace.go:171","msg":"trace[1532546127] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4995; }","duration":"154.858887ms","start":"2026-02-25T06:15:17.999916Z","end":"2026-02-25T06:15:18.154775Z","steps":["trace[1532546127] 'agreement among raft nodes before linearized reading' (duration: 154.637212ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:15:58.041507Z","caller":"traceutil/trace.go:171","msg":"trace[1069606671] transaction","detail":"{read_only:false; response_revision:5325; number_of_response:1; }","duration":"186.67634ms","start":"2026-02-25T06:15:57.8548Z","end":"2026-02-25T06:15:58.041476Z","steps":["trace[1069606671] 'process raft request' (duration: 186.478866ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:16:10.186379Z","caller":"traceutil/trace.go:171","msg":"trace[1769752024] transaction","detail":"{read_only:false; response_revision:5410; number_of_response:1; }","duration":"108.407611ms","start":"2026-02-25T06:16:10.07795Z","end":"2026-02-25T06:16:10.186358Z","steps":["trace[1769752024] 'process raft request' (duration: 108.283648ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:16:11.100809Z","caller":"traceutil/trace.go:171","msg":"trace[575364655] transaction","detail":"{read_only:false; response_revision:5414; number_of_response:1; }","duration":"181.714918ms","start":"2026-02-25T06:16:10.919076Z","end":"2026-02-25T06:16:11.100791Z","steps":["trace[575364655] 'process raft request' (duration: 181.257798ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:16:11.499375Z","caller":"traceutil/trace.go:171","msg":"trace[1227016290] transaction","detail":"{read_only:false; response_revision:5417; number_of_response:1; }","duration":"110.531159ms","start":"2026-02-25T06:16:11.388802Z","end":"2026-02-25T06:16:11.499333Z","steps":["trace[1227016290] 'process raft request' (duration: 50.486568ms)","trace[1227016290] 'compare' (duration: 59.931129ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:16:11.708136Z","caller":"traceutil/trace.go:171","msg":"trace[642882240] transaction","detail":"{read_only:false; response_revision:5419; number_of_response:1; }","duration":"154.778576ms","start":"2026-02-25T06:16:11.553332Z","end":"2026-02-25T06:16:11.70811Z","steps":["trace[642882240] 'process raft request' (duration: 154.636643ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:16:56.098408Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2716} {"level":"info","ts":"2026-02-25T06:16:56.162147Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2716,"took":"62.346913ms","hash":2494573656,"current-db-size-bytes":42291200,"current-db-size":"42 MB","current-db-size-in-use-bytes":31506432,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-02-25T06:16:56.162221Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2494573656,"revision":2716,"compact-revision":-1} {"level":"info","ts":"2026-02-25T06:21:04.40613Z","caller":"traceutil/trace.go:171","msg":"trace[157986538] linearizableReadLoop","detail":"{readStateIndex:7728; appliedIndex:7727; }","duration":"267.98384ms","start":"2026-02-25T06:21:04.138128Z","end":"2026-02-25T06:21:04.406112Z","steps":["trace[157986538] 'read index received' (duration: 267.912818ms)","trace[157986538] 'applied index is now lower than readState.Index' (duration: 70.262µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:21:04.406212Z","caller":"traceutil/trace.go:171","msg":"trace[1222454241] transaction","detail":"{read_only:false; response_revision:7452; number_of_response:1; }","duration":"352.315418ms","start":"2026-02-25T06:21:04.053871Z","end":"2026-02-25T06:21:04.406187Z","steps":["trace[1222454241] 'process raft request' (duration: 352.132514ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:21:04.406389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:21:04.053847Z","time spent":"352.418419ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:21:04.40643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.273727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-4nk9z\" ","response":"range_response_count:1 size:6386"} {"level":"info","ts":"2026-02-25T06:21:04.406543Z","caller":"traceutil/trace.go:171","msg":"trace[1940459471] range","detail":"{range_begin:/registry/pods/openstack/keepalived-4nk9z; range_end:; response_count:1; response_revision:7452; }","duration":"268.425631ms","start":"2026-02-25T06:21:04.138084Z","end":"2026-02-25T06:21:04.406509Z","steps":["trace[1940459471] 'agreement among raft nodes before linearized reading' (duration: 268.140573ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:21:04.732702Z","caller":"traceutil/trace.go:171","msg":"trace[962666361] transaction","detail":"{read_only:false; response_revision:7453; number_of_response:1; }","duration":"337.793512ms","start":"2026-02-25T06:21:04.394885Z","end":"2026-02-25T06:21:04.732678Z","steps":["trace[962666361] 'process raft request' (duration: 332.848861ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:21:04.732815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:21:04.394856Z","time spent":"337.904134ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:21:04.732752Z","caller":"traceutil/trace.go:171","msg":"trace[1666497649] linearizableReadLoop","detail":"{readStateIndex:7729; appliedIndex:7728; }","duration":"326.534888ms","start":"2026-02-25T06:21:04.406191Z","end":"2026-02-25T06:21:04.732726Z","steps":["trace[1666497649] 'read index received' (duration: 321.542816ms)","trace[1666497649] 'applied index is now lower than readState.Index' (duration: 4.990492ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:21:04.734646Z","caller":"traceutil/trace.go:171","msg":"trace[1765067601] transaction","detail":"{read_only:false; response_revision:7454; number_of_response:1; }","duration":"217.585406ms","start":"2026-02-25T06:21:04.517042Z","end":"2026-02-25T06:21:04.734627Z","steps":["trace[1765067601] 'process raft request' (duration: 217.438293ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:21:04.734652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.222011ms","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-02-25T06:21:04.734753Z","caller":"traceutil/trace.go:171","msg":"trace[333675728] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7453; }","duration":"518.373384ms","start":"2026-02-25T06:21:04.21635Z","end":"2026-02-25T06:21:04.734724Z","steps":["trace[333675728] 'agreement among raft nodes before linearized reading' (duration: 516.603244ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:21:04.73477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.730487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-25T06:21:04.734844Z","caller":"traceutil/trace.go:171","msg":"trace[1733818304] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7454; }","duration":"200.845ms","start":"2026-02-25T06:21:04.533986Z","end":"2026-02-25T06:21:04.734831Z","steps":["trace[1733818304] 'agreement among raft nodes before linearized reading' (duration: 200.668166ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:21:04.734766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.166163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T06:21:04.734824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:21:04.216328Z","time spent":"518.477127ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T06:21:04.734913Z","caller":"traceutil/trace.go:171","msg":"trace[650744242] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:7454; }","duration":"219.355207ms","start":"2026-02-25T06:21:04.515547Z","end":"2026-02-25T06:21:04.734902Z","steps":["trace[650744242] 'agreement among raft nodes before linearized reading' (duration: 219.134793ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:21:04.734955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.162265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:221147"} {"level":"info","ts":"2026-02-25T06:21:04.735035Z","caller":"traceutil/trace.go:171","msg":"trace[428672616] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:7454; }","duration":"324.244217ms","start":"2026-02-25T06:21:04.410772Z","end":"2026-02-25T06:21:04.735016Z","steps":["trace[428672616] 'agreement among raft nodes before linearized reading' (duration: 323.823838ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:21:04.735092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:21:04.410708Z","time spent":"324.362149ms","remote":"127.0.0.1:51504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":25,"response size":221171,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-02-25T06:21:15.586888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.955413ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:21:15.587046Z","caller":"traceutil/trace.go:171","msg":"trace[247096459] linearizableReadLoop","detail":"{readStateIndex:7786; appliedIndex:7785; }","duration":"146.162149ms","start":"2026-02-25T06:21:15.440859Z","end":"2026-02-25T06:21:15.587021Z","steps":["trace[247096459] 'read index received' (duration: 140.754µs)","trace[247096459] 'applied index is now lower than readState.Index' (duration: 146.019475ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:21:15.587428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.537308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T06:21:15.587561Z","caller":"traceutil/trace.go:171","msg":"trace[1069694680] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:7506; }","duration":"146.736863ms","start":"2026-02-25T06:21:15.440802Z","end":"2026-02-25T06:21:15.587539Z","steps":["trace[1069694680] 'agreement among raft nodes before linearized reading' (duration: 146.287433ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:21:15.587618Z","caller":"traceutil/trace.go:171","msg":"trace[353544786] transaction","detail":"{read_only:false; response_revision:7506; number_of_response:1; }","duration":"404.4142ms","start":"2026-02-25T06:21:15.183156Z","end":"2026-02-25T06:21:15.58757Z","steps":["trace[353544786] 'process raft request' (duration: 154.65085ms)","trace[353544786] 'compare' (duration: 248.727057ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:21:15.587824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:21:15.183131Z","time spent":"404.580065ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:21:16.640217Z","caller":"traceutil/trace.go:171","msg":"trace[1118614839] linearizableReadLoop","detail":"{readStateIndex:7787; appliedIndex:7786; }","duration":"113.982795ms","start":"2026-02-25T06:21:16.526208Z","end":"2026-02-25T06:21:16.640191Z","steps":["trace[1118614839] 'read index received' (duration: 113.679547ms)","trace[1118614839] 'applied index is now lower than readState.Index' (duration: 302.038µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:21:16.640271Z","caller":"traceutil/trace.go:171","msg":"trace[167780831] transaction","detail":"{read_only:false; response_revision:7507; number_of_response:1; }","duration":"177.050825ms","start":"2026-02-25T06:21:16.463184Z","end":"2026-02-25T06:21:16.640235Z","steps":["trace[167780831] 'process raft request' (duration: 176.788269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:21:16.640432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.198369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-02-25T06:21:16.640478Z","caller":"traceutil/trace.go:171","msg":"trace[1431747603] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:7507; }","duration":"114.314502ms","start":"2026-02-25T06:21:16.526149Z","end":"2026-02-25T06:21:16.640464Z","steps":["trace[1431747603] 'agreement among raft nodes before linearized reading' (duration: 114.139458ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:21:17.546478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.684681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T06:21:17.546896Z","caller":"traceutil/trace.go:171","msg":"trace[1572580984] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:7515; }","duration":"115.144791ms","start":"2026-02-25T06:21:17.431716Z","end":"2026-02-25T06:21:17.546861Z","steps":["trace[1572580984] 'count revisions from in-memory index tree' (duration: 114.607849ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:21:17.706968Z","caller":"traceutil/trace.go:171","msg":"trace[1918463049] transaction","detail":"{read_only:false; response_revision:7517; number_of_response:1; }","duration":"114.262761ms","start":"2026-02-25T06:21:17.592675Z","end":"2026-02-25T06:21:17.706938Z","steps":["trace[1918463049] 'process raft request' (duration: 94.296201ms)","trace[1918463049] 'compare' (duration: 19.831197ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:21:45.057497Z","caller":"traceutil/trace.go:171","msg":"trace[952755256] linearizableReadLoop","detail":"{readStateIndex:7988; appliedIndex:7987; }","duration":"365.078606ms","start":"2026-02-25T06:21:44.692384Z","end":"2026-02-25T06:21:45.057462Z","steps":["trace[952755256] 'read index received' (duration: 363.628063ms)","trace[952755256] 'applied index is now lower than readState.Index' (duration: 1.448193ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:21:45.057598Z","caller":"traceutil/trace.go:171","msg":"trace[1945050062] transaction","detail":"{read_only:false; response_revision:7701; number_of_response:1; }","duration":"122.525168ms","start":"2026-02-25T06:21:44.935014Z","end":"2026-02-25T06:21:45.057539Z","steps":["trace[1945050062] 'process raft request' (duration: 122.256052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:21:45.057934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.35888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T06:21:45.057993Z","caller":"traceutil/trace.go:171","msg":"trace[550893217] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7702; }","duration":"268.469092ms","start":"2026-02-25T06:21:44.789512Z","end":"2026-02-25T06:21:45.057981Z","steps":["trace[550893217] 'agreement among raft nodes before linearized reading' (duration: 268.240497ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:21:45.058249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.710742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T06:21:45.058286Z","caller":"traceutil/trace.go:171","msg":"trace[1636052844] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:7702; }","duration":"206.779313ms","start":"2026-02-25T06:21:44.851498Z","end":"2026-02-25T06:21:45.058278Z","steps":["trace[1636052844] 'agreement among raft nodes before linearized reading' (duration: 206.65547ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:21:45.058663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.282092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T06:21:45.058698Z","caller":"traceutil/trace.go:171","msg":"trace[1774964813] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:7702; }","duration":"366.360034ms","start":"2026-02-25T06:21:44.692327Z","end":"2026-02-25T06:21:45.058687Z","steps":["trace[1774964813] 'agreement among raft nodes before linearized reading' (duration: 365.369682ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:21:45.058733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:21:44.692298Z","time spent":"366.425646ms","remote":"127.0.0.1:46936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":31,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true "} {"level":"info","ts":"2026-02-25T06:21:48.041926Z","caller":"traceutil/trace.go:171","msg":"trace[1377780508] transaction","detail":"{read_only:false; response_revision:7718; number_of_response:1; }","duration":"236.560583ms","start":"2026-02-25T06:21:47.805332Z","end":"2026-02-25T06:21:48.041893Z","steps":["trace[1377780508] 'process raft request' (duration: 236.385529ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:21:48.249701Z","caller":"traceutil/trace.go:171","msg":"trace[1930644551] transaction","detail":"{read_only:false; response_revision:7719; number_of_response:1; }","duration":"205.859992ms","start":"2026-02-25T06:21:48.043812Z","end":"2026-02-25T06:21:48.249672Z","steps":["trace[1930644551] 'process raft request' (duration: 184.013501ms)","trace[1930644551] 'compare' (duration: 21.627687ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:21:56.105273Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5731} {"level":"info","ts":"2026-02-25T06:21:56.216565Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5731,"took":"108.974702ms","hash":3725076842,"current-db-size-bytes":44593152,"current-db-size":"45 MB","current-db-size-in-use-bytes":23539712,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-02-25T06:21:56.216641Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3725076842,"revision":5731,"compact-revision":2716} {"level":"warn","ts":"2026-02-25T06:21:57.692091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.754132ms","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-02-25T06:21:57.692238Z","caller":"traceutil/trace.go:171","msg":"trace[1238325536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7806; }","duration":"114.938037ms","start":"2026-02-25T06:21:57.577268Z","end":"2026-02-25T06:21:57.692206Z","steps":["trace[1238325536] 'range keys from in-memory index tree' (duration: 114.564168ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:21:57.713208Z","caller":"traceutil/trace.go:171","msg":"trace[1095910980] transaction","detail":"{read_only:false; response_revision:7807; number_of_response:1; }","duration":"125.524204ms","start":"2026-02-25T06:21:57.587651Z","end":"2026-02-25T06:21:57.713175Z","steps":["trace[1095910980] 'process raft request' (duration: 125.33069ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:21:57.713348Z","caller":"traceutil/trace.go:171","msg":"trace[1663611260] linearizableReadLoop","detail":"{readStateIndex:8100; appliedIndex:8100; }","duration":"125.484453ms","start":"2026-02-25T06:21:57.587836Z","end":"2026-02-25T06:21:57.71332Z","steps":["trace[1663611260] 'read index received' (duration: 125.472173ms)","trace[1663611260] 'applied index is now lower than readState.Index' (duration: 10.01µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:21:57.71377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.907463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-vdxdv\" ","response":"range_response_count:1 size:4538"} {"level":"info","ts":"2026-02-25T06:21:57.713866Z","caller":"traceutil/trace.go:171","msg":"trace[658136907] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-vdxdv; range_end:; response_count:1; response_revision:7807; }","duration":"126.048287ms","start":"2026-02-25T06:21:57.587798Z","end":"2026-02-25T06:21:57.713846Z","steps":["trace[658136907] 'agreement among raft nodes before linearized reading' (duration: 125.624616ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:21:58.329724Z","caller":"traceutil/trace.go:171","msg":"trace[1125703866] linearizableReadLoop","detail":"{readStateIndex:8102; appliedIndex:8100; }","duration":"596.652356ms","start":"2026-02-25T06:21:57.733044Z","end":"2026-02-25T06:21:58.329697Z","steps":["trace[1125703866] 'read index received' (duration: 25.032293ms)","trace[1125703866] 'applied index is now lower than readState.Index' (duration: 571.618913ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:21:58.329842Z","caller":"traceutil/trace.go:171","msg":"trace[1857123050] transaction","detail":"{read_only:false; response_revision:7809; number_of_response:1; }","duration":"608.711937ms","start":"2026-02-25T06:21:57.721118Z","end":"2026-02-25T06:21:58.32983Z","steps":["trace[1857123050] 'process raft request' (duration: 608.405521ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:21:58.329956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:21:57.721079Z","time spent":"608.791899ms","remote":"127.0.0.1:51504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4506,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:21:58.330084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.003906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T06:21:58.330185Z","caller":"traceutil/trace.go:171","msg":"trace[1636828737] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:7809; }","duration":"597.152679ms","start":"2026-02-25T06:21:57.733014Z","end":"2026-02-25T06:21:58.330166Z","steps":["trace[1636828737] 'agreement among raft nodes before linearized reading' (duration: 596.961955ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:21:58.330231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:21:57.733001Z","time spent":"597.22028ms","remote":"127.0.0.1:51640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":31,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"info","ts":"2026-02-25T06:21:58.732484Z","caller":"traceutil/trace.go:171","msg":"trace[1251193750] transaction","detail":"{read_only:false; response_revision:7810; number_of_response:1; }","duration":"803.385969ms","start":"2026-02-25T06:21:57.929075Z","end":"2026-02-25T06:21:58.73246Z","steps":["trace[1251193750] 'process raft request' (duration: 803.195175ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:21:58.732664Z","caller":"traceutil/trace.go:171","msg":"trace[1134461359] linearizableReadLoop","detail":"{readStateIndex:8104; appliedIndex:8102; }","duration":"402.834285ms","start":"2026-02-25T06:21:58.3298Z","end":"2026-02-25T06:21:58.732634Z","steps":["trace[1134461359] 'read index received' (duration: 402.519858ms)","trace[1134461359] 'applied index is now lower than readState.Index' (duration: 312.547µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:21:58.732719Z","caller":"traceutil/trace.go:171","msg":"trace[1138287583] transaction","detail":"{read_only:false; response_revision:7811; number_of_response:1; }","duration":"609.424004ms","start":"2026-02-25T06:21:58.123255Z","end":"2026-02-25T06:21:58.732679Z","steps":["trace[1138287583] 'process raft request' (duration: 609.161608ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:21:58.732766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.777309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:21:58.732846Z","caller":"traceutil/trace.go:171","msg":"trace[1194065262] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7811; }","duration":"732.894982ms","start":"2026-02-25T06:21:57.99992Z","end":"2026-02-25T06:21:58.732815Z","steps":["trace[1194065262] 'agreement among raft nodes before linearized reading' (duration: 732.760129ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:21:58.732853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.282191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5710"} {"level":"warn","ts":"2026-02-25T06:21:58.732882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:21:57.999907Z","time spent":"732.966773ms","remote":"127.0.0.1:51338","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-02-25T06:21:58.732877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:21:57.929043Z","time spent":"803.522922ms","remote":"127.0.0.1:51564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2993,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:21:58.732895Z","caller":"traceutil/trace.go:171","msg":"trace[1156064900] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:7811; }","duration":"877.364223ms","start":"2026-02-25T06:21:57.855514Z","end":"2026-02-25T06:21:58.732878Z","steps":["trace[1156064900] 'agreement among raft nodes before linearized reading' (duration: 877.21987ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:21:58.732903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:21:58.123211Z","time spent":"609.595528ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:21:58.732943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:21:57.855489Z","time spent":"877.442044ms","remote":"127.0.0.1:33426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":5734,"request content":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" "} {"level":"info","ts":"2026-02-25T06:21:58.821029Z","caller":"traceutil/trace.go:171","msg":"trace[117953115] transaction","detail":"{read_only:false; response_revision:7812; number_of_response:1; }","duration":"311.646813ms","start":"2026-02-25T06:21:58.509328Z","end":"2026-02-25T06:21:58.820975Z","steps":["trace[117953115] 'process raft request' (duration: 310.834854ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:21:58.821233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:21:58.509299Z","time spent":"311.825047ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:21:58.92757Z","caller":"traceutil/trace.go:171","msg":"trace[310880611] transaction","detail":"{read_only:false; response_revision:7813; number_of_response:1; }","duration":"182.20363ms","start":"2026-02-25T06:21:58.74533Z","end":"2026-02-25T06:21:58.927534Z","steps":["trace[310880611] 'process raft request' (duration: 158.132338ms)","trace[310880611] 'compare' (duration: 23.685914ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:21:59.885948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.453288ms","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-02-25T06:21:59.886057Z","caller":"traceutil/trace.go:171","msg":"trace[1486043175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7823; }","duration":"123.623211ms","start":"2026-02-25T06:21:59.762415Z","end":"2026-02-25T06:21:59.886039Z","steps":["trace[1486043175] 'range keys from in-memory index tree' (duration: 123.290404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:21:59.885948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.051074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-02-25T06:21:59.886275Z","caller":"traceutil/trace.go:171","msg":"trace[1600994167] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:7823; }","duration":"121.431862ms","start":"2026-02-25T06:21:59.764831Z","end":"2026-02-25T06:21:59.886263Z","steps":["trace[1600994167] 'range keys from in-memory index tree' (duration: 120.816639ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:22:10.868448Z","caller":"traceutil/trace.go:171","msg":"trace[1706803666] transaction","detail":"{read_only:false; response_revision:7903; number_of_response:1; }","duration":"161.837781ms","start":"2026-02-25T06:22:10.706584Z","end":"2026-02-25T06:22:10.868421Z","steps":["trace[1706803666] 'process raft request' (duration: 161.654997ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:22:11.084836Z","caller":"traceutil/trace.go:171","msg":"trace[1917487879] transaction","detail":"{read_only:false; response_revision:7904; number_of_response:1; }","duration":"268.84596ms","start":"2026-02-25T06:22:10.815953Z","end":"2026-02-25T06:22:11.084799Z","steps":["trace[1917487879] 'process raft request' (duration: 225.031284ms)","trace[1917487879] 'compare' (duration: 43.672373ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:22:11.677855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.364566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-4nk9z\" ","response":"range_response_count:1 size:6386"} {"level":"warn","ts":"2026-02-25T06:22:11.678034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.169106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T06:22:11.677945Z","caller":"traceutil/trace.go:171","msg":"trace[1316809358] range","detail":"{range_begin:/registry/pods/openstack/keepalived-4nk9z; range_end:; response_count:1; response_revision:7909; }","duration":"183.489659ms","start":"2026-02-25T06:22:11.494435Z","end":"2026-02-25T06:22:11.677924Z","steps":["trace[1316809358] 'range keys from in-memory index tree' (duration: 183.199342ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:22:11.678127Z","caller":"traceutil/trace.go:171","msg":"trace[500656482] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7909; }","duration":"153.300829ms","start":"2026-02-25T06:22:11.524807Z","end":"2026-02-25T06:22:11.678108Z","steps":["trace[500656482] 'range keys from in-memory index tree' (duration: 152.962061ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:22:11.678057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.286062ms","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-02-25T06:22:11.678214Z","caller":"traceutil/trace.go:171","msg":"trace[967101573] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7909; }","duration":"194.471926ms","start":"2026-02-25T06:22:11.483721Z","end":"2026-02-25T06:22:11.678193Z","steps":["trace[967101573] 'range keys from in-memory index tree' (duration: 194.162219ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:22:11.678191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.155846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-25T06:22:11.678296Z","caller":"traceutil/trace.go:171","msg":"trace[1763675437] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7909; }","duration":"225.32263ms","start":"2026-02-25T06:22:11.452946Z","end":"2026-02-25T06:22:11.678269Z","steps":["trace[1763675437] 'range keys from in-memory index tree' (duration: 224.89804ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:22:39.210744Z","caller":"traceutil/trace.go:171","msg":"trace[657265115] transaction","detail":"{read_only:false; response_revision:8155; number_of_response:1; }","duration":"124.98129ms","start":"2026-02-25T06:22:39.085734Z","end":"2026-02-25T06:22:39.210715Z","steps":["trace[657265115] 'process raft request' (duration: 63.689887ms)","trace[657265115] 'compare' (duration: 61.132829ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:22:53.677334Z","caller":"traceutil/trace.go:171","msg":"trace[1115993254] transaction","detail":"{read_only:false; response_revision:8328; number_of_response:1; }","duration":"138.204668ms","start":"2026-02-25T06:22:53.539094Z","end":"2026-02-25T06:22:53.677299Z","steps":["trace[1115993254] 'process raft request' (duration: 137.933082ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:22:56.221897Z","caller":"traceutil/trace.go:171","msg":"trace[1113364237] transaction","detail":"{read_only:false; response_revision:8346; number_of_response:1; }","duration":"104.960562ms","start":"2026-02-25T06:22:56.1169Z","end":"2026-02-25T06:22:56.221861Z","steps":["trace[1113364237] 'process raft request' (duration: 20.697174ms)","trace[1113364237] 'compare' (duration: 84.077944ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:23:03.276153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.123015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T06:23:03.276268Z","caller":"traceutil/trace.go:171","msg":"trace[2107150322] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:8460; }","duration":"117.280608ms","start":"2026-02-25T06:23:03.158962Z","end":"2026-02-25T06:23:03.276243Z","steps":["trace[2107150322] 'count revisions from in-memory index tree' (duration: 117.045562ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:23:03.480003Z","caller":"traceutil/trace.go:171","msg":"trace[528844763] linearizableReadLoop","detail":"{readStateIndex:8773; appliedIndex:8772; }","duration":"188.224327ms","start":"2026-02-25T06:23:03.291748Z","end":"2026-02-25T06:23:03.479972Z","steps":["trace[528844763] 'read index received' (duration: 188.036523ms)","trace[528844763] 'applied index is now lower than readState.Index' (duration: 186.384µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:23:03.480088Z","caller":"traceutil/trace.go:171","msg":"trace[1265347927] transaction","detail":"{read_only:false; response_revision:8461; number_of_response:1; }","duration":"201.728249ms","start":"2026-02-25T06:23:03.278333Z","end":"2026-02-25T06:23:03.480062Z","steps":["trace[1265347927] 'process raft request' (duration: 201.465213ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:23:03.480156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.38584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:23:03.480213Z","caller":"traceutil/trace.go:171","msg":"trace[2072348312] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8461; }","duration":"188.471633ms","start":"2026-02-25T06:23:03.291725Z","end":"2026-02-25T06:23:03.480197Z","steps":["trace[2072348312] 'agreement among raft nodes before linearized reading' (duration: 188.370371ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:23:03.480898Z","caller":"traceutil/trace.go:171","msg":"trace[262747721] transaction","detail":"{read_only:false; response_revision:8462; number_of_response:1; }","duration":"146.676746ms","start":"2026-02-25T06:23:03.334197Z","end":"2026-02-25T06:23:03.480873Z","steps":["trace[262747721] 'process raft request' (duration: 146.292737ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:23:15.752398Z","caller":"traceutil/trace.go:171","msg":"trace[204001046] transaction","detail":"{read_only:false; response_revision:8524; number_of_response:1; }","duration":"231.566619ms","start":"2026-02-25T06:23:15.520801Z","end":"2026-02-25T06:23:15.752368Z","steps":["trace[204001046] 'process raft request' (duration: 231.377715ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:26:22.302751Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d7dbc9f5893cc1f6","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-02-25T06:26:22.308471Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-02-25T06:26:22.308653Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-02-25T06:26:56.112911Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7788} {"level":"info","ts":"2026-02-25T06:26:56.17567Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7788,"took":"60.405195ms","hash":3269079570,"current-db-size-bytes":44593152,"current-db-size":"45 MB","current-db-size-in-use-bytes":24530944,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-02-25T06:26:56.175724Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3269079570,"revision":7788,"compact-revision":5731}