{"level":"warn","ts":"2026-02-25T01:41:15.374794Z","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-25T01:41:15.374885Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.12:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.12:2380","--initial-cluster=instance=https://162.253.55.12:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.12:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.12: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-25T01:41:15.374954Z","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-25T01:41:15.374967Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.12:2380"]} {"level":"info","ts":"2026-02-25T01:41:15.374983Z","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-25T01:41:15.375659Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.12:2379"]} {"level":"info","ts":"2026-02-25T01:41:15.375788Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.12:2380"],"listen-peer-urls":["https://162.253.55.12:2380"],"advertise-client-urls":["https://162.253.55.12:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.12:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.12: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-25T01:41:15.379598Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.473933ms"} {"level":"info","ts":"2026-02-25T01:41:15.385476Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b4c6dc24a78438ca","cluster-id":"587227f9a3f73e98"} {"level":"info","ts":"2026-02-25T01:41:15.385648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T01:41:15.385699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became follower at term 0"} {"level":"info","ts":"2026-02-25T01:41:15.385717Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b4c6dc24a78438ca [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T01:41:15.385734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became follower at term 1"} {"level":"info","ts":"2026-02-25T01:41:15.385799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca switched to configuration voters=(13026341022203132106)"} {"level":"warn","ts":"2026-02-25T01:41:15.396585Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T01:41:15.39959Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T01:41:15.400906Z","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-25T01:41:15.403502Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b4c6dc24a78438ca","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T01:41:15.403767Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b4c6dc24a78438ca","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T01:41:15.403988Z","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-25T01:41:15.404087Z","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-25T01:41:15.404111Z","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-25T01:41:15.404785Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca switched to configuration voters=(13026341022203132106)"} {"level":"info","ts":"2026-02-25T01:41:15.405519Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"587227f9a3f73e98","local-member-id":"b4c6dc24a78438ca","added-peer-id":"b4c6dc24a78438ca","added-peer-peer-urls":["https://162.253.55.12:2380"]} {"level":"info","ts":"2026-02-25T01:41:15.407752Z","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-25T01:41:15.407871Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.12:2380"} {"level":"info","ts":"2026-02-25T01:41:15.407925Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.12:2380"} {"level":"info","ts":"2026-02-25T01:41:15.408054Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b4c6dc24a78438ca","initial-advertise-peer-urls":["https://162.253.55.12:2380"],"listen-peer-urls":["https://162.253.55.12:2380"],"advertise-client-urls":["https://162.253.55.12:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.12:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T01:41:15.408098Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T01:41:15.986495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T01:41:15.986575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T01:41:15.986602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca received MsgPreVoteResp from b4c6dc24a78438ca at term 1"} {"level":"info","ts":"2026-02-25T01:41:15.986626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became candidate at term 2"} {"level":"info","ts":"2026-02-25T01:41:15.986638Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca received MsgVoteResp from b4c6dc24a78438ca at term 2"} {"level":"info","ts":"2026-02-25T01:41:15.986663Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became leader at term 2"} {"level":"info","ts":"2026-02-25T01:41:15.986678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b4c6dc24a78438ca elected leader b4c6dc24a78438ca at term 2"} {"level":"info","ts":"2026-02-25T01:41:16.014165Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T01:41:16.034649Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b4c6dc24a78438ca","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.12:2379]}","request-path":"/0/members/b4c6dc24a78438ca/attributes","cluster-id":"587227f9a3f73e98","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T01:41:16.0348Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T01:41:16.034866Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T01:41:16.035261Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T01:41:16.03544Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T01:41:16.03562Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"587227f9a3f73e98","local-member-id":"b4c6dc24a78438ca","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T01:41:16.035779Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T01:41:16.035831Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T01:41:16.03844Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.12:2379"} {"level":"info","ts":"2026-02-25T01:41:16.039119Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T01:41:25.833595Z","caller":"traceutil/trace.go:171","msg":"trace[454577712] linearizableReadLoop","detail":"{readStateIndex:314; appliedIndex:313; }","duration":"128.807802ms","start":"2026-02-25T01:41:25.704762Z","end":"2026-02-25T01:41:25.83357Z","steps":["trace[454577712] 'read index received' (duration: 128.665548ms)","trace[454577712] 'applied index is now lower than readState.Index' (duration: 141.394µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:41:25.833817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.035207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-02-25T01:41:25.833868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.501039ms","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-25T01:41:25.833907Z","caller":"traceutil/trace.go:171","msg":"trace[1122248874] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:303; }","duration":"129.18851ms","start":"2026-02-25T01:41:25.704705Z","end":"2026-02-25T01:41:25.833893Z","steps":["trace[1122248874] 'agreement among raft nodes before linearized reading' (duration: 129.013846ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:25.83372Z","caller":"traceutil/trace.go:171","msg":"trace[686780251] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"169.908085ms","start":"2026-02-25T01:41:25.663777Z","end":"2026-02-25T01:41:25.833685Z","steps":["trace[686780251] 'process raft request' (duration: 169.641009ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:25.833954Z","caller":"traceutil/trace.go:171","msg":"trace[1563210648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:303; }","duration":"101.625351ms","start":"2026-02-25T01:41:25.732281Z","end":"2026-02-25T01:41:25.833906Z","steps":["trace[1563210648] 'agreement among raft nodes before linearized reading' (duration: 101.500228ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:26.354845Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255364263705574,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T01:41:26.42177Z","caller":"traceutil/trace.go:171","msg":"trace[1284284373] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"583.628765ms","start":"2026-02-25T01:41:25.83811Z","end":"2026-02-25T01:41:26.421739Z","steps":["trace[1284284373] 'process raft request' (duration: 583.269446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:26.422691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:25.838087Z","time spent":"583.761288ms","remote":"127.0.0.1:38826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:41:26.426042Z","caller":"traceutil/trace.go:171","msg":"trace[1766737724] linearizableReadLoop","detail":"{readStateIndex:317; appliedIndex:315; }","duration":"571.601706ms","start":"2026-02-25T01:41:25.854419Z","end":"2026-02-25T01:41:26.426021Z","steps":["trace[1766737724] 'read index received' (duration: 567.432937ms)","trace[1766737724] 'applied index is now lower than readState.Index' (duration: 4.167879ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:41:26.426173Z","caller":"traceutil/trace.go:171","msg":"trace[1979767852] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"587.646889ms","start":"2026-02-25T01:41:25.838489Z","end":"2026-02-25T01:41:26.426136Z","steps":["trace[1979767852] 'process raft request' (duration: 587.389573ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:26.42613Z","caller":"traceutil/trace.go:171","msg":"trace[1505923003] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"583.909941ms","start":"2026-02-25T01:41:25.842204Z","end":"2026-02-25T01:41:26.426114Z","steps":["trace[1505923003] 'process raft request' (duration: 583.764087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:26.426261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.792141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-02-25T01:41:26.426319Z","caller":"traceutil/trace.go:171","msg":"trace[1480873246] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:306; }","duration":"571.920704ms","start":"2026-02-25T01:41:25.85438Z","end":"2026-02-25T01:41:26.426301Z","steps":["trace[1480873246] 'agreement among raft nodes before linearized reading' (duration: 571.76473ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:26.426348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:25.838468Z","time spent":"587.806663ms","remote":"127.0.0.1:38656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1739,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T01:41:26.426376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:25.854362Z","time spent":"572.001605ms","remote":"127.0.0.1:38750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":241,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"warn","ts":"2026-02-25T01:41:26.42644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.019796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6894"} {"level":"warn","ts":"2026-02-25T01:41:26.42641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:25.84219Z","time spent":"584.115865ms","remote":"127.0.0.1:39032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2125,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T01:41:26.426742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.707507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-25T01:41:26.426766Z","caller":"traceutil/trace.go:171","msg":"trace[655650855] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:306; }","duration":"270.771269ms","start":"2026-02-25T01:41:26.15599Z","end":"2026-02-25T01:41:26.426761Z","steps":["trace[655650855] 'agreement among raft nodes before linearized reading' (duration: 270.729128ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:26.426755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.654912ms","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-25T01:41:26.426702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.587533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-25T01:41:26.42666Z","caller":"traceutil/trace.go:171","msg":"trace[1434196546] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:306; }","duration":"409.253542ms","start":"2026-02-25T01:41:26.017397Z","end":"2026-02-25T01:41:26.426651Z","steps":["trace[1434196546] 'agreement among raft nodes before linearized reading' (duration: 408.963044ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:26.426819Z","caller":"traceutil/trace.go:171","msg":"trace[1205301251] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:306; }","duration":"473.738725ms","start":"2026-02-25T01:41:25.953045Z","end":"2026-02-25T01:41:26.426784Z","steps":["trace[1205301251] 'agreement among raft nodes before linearized reading' (duration: 473.644963ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:26.426827Z","caller":"traceutil/trace.go:171","msg":"trace[1143511984] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:306; }","duration":"471.742348ms","start":"2026-02-25T01:41:25.955058Z","end":"2026-02-25T01:41:26.4268Z","steps":["trace[1143511984] 'agreement among raft nodes before linearized reading' (duration: 471.323137ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:26.426834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:26.017315Z","time spent":"409.501458ms","remote":"127.0.0.1:38736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6917,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-02-25T01:41:26.42685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:25.95302Z","time spent":"473.822228ms","remote":"127.0.0.1:38826","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-25T01:41:26.42688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:25.955048Z","time spent":"471.821419ms","remote":"127.0.0.1:38750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-02-25T01:41:26.426695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.775492ms","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-25T01:41:26.426937Z","caller":"traceutil/trace.go:171","msg":"trace[1080560660] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:306; }","duration":"371.059489ms","start":"2026-02-25T01:41:26.05587Z","end":"2026-02-25T01:41:26.42693Z","steps":["trace[1080560660] 'agreement among raft nodes before linearized reading' (duration: 370.802723ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:26.426886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.636499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"warn","ts":"2026-02-25T01:41:26.42696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:26.055843Z","time spent":"371.110909ms","remote":"127.0.0.1:38750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-02-25T01:41:26.426685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.94727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-02-25T01:41:26.427006Z","caller":"traceutil/trace.go:171","msg":"trace[1805971029] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:306; }","duration":"421.805173ms","start":"2026-02-25T01:41:26.005167Z","end":"2026-02-25T01:41:26.426972Z","steps":["trace[1805971029] 'agreement among raft nodes before linearized reading' (duration: 421.503036ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:26.427064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:26.005149Z","time spent":"421.904335ms","remote":"127.0.0.1:38750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":218,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"info","ts":"2026-02-25T01:41:26.427024Z","caller":"traceutil/trace.go:171","msg":"trace[1220082068] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:306; }","duration":"321.338069ms","start":"2026-02-25T01:41:26.105676Z","end":"2026-02-25T01:41:26.427014Z","steps":["trace[1220082068] 'agreement among raft nodes before linearized reading' (duration: 320.747324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:26.427248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:26.105644Z","time spent":"321.595255ms","remote":"127.0.0.1:38750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":257,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-02-25T01:41:26.42666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.781599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-02-25T01:41:26.42731Z","caller":"traceutil/trace.go:171","msg":"trace[1745347109] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:306; }","duration":"521.657441ms","start":"2026-02-25T01:41:25.905634Z","end":"2026-02-25T01:41:26.427291Z","steps":["trace[1745347109] 'agreement among raft nodes before linearized reading' (duration: 520.724738ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:26.427363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:25.90562Z","time spent":"521.729153ms","remote":"127.0.0.1:38750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":226,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-02-25T01:41:31.865786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.228064ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:41:31.866018Z","caller":"traceutil/trace.go:171","msg":"trace[213355935] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"724.816952ms","start":"2026-02-25T01:41:31.141174Z","end":"2026-02-25T01:41:31.865991Z","steps":["trace[213355935] 'process raft request' (duration: 484.842201ms)","trace[213355935] 'compare' (duration: 238.835645ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:41:31.866103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:31.141145Z","time spent":"724.922494ms","remote":"127.0.0.1:38826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:41:32.120575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.958385ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:41:32.121869Z","caller":"traceutil/trace.go:171","msg":"trace[1748533464] linearizableReadLoop","detail":"{readStateIndex:385; appliedIndex:384; }","duration":"391.043665ms","start":"2026-02-25T01:41:31.729637Z","end":"2026-02-25T01:41:32.120681Z","steps":["trace[1748533464] 'read index received' (duration: 289.818623ms)","trace[1748533464] 'applied index is now lower than readState.Index' (duration: 101.223742ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:41:32.122041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.394969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4061"} {"level":"info","ts":"2026-02-25T01:41:32.12209Z","caller":"traceutil/trace.go:171","msg":"trace[1554201556] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:372; }","duration":"392.48876ms","start":"2026-02-25T01:41:31.729583Z","end":"2026-02-25T01:41:32.122072Z","steps":["trace[1554201556] 'agreement among raft nodes before linearized reading' (duration: 392.368917ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:32.122145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:31.729558Z","time spent":"392.577013ms","remote":"127.0.0.1:38722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4084,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-02-25T01:41:32.122194Z","caller":"traceutil/trace.go:171","msg":"trace[317338104] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"798.142097ms","start":"2026-02-25T01:41:31.324001Z","end":"2026-02-25T01:41:32.122143Z","steps":["trace[317338104] 'process raft request' (duration: 695.509031ms)","trace[317338104] 'compare' (duration: 100.72813ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:41:32.122441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:31.323977Z","time spent":"798.350401ms","remote":"127.0.0.1:38722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4047,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:41:32.900954Z","caller":"traceutil/trace.go:171","msg":"trace[1262379219] linearizableReadLoop","detail":"{readStateIndex:386; appliedIndex:385; }","duration":"447.894447ms","start":"2026-02-25T01:41:32.45302Z","end":"2026-02-25T01:41:32.900915Z","steps":["trace[1262379219] 'read index received' (duration: 447.518298ms)","trace[1262379219] 'applied index is now lower than readState.Index' (duration: 375.109µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:41:32.901038Z","caller":"traceutil/trace.go:171","msg":"trace[1344945643] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"743.96776ms","start":"2026-02-25T01:41:32.157015Z","end":"2026-02-25T01:41:32.900982Z","steps":["trace[1344945643] 'process raft request' (duration: 743.612172ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:32.901153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.110882ms","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-25T01:41:32.901196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.252188ms","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-25T01:41:32.901206Z","caller":"traceutil/trace.go:171","msg":"trace[2113497867] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:373; }","duration":"448.216714ms","start":"2026-02-25T01:41:32.452978Z","end":"2026-02-25T01:41:32.901194Z","steps":["trace[2113497867] 'agreement among raft nodes before linearized reading' (duration: 448.080101ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:32.901239Z","caller":"traceutil/trace.go:171","msg":"trace[234295297] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:373; }","duration":"441.3484ms","start":"2026-02-25T01:41:32.459878Z","end":"2026-02-25T01:41:32.901226Z","steps":["trace[234295297] 'agreement among raft nodes before linearized reading' (duration: 441.226117ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:32.90123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:32.156987Z","time spent":"744.157365ms","remote":"127.0.0.1:38722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4053,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:41:32.901251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:32.452958Z","time spent":"448.282286ms","remote":"127.0.0.1:38826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T01:41:32.901293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:32.459865Z","time spent":"441.418272ms","remote":"127.0.0.1:38826","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":"info","ts":"2026-02-25T01:41:38.371848Z","caller":"traceutil/trace.go:171","msg":"trace[2143153323] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"297.743564ms","start":"2026-02-25T01:41:38.074079Z","end":"2026-02-25T01:41:38.371823Z","steps":["trace[2143153323] 'process raft request' (duration: 297.625762ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:39.607257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.812829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.12\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-02-25T01:41:39.607305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.344322ms","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-25T01:41:39.607375Z","caller":"traceutil/trace.go:171","msg":"trace[211987582] range","detail":"{range_begin:/registry/masterleases/162.253.55.12; range_end:; response_count:1; response_revision:416; }","duration":"433.990763ms","start":"2026-02-25T01:41:39.173363Z","end":"2026-02-25T01:41:39.607353Z","steps":["trace[211987582] 'range keys from in-memory index tree' (duration: 433.711097ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:39.607393Z","caller":"traceutil/trace.go:171","msg":"trace[340284386] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:416; }","duration":"676.491206ms","start":"2026-02-25T01:41:38.930879Z","end":"2026-02-25T01:41:39.60737Z","steps":["trace[340284386] 'range keys from in-memory index tree' (duration: 676.23564ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:39.60747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:38.930859Z","time spent":"676.601429ms","remote":"127.0.0.1:38826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T01:41:39.607489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.951128ms","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-25T01:41:39.60754Z","caller":"traceutil/trace.go:171","msg":"trace[1123601430] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:416; }","duration":"657.0158ms","start":"2026-02-25T01:41:38.95051Z","end":"2026-02-25T01:41:39.607526Z","steps":["trace[1123601430] 'range keys from in-memory index tree' (duration: 656.896598ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:39.607586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:38.950502Z","time spent":"657.067982ms","remote":"127.0.0.1:38826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T01:41:39.607733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.553651ms","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-25T01:41:39.607757Z","caller":"traceutil/trace.go:171","msg":"trace[1146200050] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:416; }","duration":"654.605593ms","start":"2026-02-25T01:41:38.953143Z","end":"2026-02-25T01:41:39.607749Z","steps":["trace[1146200050] 'range keys from in-memory index tree' (duration: 654.464829ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:39.607783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:38.953124Z","time spent":"654.652693ms","remote":"127.0.0.1:38826","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-25T01:41:39.607986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.634908ms","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-25T01:41:39.608015Z","caller":"traceutil/trace.go:171","msg":"trace[1240947250] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:416; }","duration":"617.71076ms","start":"2026-02-25T01:41:38.990297Z","end":"2026-02-25T01:41:39.608008Z","steps":["trace[1240947250] 'range keys from in-memory index tree' (duration: 617.470364ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:39.607423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:39.173339Z","time spent":"434.075056ms","remote":"127.0.0.1:38556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.12\" "} {"level":"warn","ts":"2026-02-25T01:41:39.608044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:38.990271Z","time spent":"617.767681ms","remote":"127.0.0.1:38826","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-25T01:41:39.607905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.503498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:41:39.609466Z","caller":"traceutil/trace.go:171","msg":"trace[506880580] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:416; }","duration":"778.053605ms","start":"2026-02-25T01:41:38.831391Z","end":"2026-02-25T01:41:39.609444Z","steps":["trace[506880580] 'range keys from in-memory index tree' (duration: 776.422656ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:39.609517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:38.831331Z","time spent":"778.170598ms","remote":"127.0.0.1:38506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T01:41:39.824787Z","caller":"traceutil/trace.go:171","msg":"trace[435172567] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"211.748255ms","start":"2026-02-25T01:41:39.613021Z","end":"2026-02-25T01:41:39.824769Z","steps":["trace[435172567] 'process raft request' (duration: 211.602232ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:40.056084Z","caller":"traceutil/trace.go:171","msg":"trace[1615835482] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"442.753104ms","start":"2026-02-25T01:41:39.613293Z","end":"2026-02-25T01:41:40.056046Z","steps":["trace[1615835482] 'process raft request' (duration: 378.988398ms)","trace[1615835482] 'compare' (duration: 63.477339ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:41:40.056147Z","caller":"traceutil/trace.go:171","msg":"trace[101906789] linearizableReadLoop","detail":"{readStateIndex:435; appliedIndex:431; }","duration":"224.290346ms","start":"2026-02-25T01:41:39.831834Z","end":"2026-02-25T01:41:40.056124Z","steps":["trace[101906789] 'read index received' (duration: 160.378677ms)","trace[101906789] 'applied index is now lower than readState.Index' (duration: 63.909899ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:41:40.056152Z","caller":"traceutil/trace.go:171","msg":"trace[1033959019] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"442.748193ms","start":"2026-02-25T01:41:39.613359Z","end":"2026-02-25T01:41:40.056107Z","steps":["trace[1033959019] 'process raft request' (duration: 442.531268ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:40.056176Z","caller":"traceutil/trace.go:171","msg":"trace[958275121] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"442.591ms","start":"2026-02-25T01:41:39.613548Z","end":"2026-02-25T01:41:40.056139Z","steps":["trace[958275121] 'process raft request' (duration: 442.419716ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:40.056243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.396749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:41:40.056288Z","caller":"traceutil/trace.go:171","msg":"trace[886164943] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:420; }","duration":"224.480431ms","start":"2026-02-25T01:41:39.831792Z","end":"2026-02-25T01:41:40.056272Z","steps":["trace[886164943] 'agreement among raft nodes before linearized reading' (duration: 224.401889ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:40.056283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:39.613273Z","time spent":"442.905077ms","remote":"127.0.0.1:38826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:41:40.056315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:39.613528Z","time spent":"442.702262ms","remote":"127.0.0.1:38826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:41:40.05631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:39.613352Z","time spent":"442.875336ms","remote":"127.0.0.1:38826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:41:40.056294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:39.626512Z","time spent":"429.774023ms","remote":"127.0.0.1:38556","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-25T01:41:46.452175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.864948ms","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-25T01:41:46.452209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.862078ms","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-25T01:41:46.452276Z","caller":"traceutil/trace.go:171","msg":"trace[1821902838] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:431; }","duration":"175.988571ms","start":"2026-02-25T01:41:46.276272Z","end":"2026-02-25T01:41:46.45226Z","steps":["trace[1821902838] 'range keys from in-memory index tree' (duration: 175.688714ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:46.452273Z","caller":"traceutil/trace.go:171","msg":"trace[342203135] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:431; }","duration":"176.014191ms","start":"2026-02-25T01:41:46.276236Z","end":"2026-02-25T01:41:46.45225Z","steps":["trace[342203135] 'range keys from in-memory index tree' (duration: 175.660103ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:46.452265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.301262ms","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-25T01:41:46.452348Z","caller":"traceutil/trace.go:171","msg":"trace[806697837] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:431; }","duration":"228.435755ms","start":"2026-02-25T01:41:46.223892Z","end":"2026-02-25T01:41:46.452328Z","steps":["trace[806697837] 'range keys from in-memory index tree' (duration: 228.107667ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:50.367442Z","caller":"traceutil/trace.go:171","msg":"trace[1842270591] linearizableReadLoop","detail":"{readStateIndex:497; appliedIndex:496; }","duration":"113.484786ms","start":"2026-02-25T01:41:50.253932Z","end":"2026-02-25T01:41:50.367416Z","steps":["trace[1842270591] 'read index received' (duration: 94.092572ms)","trace[1842270591] 'applied index is now lower than readState.Index' (duration: 19.391294ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:41:50.367643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.685351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-25T01:41:50.367731Z","caller":"traceutil/trace.go:171","msg":"trace[2097587194] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:480; }","duration":"113.816973ms","start":"2026-02-25T01:41:50.2539Z","end":"2026-02-25T01:41:50.367717Z","steps":["trace[2097587194] 'agreement among raft nodes before linearized reading' (duration: 113.608008ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:50.368076Z","caller":"traceutil/trace.go:171","msg":"trace[2010901981] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"161.489294ms","start":"2026-02-25T01:41:50.205967Z","end":"2026-02-25T01:41:50.367457Z","steps":["trace[2010901981] 'process raft request' (duration: 142.022088ms)","trace[2010901981] 'compare' (duration: 19.303262ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:41:51.60094Z","caller":"traceutil/trace.go:171","msg":"trace[301185360] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"402.45316ms","start":"2026-02-25T01:41:51.19847Z","end":"2026-02-25T01:41:51.600924Z","steps":["trace[301185360] 'process raft request' (duration: 402.365888ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:51.601043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:51.198449Z","time spent":"402.541371ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T01:41:51.642084Z","caller":"traceutil/trace.go:171","msg":"trace[1203791575] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"241.97751ms","start":"2026-02-25T01:41:51.400075Z","end":"2026-02-25T01:41:51.642053Z","steps":["trace[1203791575] 'process raft request' (duration: 241.710934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:52.84012Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255364263705947,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T01:41:53.213546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:52.189417Z","time spent":"1.024121693s","remote":"127.0.0.1:51410","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-25T01:41:53.213984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.216007174s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:41:53.21415Z","caller":"traceutil/trace.go:171","msg":"trace[1273093831] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:512; }","duration":"1.21623393s","start":"2026-02-25T01:41:51.997877Z","end":"2026-02-25T01:41:53.214111Z","steps":["trace[1273093831] 'range keys from in-memory index tree' (duration: 1.215916062s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:53.214052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.247354664s","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-25T01:41:53.214271Z","caller":"traceutil/trace.go:171","msg":"trace[244980185] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:512; }","duration":"1.24761453s","start":"2026-02-25T01:41:51.966632Z","end":"2026-02-25T01:41:53.214246Z","steps":["trace[244980185] 'range keys from in-memory index tree' (duration: 1.247156429s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:53.214344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:51.966615Z","time spent":"1.247713013s","remote":"127.0.0.1:38826","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-25T01:41:53.214281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:51.997862Z","time spent":"1.216402914s","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-25T01:41:53.223948Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.022237978s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T01:41:53.224262Z","caller":"traceutil/trace.go:171","msg":"trace[879924402] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"1.022663037s","start":"2026-02-25T01:41:52.201571Z","end":"2026-02-25T01:41:53.224234Z","steps":["trace[879924402] 'process raft request' (duration: 1.022539935s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:53.224385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:52.201545Z","time spent":"1.022780651s","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T01:41:53.277465Z","caller":"traceutil/trace.go:171","msg":"trace[2145200692] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"789.846108ms","start":"2026-02-25T01:41:52.487562Z","end":"2026-02-25T01:41:53.277408Z","steps":["trace[2145200692] 'process raft request' (duration: 789.734465ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:53.277679Z","caller":"traceutil/trace.go:171","msg":"trace[1787183842] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:529; }","duration":"938.300299ms","start":"2026-02-25T01:41:52.339271Z","end":"2026-02-25T01:41:53.277572Z","steps":["trace[1787183842] 'read index received' (duration: 884.819839ms)","trace[1787183842] 'applied index is now lower than readState.Index' (duration: 53.477889ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:41:53.277503Z","caller":"traceutil/trace.go:171","msg":"trace[1349945617] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"1.025627509s","start":"2026-02-25T01:41:52.251829Z","end":"2026-02-25T01:41:53.277456Z","steps":["trace[1349945617] 'process raft request' (duration: 1.025183508s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:53.277869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"938.578956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:41:53.277946Z","caller":"traceutil/trace.go:171","msg":"trace[1183652457] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:516; }","duration":"938.681149ms","start":"2026-02-25T01:41:52.339237Z","end":"2026-02-25T01:41:53.277918Z","steps":["trace[1183652457] 'agreement among raft nodes before linearized reading' (duration: 938.554706ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:53.277967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:52.2518Z","time spent":"1.02602658s","remote":"127.0.0.1:38826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:41:53.278005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:52.339221Z","time spent":"938.76847ms","remote":"127.0.0.1:38642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"warn","ts":"2026-02-25T01:41:53.277887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:52.487547Z","time spent":"790.080663ms","remote":"127.0.0.1:38722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4413,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:41:53.326769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.69158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"warn","ts":"2026-02-25T01:41:53.326797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.737485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-02-25T01:41:53.326841Z","caller":"traceutil/trace.go:171","msg":"trace[559008004] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:517; }","duration":"928.811934ms","start":"2026-02-25T01:41:52.398013Z","end":"2026-02-25T01:41:53.326825Z","steps":["trace[559008004] 'agreement among raft nodes before linearized reading' (duration: 928.618179ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:53.326876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:52.397996Z","time spent":"928.873835ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5609,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-02-25T01:41:53.326856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.835279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-02-25T01:41:53.326933Z","caller":"traceutil/trace.go:171","msg":"trace[387030165] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:517; }","duration":"127.956312ms","start":"2026-02-25T01:41:53.198957Z","end":"2026-02-25T01:41:53.326913Z","steps":["trace[387030165] 'agreement among raft nodes before linearized reading' (duration: 127.716406ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:53.326959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.331952ms","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-25T01:41:53.326984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.546321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-02-25T01:41:53.327024Z","caller":"traceutil/trace.go:171","msg":"trace[1181557243] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:517; }","duration":"328.618222ms","start":"2026-02-25T01:41:52.998396Z","end":"2026-02-25T01:41:53.327014Z","steps":["trace[1181557243] 'agreement among raft nodes before linearized reading' (duration: 328.51416ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:53.327038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"842.698952ms","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-25T01:41:53.327059Z","caller":"traceutil/trace.go:171","msg":"trace[1745769269] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:517; }","duration":"842.735873ms","start":"2026-02-25T01:41:52.484317Z","end":"2026-02-25T01:41:53.327053Z","steps":["trace[1745769269] 'agreement among raft nodes before linearized reading' (duration: 842.685002ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:53.327068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:52.998375Z","time spent":"328.684564ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4106,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-02-25T01:41:53.327081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.183985ms","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-25T01:41:53.327084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:52.484309Z","time spent":"842.770595ms","remote":"127.0.0.1:38826","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-25T01:41:53.327111Z","caller":"traceutil/trace.go:171","msg":"trace[489736279] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:517; }","duration":"846.266928ms","start":"2026-02-25T01:41:52.480836Z","end":"2026-02-25T01:41:53.327103Z","steps":["trace[489736279] 'agreement among raft nodes before linearized reading' (duration: 846.196246ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:53.327154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:52.480802Z","time spent":"846.341539ms","remote":"127.0.0.1:38826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T01:41:53.32688Z","caller":"traceutil/trace.go:171","msg":"trace[1771049684] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:517; }","duration":"728.857888ms","start":"2026-02-25T01:41:52.597994Z","end":"2026-02-25T01:41:53.326852Z","steps":["trace[1771049684] 'agreement among raft nodes before linearized reading' (duration: 728.653193ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:53.327241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:52.597965Z","time spent":"729.269488ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4356,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"info","ts":"2026-02-25T01:41:53.32699Z","caller":"traceutil/trace.go:171","msg":"trace[954357819] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:517; }","duration":"844.400652ms","start":"2026-02-25T01:41:52.482582Z","end":"2026-02-25T01:41:53.326982Z","steps":["trace[954357819] 'agreement among raft nodes before linearized reading' (duration: 844.3104ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:53.327309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:52.48256Z","time spent":"844.744271ms","remote":"127.0.0.1:38826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T01:41:53.327308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"919.048599ms","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-25T01:41:53.327339Z","caller":"traceutil/trace.go:171","msg":"trace[1010891313] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:517; }","duration":"919.1242ms","start":"2026-02-25T01:41:52.408207Z","end":"2026-02-25T01:41:53.327331Z","steps":["trace[1010891313] 'agreement among raft nodes before linearized reading' (duration: 919.037918ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:53.327376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:52.408185Z","time spent":"919.183282ms","remote":"127.0.0.1:38826","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-25T01:41:53.327384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.71053ms","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-25T01:41:53.327414Z","caller":"traceutil/trace.go:171","msg":"trace[1063944084] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:517; }","duration":"528.74084ms","start":"2026-02-25T01:41:52.798665Z","end":"2026-02-25T01:41:53.327406Z","steps":["trace[1063944084] 'agreement among raft nodes before linearized reading' (duration: 528.666659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:53.327476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:52.798529Z","time spent":"528.938756ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9343,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-02-25T01:41:57.635975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.611943ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:41:57.636078Z","caller":"traceutil/trace.go:171","msg":"trace[1697712564] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"172.63438ms","start":"2026-02-25T01:41:57.463415Z","end":"2026-02-25T01:41:57.63605Z","steps":["trace[1697712564] 'process raft request' (duration: 16.861163ms)","trace[1697712564] 'compare' (duration: 155.49609ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:41:57.63644Z","caller":"traceutil/trace.go:171","msg":"trace[1980869751] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"173.004679ms","start":"2026-02-25T01:41:57.463429Z","end":"2026-02-25T01:41:57.636433Z","steps":["trace[1980869751] 'process raft request' (duration: 172.939247ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:57.636602Z","caller":"traceutil/trace.go:171","msg":"trace[262410423] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"171.709248ms","start":"2026-02-25T01:41:57.464888Z","end":"2026-02-25T01:41:57.636597Z","steps":["trace[262410423] 'process raft request' (duration: 171.524513ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:59.778261Z","caller":"traceutil/trace.go:171","msg":"trace[1460783891] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"220.008874ms","start":"2026-02-25T01:41:59.558224Z","end":"2026-02-25T01:41:59.778233Z","steps":["trace[1460783891] 'process raft request' (duration: 178.244965ms)","trace[1460783891] 'compare' (duration: 41.39346ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:41:59.77827Z","caller":"traceutil/trace.go:171","msg":"trace[477085638] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"202.510785ms","start":"2026-02-25T01:41:59.575735Z","end":"2026-02-25T01:41:59.778246Z","steps":["trace[477085638] 'process raft request' (duration: 202.381832ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:59.778332Z","caller":"traceutil/trace.go:171","msg":"trace[1587786667] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"203.96386ms","start":"2026-02-25T01:41:59.574359Z","end":"2026-02-25T01:41:59.778323Z","steps":["trace[1587786667] 'process raft request' (duration: 203.655063ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:59.781296Z","caller":"traceutil/trace.go:171","msg":"trace[2115639241] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"168.8414ms","start":"2026-02-25T01:41:59.612388Z","end":"2026-02-25T01:41:59.781229Z","steps":["trace[2115639241] 'process raft request' (duration: 166.875712ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:59.78127Z","caller":"traceutil/trace.go:171","msg":"trace[1896011348] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:645; }","duration":"140.562613ms","start":"2026-02-25T01:41:59.64062Z","end":"2026-02-25T01:41:59.781182Z","steps":["trace[1896011348] 'read index received' (duration: 95.865853ms)","trace[1896011348] 'applied index is now lower than readState.Index' (duration: 44.691949ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:41:59.782384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.436753ms","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-25T01:41:59.782672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.546787ms","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-25T01:41:59.782456Z","caller":"traceutil/trace.go:171","msg":"trace[1060353188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:630; }","duration":"141.527416ms","start":"2026-02-25T01:41:59.640897Z","end":"2026-02-25T01:41:59.782424Z","steps":["trace[1060353188] 'agreement among raft nodes before linearized reading' (duration: 140.755348ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:59.78289Z","caller":"traceutil/trace.go:171","msg":"trace[1589010391] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:630; }","duration":"141.70705ms","start":"2026-02-25T01:41:59.641038Z","end":"2026-02-25T01:41:59.782745Z","steps":["trace[1589010391] 'agreement among raft nodes before linearized reading' (duration: 141.28977ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:59.783295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.665993ms","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-25T01:41:59.783593Z","caller":"traceutil/trace.go:171","msg":"trace[1015669250] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:630; }","duration":"142.745825ms","start":"2026-02-25T01:41:59.640586Z","end":"2026-02-25T01:41:59.783331Z","steps":["trace[1015669250] 'agreement among raft nodes before linearized reading' (duration: 140.788998ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:00.075812Z","caller":"traceutil/trace.go:171","msg":"trace[601157740] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"291.76577ms","start":"2026-02-25T01:41:59.783995Z","end":"2026-02-25T01:42:00.07576Z","steps":["trace[601157740] 'process raft request' (duration: 196.01055ms)","trace[601157740] 'compare' (duration: 95.502514ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:00.076104Z","caller":"traceutil/trace.go:171","msg":"trace[574645716] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"288.765729ms","start":"2026-02-25T01:41:59.787303Z","end":"2026-02-25T01:42:00.076069Z","steps":["trace[574645716] 'process raft request' (duration: 288.430861ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:00.076168Z","caller":"traceutil/trace.go:171","msg":"trace[1399173729] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"291.31542ms","start":"2026-02-25T01:41:59.78482Z","end":"2026-02-25T01:42:00.076135Z","steps":["trace[1399173729] 'process raft request' (duration: 290.858899ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:00.076306Z","caller":"traceutil/trace.go:171","msg":"trace[2069801665] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:649; }","duration":"246.386155ms","start":"2026-02-25T01:41:59.829837Z","end":"2026-02-25T01:42:00.076223Z","steps":["trace[2069801665] 'read index received' (duration: 4.958419ms)","trace[2069801665] 'applied index is now lower than readState.Index' (duration: 241.426536ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:00.07644Z","caller":"traceutil/trace.go:171","msg":"trace[1944437456] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"288.775219ms","start":"2026-02-25T01:41:59.787643Z","end":"2026-02-25T01:42:00.076418Z","steps":["trace[1944437456] 'process raft request' (duration: 288.232146ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:00.076483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.633341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:42:00.076563Z","caller":"traceutil/trace.go:171","msg":"trace[169973971] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"289.172839ms","start":"2026-02-25T01:41:59.78737Z","end":"2026-02-25T01:42:00.076543Z","steps":["trace[169973971] 'process raft request' (duration: 288.453842ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:00.076598Z","caller":"traceutil/trace.go:171","msg":"trace[838831992] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"246.760904ms","start":"2026-02-25T01:41:59.82982Z","end":"2026-02-25T01:42:00.076581Z","steps":["trace[838831992] 'agreement among raft nodes before linearized reading' (duration: 246.578439ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:00.076705Z","caller":"traceutil/trace.go:171","msg":"trace[785192436] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"249.540691ms","start":"2026-02-25T01:41:59.827153Z","end":"2026-02-25T01:42:00.076693Z","steps":["trace[785192436] 'process raft request' (duration: 248.796883ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:00.814893Z","caller":"traceutil/trace.go:171","msg":"trace[651978673] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"734.35286ms","start":"2026-02-25T01:42:00.08051Z","end":"2026-02-25T01:42:00.814862Z","steps":["trace[651978673] 'process raft request' (duration: 655.638097ms)","trace[651978673] 'compare' (duration: 78.383366ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:00.814994Z","caller":"traceutil/trace.go:171","msg":"trace[1288610119] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:657; }","duration":"562.057178ms","start":"2026-02-25T01:42:00.252913Z","end":"2026-02-25T01:42:00.81497Z","steps":["trace[1288610119] 'read index received' (duration: 483.229202ms)","trace[1288610119] 'applied index is now lower than readState.Index' (duration: 78.826936ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:42:00.815027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:00.080494Z","time spent":"734.476893ms","remote":"127.0.0.1:38708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":900,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T01:42:00.815069Z","caller":"traceutil/trace.go:171","msg":"trace[836395828] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"731.41291ms","start":"2026-02-25T01:42:00.083645Z","end":"2026-02-25T01:42:00.815058Z","steps":["trace[836395828] 'process raft request' (duration: 731.251526ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:00.8151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:00.08364Z","time spent":"731.447451ms","remote":"127.0.0.1:39002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2978,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T01:42:00.815093Z","caller":"traceutil/trace.go:171","msg":"trace[1495459364] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"731.669026ms","start":"2026-02-25T01:42:00.083384Z","end":"2026-02-25T01:42:00.815053Z","steps":["trace[1495459364] 'process raft request' (duration: 731.483231ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:00.815134Z","caller":"traceutil/trace.go:171","msg":"trace[1900279033] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"732.485845ms","start":"2026-02-25T01:42:00.08263Z","end":"2026-02-25T01:42:00.815116Z","steps":["trace[1900279033] 'process raft request' (duration: 732.152137ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:00.815235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:00.082604Z","time spent":"732.577397ms","remote":"127.0.0.1:39002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4105,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T01:42:00.815244Z","caller":"traceutil/trace.go:171","msg":"trace[1896918654] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"673.023953ms","start":"2026-02-25T01:42:00.142197Z","end":"2026-02-25T01:42:00.815221Z","steps":["trace[1896918654] 'process raft request' (duration: 672.734786ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:00.815288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:00.083367Z","time spent":"731.830419ms","remote":"127.0.0.1:39002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3826,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T01:42:00.815324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:00.142171Z","time spent":"673.132176ms","remote":"127.0.0.1:38618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":688,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T01:42:00.815399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.419337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-25T01:42:00.815459Z","caller":"traceutil/trace.go:171","msg":"trace[226863373] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:643; }","duration":"562.56274ms","start":"2026-02-25T01:42:00.25288Z","end":"2026-02-25T01:42:00.815443Z","steps":["trace[226863373] 'agreement among raft nodes before linearized reading' (duration: 562.386446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:00.815501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:00.25286Z","time spent":"562.630252ms","remote":"127.0.0.1:38670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-02-25T01:42:00.957906Z","caller":"traceutil/trace.go:171","msg":"trace[584730094] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"138.100684ms","start":"2026-02-25T01:42:00.819778Z","end":"2026-02-25T01:42:00.957879Z","steps":["trace[584730094] 'process raft request' (duration: 102.374689ms)","trace[584730094] 'compare' (duration: 35.53623ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:00.999892Z","caller":"traceutil/trace.go:171","msg":"trace[515200437] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"179.030743ms","start":"2026-02-25T01:42:00.820828Z","end":"2026-02-25T01:42:00.999858Z","steps":["trace[515200437] 'process raft request' (duration: 178.802858ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:00.9999Z","caller":"traceutil/trace.go:171","msg":"trace[552111960] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"178.905671ms","start":"2026-02-25T01:42:00.820967Z","end":"2026-02-25T01:42:00.999873Z","steps":["trace[552111960] 'process raft request' (duration: 178.804688ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:01.000115Z","caller":"traceutil/trace.go:171","msg":"trace[201019123] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"178.779867ms","start":"2026-02-25T01:42:00.821326Z","end":"2026-02-25T01:42:01.000105Z","steps":["trace[201019123] 'process raft request' (duration: 178.47681ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:01.000148Z","caller":"traceutil/trace.go:171","msg":"trace[553050866] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"178.566613ms","start":"2026-02-25T01:42:00.821567Z","end":"2026-02-25T01:42:01.000133Z","steps":["trace[553050866] 'process raft request' (duration: 178.263516ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:01.000492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.115096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-02-25T01:42:01.000545Z","caller":"traceutil/trace.go:171","msg":"trace[1532559887] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:662; }","duration":"178.583522ms","start":"2026-02-25T01:42:00.821325Z","end":"2026-02-25T01:42:00.999909Z","steps":["trace[1532559887] 'read index received' (duration: 100.808012ms)","trace[1532559887] 'applied index is now lower than readState.Index' (duration: 77.7745ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:01.000613Z","caller":"traceutil/trace.go:171","msg":"trace[1349494639] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:1; response_revision:648; }","duration":"179.29941ms","start":"2026-02-25T01:42:00.821292Z","end":"2026-02-25T01:42:01.000591Z","steps":["trace[1349494639] 'agreement among raft nodes before linearized reading' (duration: 178.952461ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:02.045375Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.017510715s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T01:42:02.046348Z","caller":"traceutil/trace.go:171","msg":"trace[1109774545] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"1.040408953s","start":"2026-02-25T01:42:01.005898Z","end":"2026-02-25T01:42:02.046307Z","steps":["trace[1109774545] 'process raft request' (duration: 1.039814808s)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:02.046502Z","caller":"traceutil/trace.go:171","msg":"trace[272272865] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"1.040041805s","start":"2026-02-25T01:42:01.006422Z","end":"2026-02-25T01:42:02.046464Z","steps":["trace[272272865] 'process raft request' (duration: 1.039716087s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:02.046558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:01.005868Z","time spent":"1.040559667s","remote":"127.0.0.1:39002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3479,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:42:02.046579Z","caller":"traceutil/trace.go:171","msg":"trace[1920698151] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"1.039626114s","start":"2026-02-25T01:42:01.006913Z","end":"2026-02-25T01:42:02.046539Z","steps":["trace[1920698151] 'process raft request' (duration: 1.039394808s)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:02.04665Z","caller":"traceutil/trace.go:171","msg":"trace[1589959268] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"1.039396769s","start":"2026-02-25T01:42:01.00723Z","end":"2026-02-25T01:42:02.046627Z","steps":["trace[1589959268] 'process raft request' (duration: 1.039163643s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:02.046667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.040336862s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/cert-manager/\" range_end:\"/registry/limitranges/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:42:02.046502Z","caller":"traceutil/trace.go:171","msg":"trace[1343343454] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:667; }","duration":"1.040179178s","start":"2026-02-25T01:42:01.006287Z","end":"2026-02-25T01:42:02.046466Z","steps":["trace[1343343454] 'read index received' (duration: 21.555466ms)","trace[1343343454] 'applied index is now lower than readState.Index' (duration: 1.018622092s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:42:02.046715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:01.00641Z","time spent":"1.040205139s","remote":"127.0.0.1:39048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1732,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T01:42:02.046738Z","caller":"traceutil/trace.go:171","msg":"trace[113717104] range","detail":"{range_begin:/registry/limitranges/cert-manager/; range_end:/registry/limitranges/cert-manager0; response_count:0; response_revision:653; }","duration":"1.040478615s","start":"2026-02-25T01:42:01.006236Z","end":"2026-02-25T01:42:02.046715Z","steps":["trace[113717104] 'agreement among raft nodes before linearized reading' (duration: 1.040341482s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:02.046785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:01.00619Z","time spent":"1.040581017s","remote":"127.0.0.1:38686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/cert-manager/\" range_end:\"/registry/limitranges/cert-manager0\" "} {"level":"warn","ts":"2026-02-25T01:42:02.046789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:01.007217Z","time spent":"1.039496621s","remote":"127.0.0.1:39002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4315,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:42:02.046785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:01.006893Z","time spent":"1.039778728s","remote":"127.0.0.1:39002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4603,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:42:02.464294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.09434ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:42:02.464479Z","caller":"traceutil/trace.go:171","msg":"trace[1950787754] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:672; }","duration":"417.709994ms","start":"2026-02-25T01:42:02.046736Z","end":"2026-02-25T01:42:02.464446Z","steps":["trace[1950787754] 'read index received' (duration: 112.342418ms)","trace[1950787754] 'applied index is now lower than readState.Index' (duration: 305.365446ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:02.464608Z","caller":"traceutil/trace.go:171","msg":"trace[2110334375] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"1.426393128s","start":"2026-02-25T01:42:01.038197Z","end":"2026-02-25T01:42:02.46459Z","steps":["trace[2110334375] 'process raft request' (duration: 1.120874358s)","trace[2110334375] 'compare' (duration: 304.826554ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:42:02.46485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:01.038154Z","time spent":"1.426649084s","remote":"127.0.0.1:38618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T01:42:02.465063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.441609012s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dtwm2\" ","response":"range_response_count:1 size:16652"} {"level":"warn","ts":"2026-02-25T01:42:02.465061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.076067296s","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-25T01:42:02.465072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.18128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:42:02.46513Z","caller":"traceutil/trace.go:171","msg":"trace[406986121] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dtwm2; range_end:; response_count:1; response_revision:654; }","duration":"1.441723785s","start":"2026-02-25T01:42:01.02339Z","end":"2026-02-25T01:42:02.465113Z","steps":["trace[406986121] 'agreement among raft nodes before linearized reading' (duration: 1.441460279s)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:02.465166Z","caller":"traceutil/trace.go:171","msg":"trace[1800969495] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:654; }","duration":"1.07622469s","start":"2026-02-25T01:42:01.388914Z","end":"2026-02-25T01:42:02.465139Z","steps":["trace[1800969495] 'agreement among raft nodes before linearized reading' (duration: 1.075875971s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:02.465188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:01.023368Z","time spent":"1.441810367s","remote":"127.0.0.1:38736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16675,"request content":"key:\"/registry/pods/kube-system/cilium-dtwm2\" "} {"level":"info","ts":"2026-02-25T01:42:02.465185Z","caller":"traceutil/trace.go:171","msg":"trace[1682453923] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:654; }","duration":"633.364254ms","start":"2026-02-25T01:42:01.831792Z","end":"2026-02-25T01:42:02.465156Z","steps":["trace[1682453923] 'agreement among raft nodes before linearized reading' (duration: 633.130049ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:02.465216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:01.388892Z","time spent":"1.076315021s","remote":"127.0.0.1:38826","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-25T01:42:02.465253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:01.831773Z","time spent":"633.470286ms","remote":"127.0.0.1:38506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T01:42:02.465435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"983.660475ms","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-25T01:42:02.465566Z","caller":"traceutil/trace.go:171","msg":"trace[1439103257] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:654; }","duration":"983.844459ms","start":"2026-02-25T01:42:01.481696Z","end":"2026-02-25T01:42:02.465541Z","steps":["trace[1439103257] 'agreement among raft nodes before linearized reading' (duration: 983.057841ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:02.465689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:01.481675Z","time spent":"983.998592ms","remote":"127.0.0.1:38826","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":"info","ts":"2026-02-25T01:42:02.931343Z","caller":"traceutil/trace.go:171","msg":"trace[931553466] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"880.981859ms","start":"2026-02-25T01:42:02.050333Z","end":"2026-02-25T01:42:02.931315Z","steps":["trace[931553466] 'process raft request' (duration: 880.933828ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:02.931444Z","caller":"traceutil/trace.go:171","msg":"trace[1788407961] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"881.305426ms","start":"2026-02-25T01:42:02.050129Z","end":"2026-02-25T01:42:02.931435Z","steps":["trace[1788407961] 'process raft request' (duration: 881.08897ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:02.931511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:02.050326Z","time spent":"881.108562ms","remote":"127.0.0.1:38736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3755,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T01:42:02.931386Z","caller":"traceutil/trace.go:171","msg":"trace[737869633] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"881.530801ms","start":"2026-02-25T01:42:02.049833Z","end":"2026-02-25T01:42:02.931364Z","steps":["trace[737869633] 'process raft request' (duration: 881.207332ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:02.931593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:02.049807Z","time spent":"881.772447ms","remote":"127.0.0.1:38736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2619,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T01:42:02.931533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:02.050121Z","time spent":"881.359968ms","remote":"127.0.0.1:38736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3439,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T01:42:02.931869Z","caller":"traceutil/trace.go:171","msg":"trace[1109538313] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:676; }","duration":"467.330642ms","start":"2026-02-25T01:42:02.464529Z","end":"2026-02-25T01:42:02.931859Z","steps":["trace[1109538313] 'read index received' (duration: 467.321971ms)","trace[1109538313] 'applied index is now lower than readState.Index' (duration: 7.681µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:42:02.932048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.449133ms","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-25T01:42:02.932069Z","caller":"traceutil/trace.go:171","msg":"trace[592039233] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:657; }","duration":"849.529155ms","start":"2026-02-25T01:42:02.082534Z","end":"2026-02-25T01:42:02.932064Z","steps":["trace[592039233] 'agreement among raft nodes before linearized reading' (duration: 849.379152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:02.932085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:02.082513Z","time spent":"849.568656ms","remote":"127.0.0.1:38826","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-25T01:42:02.932148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.41822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-startupapicheck\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T01:42:02.932242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"879.510452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/cert-manager/cert-manager-cainjector\" ","response":"range_response_count:1 size:3494"} {"level":"info","ts":"2026-02-25T01:42:02.932261Z","caller":"traceutil/trace.go:171","msg":"trace[1977321399] range","detail":"{range_begin:/registry/deployments/cert-manager/cert-manager-cainjector; range_end:; response_count:1; response_revision:657; }","duration":"879.555544ms","start":"2026-02-25T01:42:02.052701Z","end":"2026-02-25T01:42:02.932256Z","steps":["trace[1977321399] 'agreement among raft nodes before linearized reading' (duration: 879.488712ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:02.932252Z","caller":"traceutil/trace.go:171","msg":"trace[2001839262] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-startupapicheck; range_end:; response_count:0; response_revision:657; }","duration":"874.544704ms","start":"2026-02-25T01:42:02.057676Z","end":"2026-02-25T01:42:02.93222Z","steps":["trace[2001839262] 'agreement among raft nodes before linearized reading' (duration: 874.344269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:02.93228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:02.052691Z","time spent":"879.585464ms","remote":"127.0.0.1:39002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":3517,"request content":"key:\"/registry/deployments/cert-manager/cert-manager-cainjector\" "} {"level":"warn","ts":"2026-02-25T01:42:02.932287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.842942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/cert-manager/cert-manager\" ","response":"range_response_count:1 size:4330"} {"level":"info","ts":"2026-02-25T01:42:02.932364Z","caller":"traceutil/trace.go:171","msg":"trace[668572220] range","detail":"{range_begin:/registry/deployments/cert-manager/cert-manager; range_end:; response_count:1; response_revision:657; }","duration":"877.950175ms","start":"2026-02-25T01:42:02.054399Z","end":"2026-02-25T01:42:02.93235Z","steps":["trace[668572220] 'agreement among raft nodes before linearized reading' (duration: 877.73647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:02.9324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:02.054393Z","time spent":"878.000866ms","remote":"127.0.0.1:39002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":4353,"request content":"key:\"/registry/deployments/cert-manager/cert-manager\" "} {"level":"warn","ts":"2026-02-25T01:42:02.932308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:02.05767Z","time spent":"874.627766ms","remote":"127.0.0.1:38750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-startupapicheck\" "} {"level":"warn","ts":"2026-02-25T01:42:02.932474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.848387ms","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-25T01:42:02.932515Z","caller":"traceutil/trace.go:171","msg":"trace[1016352848] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:657; }","duration":"850.938769ms","start":"2026-02-25T01:42:02.081566Z","end":"2026-02-25T01:42:02.932504Z","steps":["trace[1016352848] 'agreement among raft nodes before linearized reading' (duration: 850.771205ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:02.932566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:02.081536Z","time spent":"851.023191ms","remote":"127.0.0.1:38826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T01:42:02.932582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.918174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/cert-manager/cert-manager-webhook\" ","response":"range_response_count:1 size:4618"} {"level":"info","ts":"2026-02-25T01:42:02.932822Z","caller":"traceutil/trace.go:171","msg":"trace[1956086740] range","detail":"{range_begin:/registry/deployments/cert-manager/cert-manager-webhook; range_end:; response_count:1; response_revision:657; }","duration":"878.18677ms","start":"2026-02-25T01:42:02.054623Z","end":"2026-02-25T01:42:02.93281Z","steps":["trace[1956086740] 'agreement among raft nodes before linearized reading' (duration: 877.793651ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:02.932852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:02.054618Z","time spent":"878.229772ms","remote":"127.0.0.1:39002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":4641,"request content":"key:\"/registry/deployments/cert-manager/cert-manager-webhook\" "} {"level":"warn","ts":"2026-02-25T01:42:02.932855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.451241ms","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-25T01:42:02.932873Z","caller":"traceutil/trace.go:171","msg":"trace[1681184194] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:657; }","duration":"851.491482ms","start":"2026-02-25T01:42:02.081376Z","end":"2026-02-25T01:42:02.932867Z","steps":["trace[1681184194] 'agreement among raft nodes before linearized reading' (duration: 851.435091ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:02.932892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:02.081365Z","time spent":"851.523143ms","remote":"127.0.0.1:38826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T01:42:03.432055Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255364263706228,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T01:42:03.559608Z","caller":"traceutil/trace.go:171","msg":"trace[1311011062] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"1.089489687s","start":"2026-02-25T01:42:02.470084Z","end":"2026-02-25T01:42:03.559574Z","steps":["trace[1311011062] 'process raft request' (duration: 1.089233171s)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:03.559727Z","caller":"traceutil/trace.go:171","msg":"trace[1009585314] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"1.088615686s","start":"2026-02-25T01:42:02.471071Z","end":"2026-02-25T01:42:03.559687Z","steps":["trace[1009585314] 'process raft request' (duration: 1.088462532s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:03.559882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:02.470057Z","time spent":"1.089753573s","remote":"127.0.0.1:38618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":703,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T01:42:03.55967Z","caller":"traceutil/trace.go:171","msg":"trace[1644820273] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"1.089301543s","start":"2026-02-25T01:42:02.470341Z","end":"2026-02-25T01:42:03.559643Z","steps":["trace[1644820273] 'process raft request' (duration: 1.089133889s)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:03.559992Z","caller":"traceutil/trace.go:171","msg":"trace[393019111] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"1.081030815s","start":"2026-02-25T01:42:02.478883Z","end":"2026-02-25T01:42:03.559914Z","steps":["trace[393019111] 'process raft request' (duration: 1.080696377s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:03.56017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:02.471062Z","time spent":"1.088876213s","remote":"127.0.0.1:38826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:42:03.560172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:02.470329Z","time spent":"1.089745473s","remote":"127.0.0.1:38826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:42:03.560227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:02.478873Z","time spent":"1.08122569s","remote":"127.0.0.1:38736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:42:04.466499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.99991774s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T01:42:04.466587Z","caller":"traceutil/trace.go:171","msg":"trace[1883540408] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000033983s","start":"2026-02-25T01:42:02.466532Z","end":"2026-02-25T01:42:04.466566Z","steps":["trace[1883540408] 'agreement among raft nodes before linearized reading' (duration: 1.999935231s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:04.466629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:02.466505Z","time spent":"2.000115974s","remote":"127.0.0.1:38518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 01:42:04 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-02-25T01:42:04.552288Z","caller":"traceutil/trace.go:171","msg":"trace[603314963] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"1.601895627s","start":"2026-02-25T01:42:02.950368Z","end":"2026-02-25T01:42:04.552263Z","steps":["trace[603314963] 'process raft request' (duration: 1.601693772s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:04.552452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:02.950348Z","time spent":"1.60201883s","remote":"127.0.0.1:38826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:42:04.552512Z","caller":"traceutil/trace.go:171","msg":"trace[1387764398] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"1.601950618s","start":"2026-02-25T01:42:02.950545Z","end":"2026-02-25T01:42:04.552495Z","steps":["trace[1387764398] 'process raft request' (duration: 1.601664991s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:04.552603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:02.950517Z","time spent":"1.602036881s","remote":"127.0.0.1:39024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3529,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:42:04.553087Z","caller":"traceutil/trace.go:171","msg":"trace[424907208] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"1.602483311s","start":"2026-02-25T01:42:02.950577Z","end":"2026-02-25T01:42:04.55306Z","steps":["trace[424907208] 'process raft request' (duration: 1.601858896s)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:04.553208Z","caller":"traceutil/trace.go:171","msg":"trace[1922100682] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"1.602649745s","start":"2026-02-25T01:42:02.950541Z","end":"2026-02-25T01:42:04.553191Z","steps":["trace[1922100682] 'process raft request' (duration: 1.60244256s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:04.553232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:02.950568Z","time spent":"1.602588683s","remote":"127.0.0.1:39024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4366,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:42:04.553318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:02.950504Z","time spent":"1.602760968s","remote":"127.0.0.1:38826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:42:04.553665Z","caller":"traceutil/trace.go:171","msg":"trace[2028135260] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"1.603071285s","start":"2026-02-25T01:42:02.950573Z","end":"2026-02-25T01:42:04.553644Z","steps":["trace[2028135260] 'process raft request' (duration: 1.602558602s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:04.553788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:02.950551Z","time spent":"1.603189629s","remote":"127.0.0.1:38618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":771,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T01:42:04.55378Z","caller":"traceutil/trace.go:171","msg":"trace[1131717491] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"1.603160468s","start":"2026-02-25T01:42:02.950558Z","end":"2026-02-25T01:42:04.553719Z","steps":["trace[1131717491] 'process raft request' (duration: 1.602959083s)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:04.553969Z","caller":"traceutil/trace.go:171","msg":"trace[1162021974] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"1.603397814s","start":"2026-02-25T01:42:02.950558Z","end":"2026-02-25T01:42:04.553956Z","steps":["trace[1162021974] 'process raft request' (duration: 1.603079576s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:04.553997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:02.950539Z","time spent":"1.603351432s","remote":"127.0.0.1:39024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4653,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:42:04.554038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:02.950521Z","time spent":"1.603480865s","remote":"127.0.0.1:39002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4626,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:42:04.554252Z","caller":"traceutil/trace.go:171","msg":"trace[1195832343] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"1.603632249s","start":"2026-02-25T01:42:02.95057Z","end":"2026-02-25T01:42:04.554202Z","steps":["trace[1195832343] 'process raft request' (duration: 1.60327966s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:04.554385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:02.950537Z","time spent":"1.603779072s","remote":"127.0.0.1:38826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:42:04.554847Z","caller":"traceutil/trace.go:171","msg":"trace[1764199217] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"1.604233083s","start":"2026-02-25T01:42:02.950602Z","end":"2026-02-25T01:42:04.554835Z","steps":["trace[1764199217] 'process raft request' (duration: 1.603476536s)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:04.554878Z","caller":"traceutil/trace.go:171","msg":"trace[942235783] linearizableReadLoop","detail":"{readStateIndex:694; appliedIndex:676; }","duration":"1.622964291s","start":"2026-02-25T01:42:02.931892Z","end":"2026-02-25T01:42:04.554856Z","steps":["trace[942235783] 'read index received' (duration: 627.534605ms)","trace[942235783] 'applied index is now lower than readState.Index' (duration: 995.428836ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:42:04.554912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:02.950502Z","time spent":"1.604380017s","remote":"127.0.0.1:38618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":881,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T01:42:04.554928Z","caller":"traceutil/trace.go:171","msg":"trace[339176103] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"1.604139951s","start":"2026-02-25T01:42:02.950765Z","end":"2026-02-25T01:42:04.554905Z","steps":["trace[339176103] 'process raft request' (duration: 1.603864455s)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:04.555041Z","caller":"traceutil/trace.go:171","msg":"trace[1545499977] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"1.604426277s","start":"2026-02-25T01:42:02.950604Z","end":"2026-02-25T01:42:04.55503Z","steps":["trace[1545499977] 'process raft request' (duration: 1.603891884s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:04.555098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:02.950751Z","time spent":"1.604290224s","remote":"127.0.0.1:38736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3093,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:42:04.555143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:02.950581Z","time spent":"1.60451055s","remote":"127.0.0.1:39002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3789,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:42:04.555191Z","caller":"traceutil/trace.go:171","msg":"trace[251520592] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"1.146373678s","start":"2026-02-25T01:42:03.408806Z","end":"2026-02-25T01:42:04.555179Z","steps":["trace[251520592] 'process raft request' (duration: 1.146007929s)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:04.55521Z","caller":"traceutil/trace.go:171","msg":"trace[151833055] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"1.532509386s","start":"2026-02-25T01:42:03.02269Z","end":"2026-02-25T01:42:04.555199Z","steps":["trace[151833055] 'process raft request' (duration: 1.532010344s)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:04.554989Z","caller":"traceutil/trace.go:171","msg":"trace[1781405563] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"1.604343706s","start":"2026-02-25T01:42:02.950625Z","end":"2026-02-25T01:42:04.554969Z","steps":["trace[1781405563] 'process raft request' (duration: 1.603964597s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:04.555272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:03.022658Z","time spent":"1.532579388s","remote":"127.0.0.1:38642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":211522,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:42:04.555294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:03.408777Z","time spent":"1.14645083s","remote":"127.0.0.1:38826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:42:04.555425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:02.950619Z","time spent":"1.604699324s","remote":"127.0.0.1:39002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4913,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:42:06.477247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.002951683s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T01:42:06.477326Z","caller":"traceutil/trace.go:171","msg":"trace[1664070317] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.003076416s","start":"2026-02-25T01:42:04.474233Z","end":"2026-02-25T01:42:06.47731Z","steps":["trace[1664070317] 'agreement among raft nodes before linearized reading' (duration: 2.002980014s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:06.477369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:04.474198Z","time spent":"2.003161888s","remote":"127.0.0.1:38506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 01:42:06 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T01:42:06.684115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.970098937s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T01:42:06.684783Z","caller":"traceutil/trace.go:171","msg":"trace[1972942059] linearizableReadLoop","detail":"{readStateIndex:696; appliedIndex:694; }","duration":"2.129814658s","start":"2026-02-25T01:42:04.554939Z","end":"2026-02-25T01:42:06.684753Z","steps":["trace[1972942059] 'read index received' (duration: 159.062456ms)","trace[1972942059] 'applied index is now lower than readState.Index' (duration: 1.970749212s)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:06.684983Z","caller":"traceutil/trace.go:171","msg":"trace[231974502] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"2.213980602s","start":"2026-02-25T01:42:04.470788Z","end":"2026-02-25T01:42:06.684768Z","steps":["trace[231974502] 'process raft request' (duration: 2.213426249s)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:06.685105Z","caller":"traceutil/trace.go:171","msg":"trace[789489772] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"2.123399565s","start":"2026-02-25T01:42:04.561682Z","end":"2026-02-25T01:42:06.685082Z","steps":["trace[789489772] 'process raft request' (duration: 2.123301802s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:06.685243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:04.561647Z","time spent":"2.123527638s","remote":"127.0.0.1:38618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":848,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T01:42:06.685273Z","caller":"traceutil/trace.go:171","msg":"trace[1766202074] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"2.126834348s","start":"2026-02-25T01:42:04.558415Z","end":"2026-02-25T01:42:06.685249Z","steps":["trace[1766202074] 'process raft request' (duration: 2.126223503s)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:06.685351Z","caller":"traceutil/trace.go:171","msg":"trace[1744896773] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"2.125040805s","start":"2026-02-25T01:42:04.560274Z","end":"2026-02-25T01:42:06.685315Z","steps":["trace[1744896773] 'process raft request' (duration: 2.12445454s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:06.68541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:04.558387Z","time spent":"2.126942661s","remote":"127.0.0.1:38618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":727,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T01:42:06.685525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:04.560243Z","time spent":"2.125188178s","remote":"127.0.0.1:39024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3574,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:42:06.685527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:04.470751Z","time spent":"2.2143062s","remote":"127.0.0.1:38618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T01:42:06.744331Z","caller":"traceutil/trace.go:171","msg":"trace[1050992179] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"2.18253251s","start":"2026-02-25T01:42:04.56177Z","end":"2026-02-25T01:42:06.744303Z","steps":["trace[1050992179] 'process raft request' (duration: 2.123299233s)","trace[1050992179] 'compare' (duration: 59.006612ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:42:06.744493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:04.561756Z","time spent":"2.182660753s","remote":"127.0.0.1:39024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4411,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:42:06.744693Z","caller":"traceutil/trace.go:171","msg":"trace[789196115] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"2.181797552s","start":"2026-02-25T01:42:04.562862Z","end":"2026-02-25T01:42:06.74466Z","steps":["trace[789196115] 'process raft request' (duration: 2.181342242s)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:06.745455Z","caller":"traceutil/trace.go:171","msg":"trace[310809980] transaction","detail":"{read_only:false; number_of_response:1; response_revision:681; }","duration":"2.181580428s","start":"2026-02-25T01:42:04.563849Z","end":"2026-02-25T01:42:06.745429Z","steps":["trace[310809980] 'process raft request' (duration: 2.180711807s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:06.745476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:04.562853Z","time spent":"2.181940276s","remote":"127.0.0.1:39024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4698,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:42:06.745603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:04.563842Z","time spent":"2.18168944s","remote":"127.0.0.1:39002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59,"response count":0,"response size":4962,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:42:06.745993Z","caller":"traceutil/trace.go:171","msg":"trace[1627316574] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"2.181089265s","start":"2026-02-25T01:42:04.564856Z","end":"2026-02-25T01:42:06.745945Z","steps":["trace[1627316574] 'process raft request' (duration: 2.179860526s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:06.746203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:04.56485Z","time spent":"2.181221598s","remote":"127.0.0.1:38736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3913,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:42:06.746659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.184629169s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/cert-manager/cert-manager\" ","response":"range_response_count:1 size:4641"} {"level":"info","ts":"2026-02-25T01:42:06.746735Z","caller":"traceutil/trace.go:171","msg":"trace[2008563332] range","detail":"{range_begin:/registry/deployments/cert-manager/cert-manager; range_end:; response_count:1; response_revision:684; }","duration":"2.184727053s","start":"2026-02-25T01:42:04.561978Z","end":"2026-02-25T01:42:06.746706Z","steps":["trace[2008563332] 'agreement among raft nodes before linearized reading' (duration: 2.184299942s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:06.746786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.181870868s","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-25T01:42:06.74684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.840415ms","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-25T01:42:06.746866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:04.561969Z","time spent":"2.184800614s","remote":"127.0.0.1:39002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":4664,"request content":"key:\"/registry/deployments/cert-manager/cert-manager\" "} {"level":"info","ts":"2026-02-25T01:42:06.746927Z","caller":"traceutil/trace.go:171","msg":"trace[443068205] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:684; }","duration":"187.935047ms","start":"2026-02-25T01:42:06.558962Z","end":"2026-02-25T01:42:06.746897Z","steps":["trace[443068205] 'agreement among raft nodes before linearized reading' (duration: 187.468425ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:06.746914Z","caller":"traceutil/trace.go:171","msg":"trace[1128892614] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:684; }","duration":"1.181991991s","start":"2026-02-25T01:42:05.564869Z","end":"2026-02-25T01:42:06.746861Z","steps":["trace[1128892614] 'agreement among raft nodes before linearized reading' (duration: 1.181440987s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:06.747009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.061147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-25T01:42:06.747002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:05.564851Z","time spent":"1.182124344s","remote":"127.0.0.1:38826","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":"info","ts":"2026-02-25T01:42:06.747087Z","caller":"traceutil/trace.go:171","msg":"trace[1102213391] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:684; }","duration":"190.16258ms","start":"2026-02-25T01:42:06.556891Z","end":"2026-02-25T01:42:06.747054Z","steps":["trace[1102213391] 'agreement among raft nodes before linearized reading' (duration: 189.253797ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:06.747078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.183160256s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/cert-manager/cert-manager-cainjector\" ","response":"range_response_count:1 size:3804"} {"level":"info","ts":"2026-02-25T01:42:06.747185Z","caller":"traceutil/trace.go:171","msg":"trace[1120466316] range","detail":"{range_begin:/registry/deployments/cert-manager/cert-manager-cainjector; range_end:; response_count:1; response_revision:684; }","duration":"2.183288219s","start":"2026-02-25T01:42:04.563857Z","end":"2026-02-25T01:42:06.747145Z","steps":["trace[1120466316] 'agreement among raft nodes before linearized reading' (duration: 2.182793916s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:06.747167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.182113824s","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":"warn","ts":"2026-02-25T01:42:06.747229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:04.563844Z","time spent":"2.183377101s","remote":"127.0.0.1:39002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":3827,"request content":"key:\"/registry/deployments/cert-manager/cert-manager-cainjector\" "} {"level":"info","ts":"2026-02-25T01:42:06.747237Z","caller":"traceutil/trace.go:171","msg":"trace[1686707996] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:684; }","duration":"1.182220016s","start":"2026-02-25T01:42:05.565001Z","end":"2026-02-25T01:42:06.747221Z","steps":["trace[1686707996] 'agreement among raft nodes before linearized reading' (duration: 1.181201001s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:06.747302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:05.564968Z","time spent":"1.182314298s","remote":"127.0.0.1:38826","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-25T01:42:06.747354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.401929ms","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-25T01:42:06.747394Z","caller":"traceutil/trace.go:171","msg":"trace[142679664] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:684; }","duration":"188.458319ms","start":"2026-02-25T01:42:06.558918Z","end":"2026-02-25T01:42:06.747376Z","steps":["trace[142679664] 'agreement among raft nodes before linearized reading' (duration: 188.379818ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:06.747556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.131814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:42:06.747734Z","caller":"traceutil/trace.go:171","msg":"trace[746026640] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:684; }","duration":"270.363469ms","start":"2026-02-25T01:42:06.477313Z","end":"2026-02-25T01:42:06.747676Z","steps":["trace[746026640] 'agreement among raft nodes before linearized reading' (duration: 269.305254ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:06.747252Z","caller":"traceutil/trace.go:171","msg":"trace[72956466] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"2.172082131s","start":"2026-02-25T01:42:04.575026Z","end":"2026-02-25T01:42:06.747108Z","steps":["trace[72956466] 'process raft request' (duration: 2.17039411s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:06.749011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:04.575005Z","time spent":"2.173961035s","remote":"127.0.0.1:38750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1039,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T01:42:19.995692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.50632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:42:19.995769Z","caller":"traceutil/trace.go:171","msg":"trace[376580617] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:819; }","duration":"165.637013ms","start":"2026-02-25T01:42:19.830117Z","end":"2026-02-25T01:42:19.995754Z","steps":["trace[376580617] 'range keys from in-memory index tree' (duration: 165.357496ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:23.87756Z","caller":"traceutil/trace.go:171","msg":"trace[2140190033] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"102.331158ms","start":"2026-02-25T01:42:23.775203Z","end":"2026-02-25T01:42:23.877534Z","steps":["trace[2140190033] 'process raft request' (duration: 102.173734ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:24.280241Z","caller":"traceutil/trace.go:171","msg":"trace[38939955] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"113.157435ms","start":"2026-02-25T01:42:24.167059Z","end":"2026-02-25T01:42:24.280216Z","steps":["trace[38939955] 'process raft request' (duration: 113.000681ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:27.857521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.384501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:42:27.857632Z","caller":"traceutil/trace.go:171","msg":"trace[1807794204] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:892; }","duration":"328.560815ms","start":"2026-02-25T01:42:27.529053Z","end":"2026-02-25T01:42:27.857614Z","steps":["trace[1807794204] 'count revisions from in-memory index tree' (duration: 328.232668ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:27.857689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:27.529033Z","time spent":"328.636187ms","remote":"127.0.0.1:38866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-25T01:42:28.720608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.596506ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:42:28.720742Z","caller":"traceutil/trace.go:171","msg":"trace[1231636179] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"189.662748ms","start":"2026-02-25T01:42:28.531057Z","end":"2026-02-25T01:42:28.72072Z","steps":["trace[1231636179] 'process raft request' (duration: 38.840375ms)","trace[1231636179] 'compare' (duration: 150.459253ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:29.326589Z","caller":"traceutil/trace.go:171","msg":"trace[1047089107] linearizableReadLoop","detail":"{readStateIndex:926; appliedIndex:925; }","duration":"112.383647ms","start":"2026-02-25T01:42:29.214177Z","end":"2026-02-25T01:42:29.326561Z","steps":["trace[1047089107] 'read index received' (duration: 48.347742ms)","trace[1047089107] 'applied index is now lower than readState.Index' (duration: 64.034455ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:42:29.326834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.630463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-rpw7g\" ","response":"range_response_count:1 size:5002"} {"level":"info","ts":"2026-02-25T01:42:29.326894Z","caller":"traceutil/trace.go:171","msg":"trace[757509757] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-rpw7g; range_end:; response_count:1; response_revision:897; }","duration":"112.739526ms","start":"2026-02-25T01:42:29.21414Z","end":"2026-02-25T01:42:29.32688Z","steps":["trace[757509757] 'agreement among raft nodes before linearized reading' (duration: 112.523161ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:29.330824Z","caller":"traceutil/trace.go:171","msg":"trace[2036808673] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"115.761766ms","start":"2026-02-25T01:42:29.215037Z","end":"2026-02-25T01:42:29.330798Z","steps":["trace[2036808673] 'process raft request' (duration: 115.541361ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:29.330938Z","caller":"traceutil/trace.go:171","msg":"trace[1402707485] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"115.240844ms","start":"2026-02-25T01:42:29.215691Z","end":"2026-02-25T01:42:29.330932Z","steps":["trace[1402707485] 'process raft request' (duration: 115.001849ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:29.330913Z","caller":"traceutil/trace.go:171","msg":"trace[1510676427] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"115.097771ms","start":"2026-02-25T01:42:29.215806Z","end":"2026-02-25T01:42:29.330904Z","steps":["trace[1510676427] 'process raft request' (duration: 114.912956ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:32.496385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.924628ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:42:32.496616Z","caller":"traceutil/trace.go:171","msg":"trace[103361772] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"217.151981ms","start":"2026-02-25T01:42:32.279443Z","end":"2026-02-25T01:42:32.496595Z","steps":["trace[103361772] 'process raft request' (duration: 217.068139ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:32.496638Z","caller":"traceutil/trace.go:171","msg":"trace[172045770] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"218.098044ms","start":"2026-02-25T01:42:32.278505Z","end":"2026-02-25T01:42:32.496603Z","steps":["trace[172045770] 'process raft request' (duration: 78.854838ms)","trace[172045770] 'compare' (duration: 138.732494ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:33.625015Z","caller":"traceutil/trace.go:171","msg":"trace[300457638] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"151.111099ms","start":"2026-02-25T01:42:33.473882Z","end":"2026-02-25T01:42:33.624993Z","steps":["trace[300457638] 'process raft request' (duration: 150.965125ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:33.667854Z","caller":"traceutil/trace.go:171","msg":"trace[1799596945] linearizableReadLoop","detail":"{readStateIndex:949; appliedIndex:947; }","duration":"184.983486ms","start":"2026-02-25T01:42:33.482847Z","end":"2026-02-25T01:42:33.667831Z","steps":["trace[1799596945] 'read index received' (duration: 141.997412ms)","trace[1799596945] 'applied index is now lower than readState.Index' (duration: 42.985064ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:33.667922Z","caller":"traceutil/trace.go:171","msg":"trace[1844345464] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"190.044696ms","start":"2026-02-25T01:42:33.47785Z","end":"2026-02-25T01:42:33.667895Z","steps":["trace[1844345464] 'process raft request' (duration: 189.869112ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:33.668067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.195141ms","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-25T01:42:33.668133Z","caller":"traceutil/trace.go:171","msg":"trace[1679724942] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:920; }","duration":"185.293094ms","start":"2026-02-25T01:42:33.482822Z","end":"2026-02-25T01:42:33.668115Z","steps":["trace[1679724942] 'agreement among raft nodes before linearized reading' (duration: 185.10313ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:36.386474Z","caller":"traceutil/trace.go:171","msg":"trace[232382076] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"122.197231ms","start":"2026-02-25T01:42:36.264254Z","end":"2026-02-25T01:42:36.386452Z","steps":["trace[232382076] 'process raft request' (duration: 84.760949ms)","trace[232382076] 'compare' (duration: 37.325729ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:42:39.334603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.696025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.12\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-25T01:42:39.334677Z","caller":"traceutil/trace.go:171","msg":"trace[1575230522] range","detail":"{range_begin:/registry/masterleases/162.253.55.12; range_end:; response_count:1; response_revision:960; }","duration":"157.811408ms","start":"2026-02-25T01:42:39.17685Z","end":"2026-02-25T01:42:39.334662Z","steps":["trace[1575230522] 'range keys from in-memory index tree' (duration: 157.562142ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:39.495037Z","caller":"traceutil/trace.go:171","msg":"trace[1613617966] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"156.358294ms","start":"2026-02-25T01:42:39.338653Z","end":"2026-02-25T01:42:39.495011Z","steps":["trace[1613617966] 'process raft request' (duration: 155.973974ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:39.495192Z","caller":"traceutil/trace.go:171","msg":"trace[1797903647] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"152.049222ms","start":"2026-02-25T01:42:39.343109Z","end":"2026-02-25T01:42:39.495159Z","steps":["trace[1797903647] 'process raft request' (duration: 151.850538ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:39.527062Z","caller":"traceutil/trace.go:171","msg":"trace[1443382356] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"181.684957ms","start":"2026-02-25T01:42:39.345357Z","end":"2026-02-25T01:42:39.527042Z","steps":["trace[1443382356] 'process raft request' (duration: 181.569815ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:40.366843Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255364263706792,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T01:42:40.739201Z","caller":"traceutil/trace.go:171","msg":"trace[2000564403] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"965.372393ms","start":"2026-02-25T01:42:39.773776Z","end":"2026-02-25T01:42:40.739148Z","steps":["trace[2000564403] 'process raft request' (duration: 964.991684ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:40.739397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:39.77375Z","time spent":"965.545187ms","remote":"127.0.0.1:38826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:42:40.745794Z","caller":"traceutil/trace.go:171","msg":"trace[171888824] linearizableReadLoop","detail":"{readStateIndex:1000; appliedIndex:998; }","duration":"879.233811ms","start":"2026-02-25T01:42:39.866534Z","end":"2026-02-25T01:42:40.745767Z","steps":["trace[171888824] 'read index received' (duration: 872.341127ms)","trace[171888824] 'applied index is now lower than readState.Index' (duration: 6.888584ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:42:40.746076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"879.525898ms","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-25T01:42:40.746113Z","caller":"traceutil/trace.go:171","msg":"trace[1762970868] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"939.332992ms","start":"2026-02-25T01:42:39.806769Z","end":"2026-02-25T01:42:40.746102Z","steps":["trace[1762970868] 'process raft request' (duration: 938.859591ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:40.746145Z","caller":"traceutil/trace.go:171","msg":"trace[2024854737] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:969; }","duration":"879.64914ms","start":"2026-02-25T01:42:39.86648Z","end":"2026-02-25T01:42:40.746129Z","steps":["trace[2024854737] 'agreement among raft nodes before linearized reading' (duration: 879.522747ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:40.746185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:39.866461Z","time spent":"879.714363ms","remote":"127.0.0.1:38722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-02-25T01:42:40.746198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.117231ms","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-25T01:42:40.746265Z","caller":"traceutil/trace.go:171","msg":"trace[1146484708] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:969; }","duration":"877.205622ms","start":"2026-02-25T01:42:39.869047Z","end":"2026-02-25T01:42:40.746253Z","steps":["trace[1146484708] 'agreement among raft nodes before linearized reading' (duration: 877.033748ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:40.746276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:39.806741Z","time spent":"939.406474ms","remote":"127.0.0.1:38826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:42:40.746301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:39.869037Z","time spent":"877.255743ms","remote":"127.0.0.1:38826","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-25T01:42:40.746306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.947813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T01:42:40.746409Z","caller":"traceutil/trace.go:171","msg":"trace[699365052] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:969; }","duration":"596.106788ms","start":"2026-02-25T01:42:40.150265Z","end":"2026-02-25T01:42:40.746371Z","steps":["trace[699365052] 'agreement among raft nodes before linearized reading' (duration: 595.854902ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:40.746466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:40.150241Z","time spent":"596.21454ms","remote":"127.0.0.1:38924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-02-25T01:42:40.746504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"873.556996ms","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-25T01:42:40.746634Z","caller":"traceutil/trace.go:171","msg":"trace[2030466513] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:969; }","duration":"873.883604ms","start":"2026-02-25T01:42:39.872719Z","end":"2026-02-25T01:42:40.746602Z","steps":["trace[2030466513] 'agreement among raft nodes before linearized reading' (duration: 873.474284ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:40.746713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:39.872702Z","time spent":"873.996686ms","remote":"127.0.0.1:38826","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-25T01:42:40.929243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.210716ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:42:40.929479Z","caller":"traceutil/trace.go:171","msg":"trace[1825920938] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"176.835713ms","start":"2026-02-25T01:42:40.752585Z","end":"2026-02-25T01:42:40.929421Z","steps":["trace[1825920938] 'process raft request' (duration: 58.298539ms)","trace[1825920938] 'compare' (duration: 118ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:42:54.175511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.272754ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:42:54.175685Z","caller":"traceutil/trace.go:171","msg":"trace[1751770746] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"196.710425ms","start":"2026-02-25T01:42:53.97895Z","end":"2026-02-25T01:42:54.17566Z","steps":["trace[1751770746] 'process raft request' (duration: 45.174596ms)","trace[1751770746] 'compare' (duration: 150.915844ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:43:02.115326Z","caller":"traceutil/trace.go:171","msg":"trace[271590699] linearizableReadLoop","detail":"{readStateIndex:1149; appliedIndex:1148; }","duration":"283.629805ms","start":"2026-02-25T01:43:01.831671Z","end":"2026-02-25T01:43:02.115301Z","steps":["trace[271590699] 'read index received' (duration: 283.4268ms)","trace[271590699] 'applied index is now lower than readState.Index' (duration: 202.124µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:43:02.115495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.810769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:43:02.115522Z","caller":"traceutil/trace.go:171","msg":"trace[672224353] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1111; }","duration":"283.906042ms","start":"2026-02-25T01:43:01.831609Z","end":"2026-02-25T01:43:02.115515Z","steps":["trace[672224353] 'agreement among raft nodes before linearized reading' (duration: 283.83047ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:43:02.115482Z","caller":"traceutil/trace.go:171","msg":"trace[124218904] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"356.267355ms","start":"2026-02-25T01:43:01.759164Z","end":"2026-02-25T01:43:02.115431Z","steps":["trace[124218904] 'process raft request' (duration: 355.998198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:02.115681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:43:01.759144Z","time spent":"356.439259ms","remote":"127.0.0.1:38826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:43:07.199724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.790047ms","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-25T01:43:07.199824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.198407ms","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-25T01:43:07.199949Z","caller":"traceutil/trace.go:171","msg":"trace[1027231242] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1220; }","duration":"150.404131ms","start":"2026-02-25T01:43:07.049522Z","end":"2026-02-25T01:43:07.199926Z","steps":["trace[1027231242] 'range keys from in-memory index tree' (duration: 149.93775ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:43:07.199876Z","caller":"traceutil/trace.go:171","msg":"trace[1019746935] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1220; }","duration":"149.967231ms","start":"2026-02-25T01:43:07.049863Z","end":"2026-02-25T01:43:07.19983Z","steps":["trace[1019746935] 'range keys from in-memory index tree' (duration: 149.610273ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:43:07.503659Z","caller":"traceutil/trace.go:171","msg":"trace[828843065] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"109.224972ms","start":"2026-02-25T01:43:07.394384Z","end":"2026-02-25T01:43:07.503609Z","steps":["trace[828843065] 'process raft request' (duration: 109.065488ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:43:07.506796Z","caller":"traceutil/trace.go:171","msg":"trace[1791821839] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"108.543215ms","start":"2026-02-25T01:43:07.398236Z","end":"2026-02-25T01:43:07.506779Z","steps":["trace[1791821839] 'process raft request' (duration: 108.341981ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:43:21.06498Z","caller":"traceutil/trace.go:171","msg":"trace[2043657793] transaction","detail":"{read_only:false; response_revision:1464; number_of_response:1; }","duration":"117.224252ms","start":"2026-02-25T01:43:20.947734Z","end":"2026-02-25T01:43:21.064958Z","steps":["trace[2043657793] 'process raft request' (duration: 117.11405ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:43:37.725267Z","caller":"traceutil/trace.go:171","msg":"trace[871069804] transaction","detail":"{read_only:false; response_revision:1565; number_of_response:1; }","duration":"141.640316ms","start":"2026-02-25T01:43:37.583599Z","end":"2026-02-25T01:43:37.725239Z","steps":["trace[871069804] 'process raft request' (duration: 100.928118ms)","trace[871069804] 'compare' (duration: 40.553954ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:43:40.055874Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255364263708080,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T01:43:40.403904Z","caller":"traceutil/trace.go:171","msg":"trace[533197982] linearizableReadLoop","detail":"{readStateIndex:1638; appliedIndex:1637; }","duration":"848.906154ms","start":"2026-02-25T01:43:39.554975Z","end":"2026-02-25T01:43:40.403881Z","steps":["trace[533197982] 'read index received' (duration: 848.7125ms)","trace[533197982] 'applied index is now lower than readState.Index' (duration: 192.924µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:43:40.403976Z","caller":"traceutil/trace.go:171","msg":"trace[908786530] transaction","detail":"{read_only:false; response_revision:1570; number_of_response:1; }","duration":"918.241382ms","start":"2026-02-25T01:43:39.485706Z","end":"2026-02-25T01:43:40.403947Z","steps":["trace[908786530] 'process raft request' (duration: 918.031907ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:40.404101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.021937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T01:43:40.404115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:43:39.485671Z","time spent":"918.370165ms","remote":"127.0.0.1:38826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:43:40.40414Z","caller":"traceutil/trace.go:171","msg":"trace[415969826] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1570; }","duration":"703.121569ms","start":"2026-02-25T01:43:39.701008Z","end":"2026-02-25T01:43:40.404129Z","steps":["trace[415969826] 'agreement among raft nodes before linearized reading' (duration: 703.039777ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:40.40418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:43:39.700983Z","time spent":"703.189151ms","remote":"127.0.0.1:38506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T01:43:40.404181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.261219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T01:43:40.404237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.221232ms","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-25T01:43:40.404285Z","caller":"traceutil/trace.go:171","msg":"trace[870830865] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1570; }","duration":"572.414762ms","start":"2026-02-25T01:43:39.831849Z","end":"2026-02-25T01:43:40.404264Z","steps":["trace[870830865] 'agreement among raft nodes before linearized reading' (duration: 572.253278ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:40.404332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:43:39.831828Z","time spent":"572.496544ms","remote":"127.0.0.1:38518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T01:43:40.40433Z","caller":"traceutil/trace.go:171","msg":"trace[1648950868] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1570; }","duration":"849.370275ms","start":"2026-02-25T01:43:39.554937Z","end":"2026-02-25T01:43:40.404308Z","steps":["trace[1648950868] 'agreement among raft nodes before linearized reading' (duration: 849.091959ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:40.404331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.740505ms","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-25T01:43:40.404388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:43:39.554918Z","time spent":"849.452377ms","remote":"127.0.0.1:38826","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-25T01:43:40.404406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.582182ms","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-25T01:43:40.404423Z","caller":"traceutil/trace.go:171","msg":"trace[1606923248] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1570; }","duration":"674.869288ms","start":"2026-02-25T01:43:39.729541Z","end":"2026-02-25T01:43:40.40441Z","steps":["trace[1606923248] 'agreement among raft nodes before linearized reading' (duration: 674.685123ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:43:40.404474Z","caller":"traceutil/trace.go:171","msg":"trace[2123937298] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1570; }","duration":"661.678384ms","start":"2026-02-25T01:43:39.742778Z","end":"2026-02-25T01:43:40.404457Z","steps":["trace[2123937298] 'agreement among raft nodes before linearized reading' (duration: 661.391497ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:40.404485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:43:39.729521Z","time spent":"674.9484ms","remote":"127.0.0.1:38826","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-25T01:43:40.404526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:43:39.742763Z","time spent":"661.751856ms","remote":"127.0.0.1:38826","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-25T01:43:40.404631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.011668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:3875"} {"level":"info","ts":"2026-02-25T01:43:40.404755Z","caller":"traceutil/trace.go:171","msg":"trace[569177131] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1570; }","duration":"547.202974ms","start":"2026-02-25T01:43:39.857504Z","end":"2026-02-25T01:43:40.404707Z","steps":["trace[569177131] 'agreement among raft nodes before linearized reading' (duration: 546.794494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:40.404887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:43:39.857471Z","time spent":"547.393477ms","remote":"127.0.0.1:38788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3898,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-02-25T01:43:41.396377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.32627ms","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-25T01:43:41.396507Z","caller":"traceutil/trace.go:171","msg":"trace[1658226554] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1571; }","duration":"525.473703ms","start":"2026-02-25T01:43:40.871014Z","end":"2026-02-25T01:43:41.396488Z","steps":["trace[1658226554] 'range keys from in-memory index tree' (duration: 525.31049ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:41.396531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.997747ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:43:41.396631Z","caller":"traceutil/trace.go:171","msg":"trace[894742177] linearizableReadLoop","detail":"{readStateIndex:1641; appliedIndex:1639; }","duration":"966.867371ms","start":"2026-02-25T01:43:40.429758Z","end":"2026-02-25T01:43:41.396625Z","steps":["trace[894742177] 'read index received' (duration: 195.745191ms)","trace[894742177] 'applied index is now lower than readState.Index' (duration: 771.12159ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:43:41.396677Z","caller":"traceutil/trace.go:171","msg":"trace[1241489861] transaction","detail":"{read_only:false; response_revision:1572; number_of_response:1; }","duration":"987.521162ms","start":"2026-02-25T01:43:40.40915Z","end":"2026-02-25T01:43:41.396672Z","steps":["trace[1241489861] 'process raft request' (duration: 216.33945ms)","trace[1241489861] 'compare' (duration: 770.853964ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:43:41.396704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:43:40.409123Z","time spent":"987.567242ms","remote":"127.0.0.1:38826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:43:41.396805Z","caller":"traceutil/trace.go:171","msg":"trace[2119506070] transaction","detail":"{read_only:false; response_revision:1573; number_of_response:1; }","duration":"987.294067ms","start":"2026-02-25T01:43:40.409507Z","end":"2026-02-25T01:43:41.396801Z","steps":["trace[2119506070] 'process raft request' (duration: 987.06653ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:41.396832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:43:40.409481Z","time spent":"987.333988ms","remote":"127.0.0.1:38826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:43:41.396888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.171888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:43:41.396979Z","caller":"traceutil/trace.go:171","msg":"trace[1656090507] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1573; }","duration":"565.307771ms","start":"2026-02-25T01:43:40.831653Z","end":"2026-02-25T01:43:41.396961Z","steps":["trace[1656090507] 'agreement among raft nodes before linearized reading' (duration: 565.177148ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:41.396974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"967.21565ms","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-25T01:43:41.397019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:43:40.831631Z","time spent":"565.380023ms","remote":"127.0.0.1:38506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T01:43:41.397036Z","caller":"traceutil/trace.go:171","msg":"trace[123880602] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1573; }","duration":"967.303622ms","start":"2026-02-25T01:43:40.429716Z","end":"2026-02-25T01:43:41.39702Z","steps":["trace[123880602] 'agreement among raft nodes before linearized reading' (duration: 967.118047ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:41.397073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:43:40.429699Z","time spent":"967.365574ms","remote":"127.0.0.1:38826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T01:43:41.397198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.126042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:43:41.39725Z","caller":"traceutil/trace.go:171","msg":"trace[1913906864] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1573; }","duration":"420.211114ms","start":"2026-02-25T01:43:40.977022Z","end":"2026-02-25T01:43:41.397233Z","steps":["trace[1913906864] 'agreement among raft nodes before linearized reading' (duration: 420.102951ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:41.397302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:43:40.977006Z","time spent":"420.282256ms","remote":"127.0.0.1:49524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-02-25T01:43:41.824003Z","caller":"traceutil/trace.go:171","msg":"trace[548288027] linearizableReadLoop","detail":"{readStateIndex:1642; appliedIndex:1641; }","duration":"412.746747ms","start":"2026-02-25T01:43:41.41124Z","end":"2026-02-25T01:43:41.823986Z","steps":["trace[548288027] 'read index received' (duration: 412.629684ms)","trace[548288027] 'applied index is now lower than readState.Index' (duration: 116.343µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:43:41.824107Z","caller":"traceutil/trace.go:171","msg":"trace[1240889150] transaction","detail":"{read_only:false; response_revision:1574; number_of_response:1; }","duration":"422.08595ms","start":"2026-02-25T01:43:41.401997Z","end":"2026-02-25T01:43:41.824083Z","steps":["trace[1240889150] 'process raft request' (duration: 421.869405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:41.824231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:43:41.401971Z","time spent":"422.196002ms","remote":"127.0.0.1:38826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:43:41.824248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.910592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:3875"} {"level":"info","ts":"2026-02-25T01:43:41.82428Z","caller":"traceutil/trace.go:171","msg":"trace[714556858] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1574; }","duration":"413.089086ms","start":"2026-02-25T01:43:41.411183Z","end":"2026-02-25T01:43:41.824272Z","steps":["trace[714556858] 'agreement among raft nodes before linearized reading' (duration: 412.869061ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:41.824302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:43:41.411165Z","time spent":"413.131036ms","remote":"127.0.0.1:38788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3898,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-02-25T01:51:16.065526Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2114} {"level":"info","ts":"2026-02-25T01:51:16.136319Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2114,"took":"69.979788ms","hash":1932858818,"current-db-size-bytes":14577664,"current-db-size":"15 MB","current-db-size-in-use-bytes":4313088,"current-db-size-in-use":"4.3 MB"} {"level":"info","ts":"2026-02-25T01:51:16.136391Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1932858818,"revision":2114,"compact-revision":-1} {"level":"info","ts":"2026-02-25T01:56:16.071684Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3043} {"level":"info","ts":"2026-02-25T01:56:16.091969Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3043,"took":"19.548814ms","hash":3152386504,"current-db-size-bytes":14577664,"current-db-size":"15 MB","current-db-size-in-use-bytes":4677632,"current-db-size-in-use":"4.7 MB"} {"level":"info","ts":"2026-02-25T01:56:16.092044Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3152386504,"revision":3043,"compact-revision":2114}