{"level":"warn","ts":"2026-04-17T05:57:34.705996Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-17T05:57:34.706092Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.72:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.72:2380","--initial-cluster=instance=https://199.204.45.72:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.72:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.72: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-04-17T05:57:34.706159Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-17T05:57:34.706171Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-04-17T05:57:34.706191Z","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-04-17T05:57:34.707005Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"]} {"level":"info","ts":"2026-04-17T05:57:34.707087Z","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":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.72: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-04-17T05:57:34.712707Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.441307ms"} {"level":"info","ts":"2026-04-17T05:57:34.72301Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"50e03cebfd94cbba","cluster-id":"120c03c1d5b80f94"} {"level":"info","ts":"2026-04-17T05:57:34.7231Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=()"} {"level":"info","ts":"2026-04-17T05:57:34.723123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 0"} {"level":"info","ts":"2026-04-17T05:57:34.723136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 50e03cebfd94cbba [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-17T05:57:34.723144Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 1"} {"level":"info","ts":"2026-04-17T05:57:34.723183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"warn","ts":"2026-04-17T05:57:34.735837Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-17T05:57:34.737118Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-17T05:57:34.738135Z","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-04-17T05:57:34.74416Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"50e03cebfd94cbba","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-17T05:57:34.744416Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"50e03cebfd94cbba","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-17T05:57:34.744656Z","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-04-17T05:57:34.744728Z","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-04-17T05:57:34.744738Z","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-04-17T05:57:34.747701Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"info","ts":"2026-04-17T05:57:34.747857Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","added-peer-id":"50e03cebfd94cbba","added-peer-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-04-17T05:57:34.75191Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-04-17T05:57:34.75194Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-04-17T05:57:34.751949Z","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-04-17T05:57:34.752211Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"50e03cebfd94cbba","initial-advertise-peer-urls":["https://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-17T05:57:34.752238Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-17T05:57:35.223923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba is starting a new election at term 1"} {"level":"info","ts":"2026-04-17T05:57:35.223981Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became pre-candidate at term 1"} {"level":"info","ts":"2026-04-17T05:57:35.224004Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgPreVoteResp from 50e03cebfd94cbba at term 1"} {"level":"info","ts":"2026-04-17T05:57:35.224019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became candidate at term 2"} {"level":"info","ts":"2026-04-17T05:57:35.224024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgVoteResp from 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-04-17T05:57:35.224033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became leader at term 2"} {"level":"info","ts":"2026-04-17T05:57:35.224041Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 50e03cebfd94cbba elected leader 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-04-17T05:57:35.26163Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"50e03cebfd94cbba","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.72:2379]}","request-path":"/0/members/50e03cebfd94cbba/attributes","cluster-id":"120c03c1d5b80f94","publish-timeout":"7s"} {"level":"info","ts":"2026-04-17T05:57:35.261644Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T05:57:35.261654Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T05:57:35.262139Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-17T05:57:35.262198Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-17T05:57:35.262658Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T05:57:35.262817Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T05:57:35.262857Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T05:57:35.265177Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-17T05:57:35.271447Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T05:57:35.275098Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.72:2379"} {"level":"warn","ts":"2026-04-17T05:57:40.554555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.213095ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T05:57:40.554816Z","caller":"traceutil/trace.go:171","msg":"trace[1021071384] transaction","detail":"{read_only:false; response_revision:226; number_of_response:1; }","duration":"346.348299ms","start":"2026-04-17T05:57:40.208393Z","end":"2026-04-17T05:57:40.554741Z","steps":["trace[1021071384] 'process raft request' (duration: 158.150333ms)","trace[1021071384] 'compare' (duration: 187.017964ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:57:40.554961Z","caller":"traceutil/trace.go:171","msg":"trace[243731528] transaction","detail":"{read_only:false; number_of_response:0; response_revision:226; }","duration":"194.296609ms","start":"2026-04-17T05:57:40.360648Z","end":"2026-04-17T05:57:40.554944Z","steps":["trace[243731528] 'process raft request' (duration: 194.237769ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:57:40.554944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:57:40.208361Z","time spent":"346.536531ms","remote":"127.0.0.1:50158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":164,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T05:57:40.554937Z","caller":"traceutil/trace.go:171","msg":"trace[547053428] linearizableReadLoop","detail":"{readStateIndex:231; appliedIndex:230; }","duration":"292.857813ms","start":"2026-04-17T05:57:40.262042Z","end":"2026-04-17T05:57:40.5549Z","steps":["trace[547053428] 'read index received' (duration: 104.518995ms)","trace[547053428] 'applied index is now lower than readState.Index' (duration: 188.335858ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:57:40.555461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.334198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-17T05:57:40.555555Z","caller":"traceutil/trace.go:171","msg":"trace[1882997819] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:226; }","duration":"293.500159ms","start":"2026-04-17T05:57:40.262032Z","end":"2026-04-17T05:57:40.555533Z","steps":["trace[1882997819] 'agreement among raft nodes before linearized reading' (duration: 293.080355ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:57:40.564524Z","caller":"traceutil/trace.go:171","msg":"trace[345749599] transaction","detail":"{read_only:false; number_of_response:0; response_revision:226; }","duration":"184.734929ms","start":"2026-04-17T05:57:40.379765Z","end":"2026-04-17T05:57:40.564499Z","steps":["trace[345749599] 'process raft request' (duration: 184.552487ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:57:40.564648Z","caller":"traceutil/trace.go:171","msg":"trace[1506352678] transaction","detail":"{read_only:false; number_of_response:0; response_revision:227; }","duration":"183.945981ms","start":"2026-04-17T05:57:40.380665Z","end":"2026-04-17T05:57:40.564611Z","steps":["trace[1506352678] 'process raft request' (duration: 183.83419ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:57:40.564665Z","caller":"traceutil/trace.go:171","msg":"trace[1564036797] transaction","detail":"{read_only:false; number_of_response:0; response_revision:227; }","duration":"183.413285ms","start":"2026-04-17T05:57:40.381232Z","end":"2026-04-17T05:57:40.564645Z","steps":["trace[1564036797] 'process raft request' (duration: 183.304893ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:57:40.564626Z","caller":"traceutil/trace.go:171","msg":"trace[1565426793] transaction","detail":"{read_only:false; number_of_response:0; response_revision:227; }","duration":"183.073042ms","start":"2026-04-17T05:57:40.381519Z","end":"2026-04-17T05:57:40.564592Z","steps":["trace[1565426793] 'process raft request' (duration: 183.043252ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:57:40.564663Z","caller":"traceutil/trace.go:171","msg":"trace[161840914] transaction","detail":"{read_only:false; response_revision:227; number_of_response:1; }","duration":"184.415956ms","start":"2026-04-17T05:57:40.380234Z","end":"2026-04-17T05:57:40.56465Z","steps":["trace[161840914] 'process raft request' (duration: 184.177623ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:57:40.565124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.497203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-04-17T05:57:40.565158Z","caller":"traceutil/trace.go:171","msg":"trace[910028145] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:1; response_revision:227; }","duration":"302.591404ms","start":"2026-04-17T05:57:40.262558Z","end":"2026-04-17T05:57:40.565149Z","steps":["trace[910028145] 'agreement among raft nodes before linearized reading' (duration: 302.312621ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:57:40.565179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:57:40.26253Z","time spent":"302.642844ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"info","ts":"2026-04-17T05:57:41.398151Z","caller":"traceutil/trace.go:171","msg":"trace[971581847] transaction","detail":"{read_only:false; number_of_response:0; response_revision:262; }","duration":"100.574875ms","start":"2026-04-17T05:57:41.297549Z","end":"2026-04-17T05:57:41.398124Z","steps":["trace[971581847] 'process raft request' (duration: 100.14823ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:57:41.398175Z","caller":"traceutil/trace.go:171","msg":"trace[8770403] transaction","detail":"{read_only:false; number_of_response:0; response_revision:262; }","duration":"101.305633ms","start":"2026-04-17T05:57:41.296828Z","end":"2026-04-17T05:57:41.398133Z","steps":["trace[8770403] 'process raft request' (duration: 100.763176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:57:46.100019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.579143ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T05:57:46.100222Z","caller":"traceutil/trace.go:171","msg":"trace[1249684777] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"260.82158ms","start":"2026-04-17T05:57:45.839356Z","end":"2026-04-17T05:57:46.100178Z","steps":["trace[1249684777] 'process raft request' (duration: 114.978184ms)","trace[1249684777] 'compare' (duration: 145.181609ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:57:46.145217Z","caller":"traceutil/trace.go:171","msg":"trace[913002863] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"302.22278ms","start":"2026-04-17T05:57:45.842951Z","end":"2026-04-17T05:57:46.145174Z","steps":["trace[913002863] 'process raft request' (duration: 301.805406ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:57:46.145476Z","caller":"traceutil/trace.go:171","msg":"trace[1817893939] linearizableReadLoop","detail":"{readStateIndex:325; appliedIndex:320; }","duration":"279.779187ms","start":"2026-04-17T05:57:45.865665Z","end":"2026-04-17T05:57:46.145444Z","steps":["trace[1817893939] 'read index received' (duration: 88.603861ms)","trace[1817893939] 'applied index is now lower than readState.Index' (duration: 191.174036ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:57:46.145642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:57:45.842919Z","time spent":"302.398743ms","remote":"127.0.0.1:50316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T05:57:46.145974Z","caller":"traceutil/trace.go:171","msg":"trace[1910438217] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"301.637283ms","start":"2026-04-17T05:57:45.844321Z","end":"2026-04-17T05:57:46.145958Z","steps":["trace[1910438217] 'process raft request' (duration: 300.907656ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:57:46.145977Z","caller":"traceutil/trace.go:171","msg":"trace[1411515899] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"298.329179ms","start":"2026-04-17T05:57:45.847625Z","end":"2026-04-17T05:57:46.145955Z","steps":["trace[1411515899] 'process raft request' (duration: 297.726753ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:57:46.146038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:57:45.844309Z","time spent":"301.692644ms","remote":"127.0.0.1:50316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T05:57:46.146204Z","caller":"traceutil/trace.go:171","msg":"trace[1451885247] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"301.796726ms","start":"2026-04-17T05:57:45.844396Z","end":"2026-04-17T05:57:46.146193Z","steps":["trace[1451885247] 'process raft request' (duration: 300.902066ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:57:46.146258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:57:45.844389Z","time spent":"301.838717ms","remote":"127.0.0.1:50316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T05:57:46.1462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.515156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4202"} {"level":"info","ts":"2026-04-17T05:57:46.146427Z","caller":"traceutil/trace.go:171","msg":"trace[26548459] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:310; }","duration":"280.804609ms","start":"2026-04-17T05:57:45.865611Z","end":"2026-04-17T05:57:46.146415Z","steps":["trace[26548459] 'agreement among raft nodes before linearized reading' (duration: 280.527636ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:57:46.446558Z","caller":"traceutil/trace.go:171","msg":"trace[480062994] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"342.169955ms","start":"2026-04-17T05:57:46.104349Z","end":"2026-04-17T05:57:46.446519Z","steps":["trace[480062994] 'process raft request' (duration: 338.448396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:57:46.446733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:57:46.104326Z","time spent":"342.314207ms","remote":"127.0.0.1:50144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3424,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T05:57:46.447447Z","caller":"traceutil/trace.go:171","msg":"trace[553667003] linearizableReadLoop","detail":"{readStateIndex:328; appliedIndex:325; }","duration":"301.859606ms","start":"2026-04-17T05:57:46.145559Z","end":"2026-04-17T05:57:46.447419Z","steps":["trace[553667003] 'read index received' (duration: 297.39117ms)","trace[553667003] 'applied index is now lower than readState.Index' (duration: 4.467376ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:57:46.447562Z","caller":"traceutil/trace.go:171","msg":"trace[1441709587] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"342.993864ms","start":"2026-04-17T05:57:46.104557Z","end":"2026-04-17T05:57:46.447551Z","steps":["trace[1441709587] 'process raft request' (duration: 341.776382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:57:46.447724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:57:46.10455Z","time spent":"343.084935ms","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":669,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T05:57:46.448039Z","caller":"traceutil/trace.go:171","msg":"trace[2017340941] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"341.386308ms","start":"2026-04-17T05:57:46.106645Z","end":"2026-04-17T05:57:46.448031Z","steps":["trace[2017340941] 'process raft request' (duration: 340.697191ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:57:46.448116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:57:46.106636Z","time spent":"341.436078ms","remote":"127.0.0.1:50458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2886,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T05:57:46.448257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.667544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-04-17T05:57:46.448279Z","caller":"traceutil/trace.go:171","msg":"trace[1493727937] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:313; }","duration":"325.734484ms","start":"2026-04-17T05:57:46.122537Z","end":"2026-04-17T05:57:46.448272Z","steps":["trace[1493727937] 'agreement among raft nodes before linearized reading' (duration: 325.679943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:57:46.448304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:57:46.122516Z","time spent":"325.782974ms","remote":"127.0.0.1:50158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-04-17T05:57:46.448454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.582953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5326"} {"level":"info","ts":"2026-04-17T05:57:46.448472Z","caller":"traceutil/trace.go:171","msg":"trace[1716935823] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:313; }","duration":"582.616054ms","start":"2026-04-17T05:57:45.86585Z","end":"2026-04-17T05:57:46.448466Z","steps":["trace[1716935823] 'agreement among raft nodes before linearized reading' (duration: 582.549813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:57:46.448471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.561933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"info","ts":"2026-04-17T05:57:46.448532Z","caller":"traceutil/trace.go:171","msg":"trace[126115734] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:313; }","duration":"582.634313ms","start":"2026-04-17T05:57:45.865882Z","end":"2026-04-17T05:57:46.448517Z","steps":["trace[126115734] 'agreement among raft nodes before linearized reading' (duration: 582.514362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:57:46.448567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:57:45.865874Z","time spent":"582.686914ms","remote":"127.0.0.1:50144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4344,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-04-17T05:57:46.448577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.730995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7294"} {"level":"info","ts":"2026-04-17T05:57:46.448663Z","caller":"traceutil/trace.go:171","msg":"trace[1574320015] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:313; }","duration":"582.904678ms","start":"2026-04-17T05:57:45.865735Z","end":"2026-04-17T05:57:46.44864Z","steps":["trace[1574320015] 'agreement among raft nodes before linearized reading' (duration: 582.756335ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:57:46.448709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:57:45.865713Z","time spent":"582.986528ms","remote":"127.0.0.1:50144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7316,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-04-17T05:57:46.448717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.795863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-04-17T05:57:46.448723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.241018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-04-17T05:57:46.448755Z","caller":"traceutil/trace.go:171","msg":"trace[296448962] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:313; }","duration":"525.903775ms","start":"2026-04-17T05:57:45.922838Z","end":"2026-04-17T05:57:46.448742Z","steps":["trace[296448962] 'agreement among raft nodes before linearized reading' (duration: 525.846084ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:57:46.448756Z","caller":"traceutil/trace.go:171","msg":"trace[493555354] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:313; }","duration":"578.287489ms","start":"2026-04-17T05:57:45.870458Z","end":"2026-04-17T05:57:46.448745Z","steps":["trace[493555354] 'agreement among raft nodes before linearized reading' (duration: 578.231078ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:57:46.448778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:57:45.922811Z","time spent":"525.961855ms","remote":"127.0.0.1:50158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-04-17T05:57:46.448788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:57:45.870451Z","time spent":"578.326909ms","remote":"127.0.0.1:50158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":219,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-04-17T05:57:46.448889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.970908ms","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-04-17T05:57:46.448913Z","caller":"traceutil/trace.go:171","msg":"trace[27628118] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:313; }","duration":"354.046399ms","start":"2026-04-17T05:57:46.094859Z","end":"2026-04-17T05:57:46.448905Z","steps":["trace[27628118] 'agreement among raft nodes before linearized reading' (duration: 353.994679ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:57:46.448919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.131199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4659"} {"level":"warn","ts":"2026-04-17T05:57:46.448936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.032579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6961"} {"level":"info","ts":"2026-04-17T05:57:46.448952Z","caller":"traceutil/trace.go:171","msg":"trace[1628802030] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:313; }","duration":"583.17251ms","start":"2026-04-17T05:57:45.86577Z","end":"2026-04-17T05:57:46.448942Z","steps":["trace[1628802030] 'agreement among raft nodes before linearized reading' (duration: 583.113959ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:57:46.448963Z","caller":"traceutil/trace.go:171","msg":"trace[1016837842] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:313; }","duration":"583.070889ms","start":"2026-04-17T05:57:45.865885Z","end":"2026-04-17T05:57:46.448956Z","steps":["trace[1016837842] 'agreement among raft nodes before linearized reading' (duration: 583.016588ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:57:46.448988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:57:45.865761Z","time spent":"583.219401ms","remote":"127.0.0.1:50144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":4681,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-04-17T05:57:46.448991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:57:45.865877Z","time spent":"583.108369ms","remote":"127.0.0.1:50144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6983,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-04-17T05:57:46.448497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:57:45.865839Z","time spent":"582.648544ms","remote":"127.0.0.1:50144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5348,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-04-17T05:57:46.449393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.613213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"warn","ts":"2026-04-17T05:57:46.44946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.665664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-17T05:57:46.44948Z","caller":"traceutil/trace.go:171","msg":"trace[543958142] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:313; }","duration":"477.761314ms","start":"2026-04-17T05:57:45.971696Z","end":"2026-04-17T05:57:46.449458Z","steps":["trace[543958142] 'agreement among raft nodes before linearized reading' (duration: 477.585102ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:57:46.449497Z","caller":"traceutil/trace.go:171","msg":"trace[999621064] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:313; }","duration":"427.741094ms","start":"2026-04-17T05:57:46.021746Z","end":"2026-04-17T05:57:46.449488Z","steps":["trace[999621064] 'agreement among raft nodes before linearized reading' (duration: 427.677943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:57:46.449519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:57:45.971664Z","time spent":"477.847185ms","remote":"127.0.0.1:50158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":236,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-04-17T05:57:46.449526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:57:46.021716Z","time spent":"427.804415ms","remote":"127.0.0.1:50158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-04-17T05:57:46.453288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:57:46.094834Z","time spent":"358.418404ms","remote":"127.0.0.1:50158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":240,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"warn","ts":"2026-04-17T05:57:46.453836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.649212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:1 size:2108"} {"level":"info","ts":"2026-04-17T05:57:46.453879Z","caller":"traceutil/trace.go:171","msg":"trace[1444443613] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:1; response_revision:315; }","duration":"294.732332ms","start":"2026-04-17T05:57:46.159135Z","end":"2026-04-17T05:57:46.453867Z","steps":["trace[1444443613] 'agreement among raft nodes before linearized reading' (duration: 294.633651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:57:46.4543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.169062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-17T05:57:46.454357Z","caller":"traceutil/trace.go:171","msg":"trace[1081215640] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:315; }","duration":"181.269064ms","start":"2026-04-17T05:57:46.273072Z","end":"2026-04-17T05:57:46.454341Z","steps":["trace[1081215640] 'agreement among raft nodes before linearized reading' (duration: 181.162183ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:57:46.454599Z","caller":"traceutil/trace.go:171","msg":"trace[207963786] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"299.246169ms","start":"2026-04-17T05:57:46.15534Z","end":"2026-04-17T05:57:46.454586Z","steps":["trace[207963786] 'process raft request' (duration: 298.323779ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:57:46.45461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.517665ms","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-04-17T05:57:46.454638Z","caller":"traceutil/trace.go:171","msg":"trace[144867003] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:315; }","duration":"282.566266ms","start":"2026-04-17T05:57:46.172064Z","end":"2026-04-17T05:57:46.45463Z","steps":["trace[144867003] 'agreement among raft nodes before linearized reading' (duration: 282.510165ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:57:46.454727Z","caller":"traceutil/trace.go:171","msg":"trace[1773117825] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"299.186228ms","start":"2026-04-17T05:57:46.155531Z","end":"2026-04-17T05:57:46.454717Z","steps":["trace[1773117825] 'process raft request' (duration: 298.200438ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:57:46.454799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.892529ms","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-04-17T05:57:46.454827Z","caller":"traceutil/trace.go:171","msg":"trace[824400676] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:315; }","duration":"231.951599ms","start":"2026-04-17T05:57:46.222866Z","end":"2026-04-17T05:57:46.454817Z","steps":["trace[824400676] 'agreement among raft nodes before linearized reading' (duration: 231.873569ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:57:54.875518Z","caller":"traceutil/trace.go:171","msg":"trace[454524553] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"205.533666ms","start":"2026-04-17T05:57:54.669958Z","end":"2026-04-17T05:57:54.875491Z","steps":["trace[454524553] 'process raft request' (duration: 205.251993ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:57:55.018138Z","caller":"traceutil/trace.go:171","msg":"trace[679676611] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"137.775822ms","start":"2026-04-17T05:57:54.880311Z","end":"2026-04-17T05:57:55.018087Z","steps":["trace[679676611] 'process raft request' (duration: 88.126246ms)","trace[679676611] 'compare' (duration: 49.462194ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:58:04.733914Z","caller":"traceutil/trace.go:171","msg":"trace[243592002] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"289.134738ms","start":"2026-04-17T05:58:04.444748Z","end":"2026-04-17T05:58:04.733883Z","steps":["trace[243592002] 'process raft request' (duration: 283.112769ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:04.744332Z","caller":"traceutil/trace.go:171","msg":"trace[78453730] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:541; }","duration":"234.309351ms","start":"2026-04-17T05:58:04.509955Z","end":"2026-04-17T05:58:04.744264Z","steps":["trace[78453730] 'read index received' (duration: 217.955897ms)","trace[78453730] 'applied index is now lower than readState.Index' (duration: 16.351374ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:58:04.744517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.560841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T05:58:04.744566Z","caller":"traceutil/trace.go:171","msg":"trace[272673495] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:519; }","duration":"234.617343ms","start":"2026-04-17T05:58:04.509927Z","end":"2026-04-17T05:58:04.744544Z","steps":["trace[272673495] 'agreement among raft nodes before linearized reading' (duration: 234.470618ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:04.746922Z","caller":"traceutil/trace.go:171","msg":"trace[1826079319] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"271.428482ms","start":"2026-04-17T05:58:04.475473Z","end":"2026-04-17T05:58:04.746901Z","steps":["trace[1826079319] 'process raft request' (duration: 264.902345ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:04.747007Z","caller":"traceutil/trace.go:171","msg":"trace[1776402604] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"289.789982ms","start":"2026-04-17T05:58:04.457153Z","end":"2026-04-17T05:58:04.746943Z","steps":["trace[1776402604] 'process raft request' (duration: 283.026996ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:05.219421Z","caller":"traceutil/trace.go:171","msg":"trace[97410744] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"272.120959ms","start":"2026-04-17T05:58:04.947265Z","end":"2026-04-17T05:58:05.219386Z","steps":["trace[97410744] 'process raft request' (duration: 267.648965ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:05.223618Z","caller":"traceutil/trace.go:171","msg":"trace[1849515003] linearizableReadLoop","detail":"{readStateIndex:551; appliedIndex:549; }","duration":"199.259857ms","start":"2026-04-17T05:58:05.024332Z","end":"2026-04-17T05:58:05.223592Z","steps":["trace[1849515003] 'read index received' (duration: 190.595941ms)","trace[1849515003] 'applied index is now lower than readState.Index' (duration: 8.662736ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:58:05.235459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.088617ms","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-04-17T05:58:05.235613Z","caller":"traceutil/trace.go:171","msg":"trace[2096281977] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:527; }","duration":"211.302505ms","start":"2026-04-17T05:58:05.024278Z","end":"2026-04-17T05:58:05.23558Z","steps":["trace[2096281977] 'agreement among raft nodes before linearized reading' (duration: 211.022965ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:05.235676Z","caller":"traceutil/trace.go:171","msg":"trace[1472358693] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"246.993313ms","start":"2026-04-17T05:58:04.988643Z","end":"2026-04-17T05:58:05.235636Z","steps":["trace[1472358693] 'process raft request' (duration: 234.182786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:58:05.236377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.9271ms","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-04-17T05:58:05.236419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.373914ms","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-04-17T05:58:05.236475Z","caller":"traceutil/trace.go:171","msg":"trace[1725258933] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:527; }","duration":"170.476028ms","start":"2026-04-17T05:58:05.065985Z","end":"2026-04-17T05:58:05.236461Z","steps":["trace[1725258933] 'agreement among raft nodes before linearized reading' (duration: 170.410966ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:05.236494Z","caller":"traceutil/trace.go:171","msg":"trace[382735683] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:527; }","duration":"200.293263ms","start":"2026-04-17T05:58:05.036165Z","end":"2026-04-17T05:58:05.236458Z","steps":["trace[382735683] 'agreement among raft nodes before linearized reading' (duration: 199.895739ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:08.817734Z","caller":"traceutil/trace.go:171","msg":"trace[646075107] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"141.926708ms","start":"2026-04-17T05:58:08.675779Z","end":"2026-04-17T05:58:08.817706Z","steps":["trace[646075107] 'process raft request' (duration: 138.519233ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:09.333259Z","caller":"traceutil/trace.go:171","msg":"trace[1948997176] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"455.563779ms","start":"2026-04-17T05:58:08.877659Z","end":"2026-04-17T05:58:09.333223Z","steps":["trace[1948997176] 'process raft request' (duration: 455.282088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:58:09.333464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:58:08.877621Z","time spent":"455.755395ms","remote":"127.0.0.1:50304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1044,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T05:58:09.443873Z","caller":"traceutil/trace.go:171","msg":"trace[871139173] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"103.328952ms","start":"2026-04-17T05:58:09.340496Z","end":"2026-04-17T05:58:09.443825Z","steps":["trace[871139173] 'process raft request' (duration: 76.777926ms)","trace[871139173] 'compare' (duration: 26.287117ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:58:09.443873Z","caller":"traceutil/trace.go:171","msg":"trace[1033604668] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"102.301624ms","start":"2026-04-17T05:58:09.341542Z","end":"2026-04-17T05:58:09.443844Z","steps":["trace[1033604668] 'process raft request' (duration: 102.18344ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:09.444062Z","caller":"traceutil/trace.go:171","msg":"trace[528313345] linearizableReadLoop","detail":"{readStateIndex:589; appliedIndex:585; }","duration":"100.435957ms","start":"2026-04-17T05:58:09.343556Z","end":"2026-04-17T05:58:09.443992Z","steps":["trace[528313345] 'read index received' (duration: 60.531945ms)","trace[528313345] 'applied index is now lower than readState.Index' (duration: 39.901602ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:58:09.444183Z","caller":"traceutil/trace.go:171","msg":"trace[940574950] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"102.501802ms","start":"2026-04-17T05:58:09.34166Z","end":"2026-04-17T05:58:09.444162Z","steps":["trace[940574950] 'process raft request' (duration: 102.129388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:58:09.444292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.669215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T05:58:09.444392Z","caller":"traceutil/trace.go:171","msg":"trace[1327812843] range","detail":"{range_begin:/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:0; response_revision:563; }","duration":"100.851112ms","start":"2026-04-17T05:58:09.343518Z","end":"2026-04-17T05:58:09.444369Z","steps":["trace[1327812843] 'agreement among raft nodes before linearized reading' (duration: 100.648265ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:10.377748Z","caller":"traceutil/trace.go:171","msg":"trace[812957656] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"510.26072ms","start":"2026-04-17T05:58:09.867458Z","end":"2026-04-17T05:58:10.377718Z","steps":["trace[812957656] 'process raft request' (duration: 509.557335ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:58:10.377942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:58:09.867416Z","time spent":"510.426317ms","remote":"127.0.0.1:50044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036117,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T05:58:11.555123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.753187ms","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-04-17T05:58:11.555172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.717386ms","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-04-17T05:58:11.555254Z","caller":"traceutil/trace.go:171","msg":"trace[2052156039] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:569; }","duration":"106.937464ms","start":"2026-04-17T05:58:11.44829Z","end":"2026-04-17T05:58:11.555227Z","steps":["trace[2052156039] 'range keys from in-memory index tree' (duration: 106.476987ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:11.555268Z","caller":"traceutil/trace.go:171","msg":"trace[610271697] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:569; }","duration":"106.849ms","start":"2026-04-17T05:58:11.448391Z","end":"2026-04-17T05:58:11.55524Z","steps":["trace[610271697] 'range keys from in-memory index tree' (duration: 106.54402ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:11.563377Z","caller":"traceutil/trace.go:171","msg":"trace[1774945432] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:595; }","duration":"114.778389ms","start":"2026-04-17T05:58:11.44858Z","end":"2026-04-17T05:58:11.563358Z","steps":["trace[1774945432] 'read index received' (duration: 113.793343ms)","trace[1774945432] 'applied index is now lower than readState.Index' (duration: 983.976µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:58:11.563418Z","caller":"traceutil/trace.go:171","msg":"trace[75124111] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"115.154423ms","start":"2026-04-17T05:58:11.448243Z","end":"2026-04-17T05:58:11.563398Z","steps":["trace[75124111] 'process raft request' (duration: 114.370793ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:58:11.56366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.05215ms","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-04-17T05:58:11.563742Z","caller":"traceutil/trace.go:171","msg":"trace[1454624198] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:570; }","duration":"115.155653ms","start":"2026-04-17T05:58:11.448568Z","end":"2026-04-17T05:58:11.563724Z","steps":["trace[1454624198] 'agreement among raft nodes before linearized reading' (duration: 114.903874ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:12.126049Z","caller":"traceutil/trace.go:171","msg":"trace[863378533] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"501.395406ms","start":"2026-04-17T05:58:11.624622Z","end":"2026-04-17T05:58:12.126017Z","steps":["trace[863378533] 'process raft request' (duration: 497.774265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:58:12.126224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:58:11.624594Z","time spent":"501.548084ms","remote":"127.0.0.1:50480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T05:58:12.906681Z","caller":"traceutil/trace.go:171","msg":"trace[201418213] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"344.38648ms","start":"2026-04-17T05:58:12.562261Z","end":"2026-04-17T05:58:12.906648Z","steps":["trace[201418213] 'process raft request' (duration: 343.639683ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:58:12.906941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:58:12.562226Z","time spent":"344.605298ms","remote":"127.0.0.1:50044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036205,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T05:58:13.164256Z","caller":"traceutil/trace.go:171","msg":"trace[1501554969] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"148.052872ms","start":"2026-04-17T05:58:13.01616Z","end":"2026-04-17T05:58:13.164213Z","steps":["trace[1501554969] 'process raft request' (duration: 147.746641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:58:13.543867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.348697ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T05:58:13.544006Z","caller":"traceutil/trace.go:171","msg":"trace[408885955] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"125.21171ms","start":"2026-04-17T05:58:13.418772Z","end":"2026-04-17T05:58:13.543984Z","steps":["trace[408885955] 'read index received' (duration: 97.574µs)","trace[408885955] 'applied index is now lower than readState.Index' (duration: 125.112756ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:58:13.544056Z","caller":"traceutil/trace.go:171","msg":"trace[1959769535] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"361.178332ms","start":"2026-04-17T05:58:13.182836Z","end":"2026-04-17T05:58:13.544014Z","steps":["trace[1959769535] 'process raft request' (duration: 230.592126ms)","trace[1959769535] 'compare' (duration: 130.091687ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:58:13.544257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:58:13.182817Z","time spent":"361.342828ms","remote":"127.0.0.1:50144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T05:58:13.544369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.602763ms","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-04-17T05:58:13.544468Z","caller":"traceutil/trace.go:171","msg":"trace[753339270] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:577; }","duration":"125.709717ms","start":"2026-04-17T05:58:13.418731Z","end":"2026-04-17T05:58:13.54444Z","steps":["trace[753339270] 'agreement among raft nodes before linearized reading' (duration: 125.306723ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:58:13.780982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.95407ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T05:58:13.781414Z","caller":"traceutil/trace.go:171","msg":"trace[2132907429] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:603; }","duration":"207.917821ms","start":"2026-04-17T05:58:13.573477Z","end":"2026-04-17T05:58:13.781395Z","steps":["trace[2132907429] 'read index received' (duration: 78.765138ms)","trace[2132907429] 'applied index is now lower than readState.Index' (duration: 129.151653ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:58:13.781506Z","caller":"traceutil/trace.go:171","msg":"trace[1931312308] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"231.880723ms","start":"2026-04-17T05:58:13.549614Z","end":"2026-04-17T05:58:13.781494Z","steps":["trace[1931312308] 'process raft request' (duration: 102.651198ms)","trace[1931312308] 'compare' (duration: 127.743292ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:58:13.781716Z","caller":"traceutil/trace.go:171","msg":"trace[1851980668] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"231.716957ms","start":"2026-04-17T05:58:13.54996Z","end":"2026-04-17T05:58:13.781677Z","steps":["trace[1851980668] 'process raft request' (duration: 231.158017ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:13.781765Z","caller":"traceutil/trace.go:171","msg":"trace[715413424] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"230.733993ms","start":"2026-04-17T05:58:13.550996Z","end":"2026-04-17T05:58:13.78173Z","steps":["trace[715413424] 'process raft request' (duration: 230.304686ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:58:13.78272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.224789ms","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-04-17T05:58:13.782914Z","caller":"traceutil/trace.go:171","msg":"trace[1679838068] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:582; }","duration":"209.453118ms","start":"2026-04-17T05:58:13.573434Z","end":"2026-04-17T05:58:13.782888Z","steps":["trace[1679838068] 'agreement among raft nodes before linearized reading' (duration: 209.067834ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:13.783102Z","caller":"traceutil/trace.go:171","msg":"trace[435740034] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"231.400677ms","start":"2026-04-17T05:58:13.551679Z","end":"2026-04-17T05:58:13.78308Z","steps":["trace[435740034] 'process raft request' (duration: 229.667793ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:13.783539Z","caller":"traceutil/trace.go:171","msg":"trace[1970341944] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"233.535623ms","start":"2026-04-17T05:58:13.549986Z","end":"2026-04-17T05:58:13.783521Z","steps":["trace[1970341944] 'process raft request' (duration: 231.24786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:58:13.950104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.469839ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-17T05:58:13.951086Z","caller":"traceutil/trace.go:171","msg":"trace[60149669] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:608; }","duration":"169.605506ms","start":"2026-04-17T05:58:13.781457Z","end":"2026-04-17T05:58:13.951062Z","steps":["trace[60149669] 'read index received' (duration: 4.0974ms)","trace[60149669] 'applied index is now lower than readState.Index' (duration: 165.505836ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:58:13.950972Z","caller":"traceutil/trace.go:171","msg":"trace[523199504] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"161.336685ms","start":"2026-04-17T05:58:13.789613Z","end":"2026-04-17T05:58:13.950949Z","steps":["trace[523199504] 'process raft request' (duration: 161.113227ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:58:13.951347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.61213ms","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-04-17T05:58:13.951311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.409724ms","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-04-17T05:58:13.951393Z","caller":"traceutil/trace.go:171","msg":"trace[824771619] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:583; }","duration":"377.701624ms","start":"2026-04-17T05:58:13.57368Z","end":"2026-04-17T05:58:13.951382Z","steps":["trace[824771619] 'agreement among raft nodes before linearized reading' (duration: 377.468955ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:58:13.951438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:58:13.57367Z","time spent":"377.759215ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-17T05:58:13.951436Z","caller":"traceutil/trace.go:171","msg":"trace[1386246299] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:583; }","duration":"360.544539ms","start":"2026-04-17T05:58:13.590863Z","end":"2026-04-17T05:58:13.951407Z","steps":["trace[1386246299] 'agreement among raft nodes before linearized reading' (duration: 360.206786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:58:13.951495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:58:13.590828Z","time spent":"360.656672ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-17T05:58:14.33458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.503676ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T05:58:14.334714Z","caller":"traceutil/trace.go:171","msg":"trace[830799981] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"376.659446ms","start":"2026-04-17T05:58:13.958031Z","end":"2026-04-17T05:58:14.33469Z","steps":["trace[830799981] 'process raft request' (duration: 235.928232ms)","trace[830799981] 'compare' (duration: 140.33ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:58:14.334833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:58:13.958008Z","time spent":"376.79138ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T05:58:16.094214Z","caller":"traceutil/trace.go:171","msg":"trace[1399356850] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:614; }","duration":"123.59298ms","start":"2026-04-17T05:58:15.97059Z","end":"2026-04-17T05:58:16.094183Z","steps":["trace[1399356850] 'read index received' (duration: 123.375242ms)","trace[1399356850] 'applied index is now lower than readState.Index' (duration: 215.718µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:58:16.09438Z","caller":"traceutil/trace.go:171","msg":"trace[1204930896] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"132.669151ms","start":"2026-04-17T05:58:15.961667Z","end":"2026-04-17T05:58:16.094336Z","steps":["trace[1204930896] 'process raft request' (duration: 132.300637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:58:16.094477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.85795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T05:58:16.094657Z","caller":"traceutil/trace.go:171","msg":"trace[1161512430] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:588; }","duration":"124.080148ms","start":"2026-04-17T05:58:15.970546Z","end":"2026-04-17T05:58:16.094627Z","steps":["trace[1161512430] 'agreement among raft nodes before linearized reading' (duration: 123.826159ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:58:16.094983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.320936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T05:58:16.095266Z","caller":"traceutil/trace.go:171","msg":"trace[1300543888] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:588; }","duration":"124.660489ms","start":"2026-04-17T05:58:15.970582Z","end":"2026-04-17T05:58:16.095242Z","steps":["trace[1300543888] 'agreement among raft nodes before linearized reading' (duration: 124.112209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:58:16.09528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.360819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T05:58:16.103832Z","caller":"traceutil/trace.go:171","msg":"trace[1759185101] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:588; }","duration":"115.937321ms","start":"2026-04-17T05:58:15.987867Z","end":"2026-04-17T05:58:16.103805Z","steps":["trace[1759185101] 'agreement among raft nodes before linearized reading' (duration: 107.355658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:58:16.095267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.678254ms","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-04-17T05:58:16.09533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.411211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T05:58:16.103918Z","caller":"traceutil/trace.go:171","msg":"trace[1403132729] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:588; }","duration":"115.35631ms","start":"2026-04-17T05:58:15.988544Z","end":"2026-04-17T05:58:16.1039Z","steps":["trace[1403132729] 'agreement among raft nodes before linearized reading' (duration: 106.532989ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:16.103998Z","caller":"traceutil/trace.go:171","msg":"trace[811104692] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:588; }","duration":"116.096887ms","start":"2026-04-17T05:58:15.987882Z","end":"2026-04-17T05:58:16.103979Z","steps":["trace[811104692] 'agreement among raft nodes before linearized reading' (duration: 107.424021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:58:16.095335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.949065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T05:58:16.104196Z","caller":"traceutil/trace.go:171","msg":"trace[1571762462] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:588; }","duration":"115.863359ms","start":"2026-04-17T05:58:15.988315Z","end":"2026-04-17T05:58:16.104179Z","steps":["trace[1571762462] 'agreement among raft nodes before linearized reading' (duration: 106.898262ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:58:16.0954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.368623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T05:58:16.104294Z","caller":"traceutil/trace.go:171","msg":"trace[935121565] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:588; }","duration":"132.34693ms","start":"2026-04-17T05:58:15.971936Z","end":"2026-04-17T05:58:16.104283Z","steps":["trace[935121565] 'agreement among raft nodes before linearized reading' (duration: 123.428845ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:58:18.201705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.564588ms","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-04-17T05:58:18.20181Z","caller":"traceutil/trace.go:171","msg":"trace[840866603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:602; }","duration":"101.754995ms","start":"2026-04-17T05:58:18.100029Z","end":"2026-04-17T05:58:18.201784Z","steps":["trace[840866603] 'range keys from in-memory index tree' (duration: 101.297158ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:18.360241Z","caller":"traceutil/trace.go:171","msg":"trace[1373663102] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"153.698367ms","start":"2026-04-17T05:58:18.206509Z","end":"2026-04-17T05:58:18.360208Z","steps":["trace[1373663102] 'process raft request' (duration: 153.358965ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:18.551378Z","caller":"traceutil/trace.go:171","msg":"trace[1531192840] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:631; }","duration":"124.960831ms","start":"2026-04-17T05:58:18.426381Z","end":"2026-04-17T05:58:18.551342Z","steps":["trace[1531192840] 'read index received' (duration: 124.731483ms)","trace[1531192840] 'applied index is now lower than readState.Index' (duration: 228.058µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:58:18.551462Z","caller":"traceutil/trace.go:171","msg":"trace[1890702631] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"148.209627ms","start":"2026-04-17T05:58:18.40322Z","end":"2026-04-17T05:58:18.55143Z","steps":["trace[1890702631] 'process raft request' (duration: 147.879745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:58:18.551686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.273722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-17T05:58:18.551738Z","caller":"traceutil/trace.go:171","msg":"trace[1844473661] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:605; }","duration":"125.369476ms","start":"2026-04-17T05:58:18.426356Z","end":"2026-04-17T05:58:18.551725Z","steps":["trace[1844473661] 'agreement among raft nodes before linearized reading' (duration: 125.160038ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:19.023729Z","caller":"traceutil/trace.go:171","msg":"trace[411391211] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:633; }","duration":"440.613035ms","start":"2026-04-17T05:58:18.583077Z","end":"2026-04-17T05:58:19.02369Z","steps":["trace[411391211] 'read index received' (duration: 429.379806ms)","trace[411391211] 'applied index is now lower than readState.Index' (duration: 11.231419ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:58:19.023731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:58:18.553585Z","time spent":"470.135769ms","remote":"127.0.0.1:49984","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-17T05:58:19.023873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.76507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T05:58:19.023908Z","caller":"traceutil/trace.go:171","msg":"trace[25776174] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:606; }","duration":"440.850043ms","start":"2026-04-17T05:58:18.583048Z","end":"2026-04-17T05:58:19.023898Z","steps":["trace[25776174] 'agreement among raft nodes before linearized reading' (duration: 440.75146ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:58:19.023945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:58:18.583036Z","time spent":"440.900865ms","remote":"127.0.0.1:49940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T05:58:19.024596Z","caller":"traceutil/trace.go:171","msg":"trace[94983996] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"427.05586ms","start":"2026-04-17T05:58:18.597517Z","end":"2026-04-17T05:58:19.024573Z","steps":["trace[94983996] 'process raft request' (duration: 426.858413ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:58:19.024713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:58:18.59749Z","time spent":"427.145733ms","remote":"127.0.0.1:50144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17771,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T05:58:20.484102Z","caller":"traceutil/trace.go:171","msg":"trace[1832803891] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:660; }","duration":"119.149699ms","start":"2026-04-17T05:58:20.364922Z","end":"2026-04-17T05:58:20.484071Z","steps":["trace[1832803891] 'read index received' (duration: 92.151906ms)","trace[1832803891] 'applied index is now lower than readState.Index' (duration: 26.996493ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:58:20.484229Z","caller":"traceutil/trace.go:171","msg":"trace[1076864264] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"133.578375ms","start":"2026-04-17T05:58:20.350618Z","end":"2026-04-17T05:58:20.484197Z","steps":["trace[1076864264] 'process raft request' (duration: 106.430986ms)","trace[1076864264] 'compare' (duration: 26.850538ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:58:20.484411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.46647ms","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-04-17T05:58:20.484469Z","caller":"traceutil/trace.go:171","msg":"trace[1748114812] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:633; }","duration":"119.583955ms","start":"2026-04-17T05:58:20.364857Z","end":"2026-04-17T05:58:20.484441Z","steps":["trace[1748114812] 'agreement among raft nodes before linearized reading' (duration: 119.335666ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:20.811906Z","caller":"traceutil/trace.go:171","msg":"trace[189888932] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"182.89869ms","start":"2026-04-17T05:58:20.628975Z","end":"2026-04-17T05:58:20.811874Z","steps":["trace[189888932] 'process raft request' (duration: 106.360033ms)","trace[189888932] 'compare' (duration: 76.305868ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:58:20.812147Z","caller":"traceutil/trace.go:171","msg":"trace[2082815981] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"176.31615ms","start":"2026-04-17T05:58:20.635792Z","end":"2026-04-17T05:58:20.812108Z","steps":["trace[2082815981] 'process raft request' (duration: 175.979848ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:21.332642Z","caller":"traceutil/trace.go:171","msg":"trace[1621743612] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"177.895319ms","start":"2026-04-17T05:58:21.154716Z","end":"2026-04-17T05:58:21.332611Z","steps":["trace[1621743612] 'process raft request' (duration: 177.707842ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:23.725961Z","caller":"traceutil/trace.go:171","msg":"trace[1403031542] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"185.240276ms","start":"2026-04-17T05:58:23.540689Z","end":"2026-04-17T05:58:23.725929Z","steps":["trace[1403031542] 'process raft request' (duration: 184.895203ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:23.863271Z","caller":"traceutil/trace.go:171","msg":"trace[1157390043] linearizableReadLoop","detail":"{readStateIndex:710; appliedIndex:709; }","duration":"115.165274ms","start":"2026-04-17T05:58:23.748035Z","end":"2026-04-17T05:58:23.8632Z","steps":["trace[1157390043] 'read index received' (duration: 65.89345ms)","trace[1157390043] 'applied index is now lower than readState.Index' (duration: 49.270284ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:58:23.863325Z","caller":"traceutil/trace.go:171","msg":"trace[1502671906] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"243.799548ms","start":"2026-04-17T05:58:23.619478Z","end":"2026-04-17T05:58:23.863277Z","steps":["trace[1502671906] 'process raft request' (duration: 202.338368ms)","trace[1502671906] 'compare' (duration: 41.156999ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:58:23.863555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.481325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-04-17T05:58:23.863618Z","caller":"traceutil/trace.go:171","msg":"trace[1360495168] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:682; }","duration":"115.61959ms","start":"2026-04-17T05:58:23.74798Z","end":"2026-04-17T05:58:23.8636Z","steps":["trace[1360495168] 'agreement among raft nodes before linearized reading' (duration: 115.352681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:58:23.905509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.205867ms","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-04-17T05:58:23.905597Z","caller":"traceutil/trace.go:171","msg":"trace[301344192] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:683; }","duration":"142.364943ms","start":"2026-04-17T05:58:23.763213Z","end":"2026-04-17T05:58:23.905578Z","steps":["trace[301344192] 'agreement among raft nodes before linearized reading' (duration: 142.114104ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:25.113641Z","caller":"traceutil/trace.go:171","msg":"trace[272486375] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"110.045108ms","start":"2026-04-17T05:58:25.003554Z","end":"2026-04-17T05:58:25.113599Z","steps":["trace[272486375] 'process raft request' (duration: 109.733015ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:26.612081Z","caller":"traceutil/trace.go:171","msg":"trace[1133447754] linearizableReadLoop","detail":"{readStateIndex:730; appliedIndex:729; }","duration":"216.107049ms","start":"2026-04-17T05:58:26.39594Z","end":"2026-04-17T05:58:26.612047Z","steps":["trace[1133447754] 'read index received' (duration: 211.969659ms)","trace[1133447754] 'applied index is now lower than readState.Index' (duration: 4.13629ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:58:26.612213Z","caller":"traceutil/trace.go:171","msg":"trace[1241713788] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"230.538345ms","start":"2026-04-17T05:58:26.381644Z","end":"2026-04-17T05:58:26.612182Z","steps":["trace[1241713788] 'process raft request' (duration: 226.254828ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:58:26.612371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.102522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T05:58:26.612459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.483463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-lsw55\" ","response":"range_response_count:1 size:18709"} {"level":"info","ts":"2026-04-17T05:58:26.612478Z","caller":"traceutil/trace.go:171","msg":"trace[1299541] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:700; }","duration":"104.269578ms","start":"2026-04-17T05:58:26.508188Z","end":"2026-04-17T05:58:26.612457Z","steps":["trace[1299541] 'agreement among raft nodes before linearized reading' (duration: 104.06234ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:26.61251Z","caller":"traceutil/trace.go:171","msg":"trace[1317455032] range","detail":"{range_begin:/registry/pods/kube-system/cilium-lsw55; range_end:; response_count:1; response_revision:700; }","duration":"216.595427ms","start":"2026-04-17T05:58:26.395902Z","end":"2026-04-17T05:58:26.612498Z","steps":["trace[1317455032] 'agreement among raft nodes before linearized reading' (duration: 216.324978ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:28.83978Z","caller":"traceutil/trace.go:171","msg":"trace[1590777131] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"167.565252ms","start":"2026-04-17T05:58:28.672167Z","end":"2026-04-17T05:58:28.839732Z","steps":["trace[1590777131] 'process raft request' (duration: 81.878591ms)","trace[1590777131] 'compare' (duration: 85.419301ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:58:28.839819Z","caller":"traceutil/trace.go:171","msg":"trace[522280997] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"166.606587ms","start":"2026-04-17T05:58:28.673185Z","end":"2026-04-17T05:58:28.839791Z","steps":["trace[522280997] 'process raft request' (duration: 166.455992ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:29.159514Z","caller":"traceutil/trace.go:171","msg":"trace[1797360015] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"307.748466ms","start":"2026-04-17T05:58:28.851731Z","end":"2026-04-17T05:58:29.159479Z","steps":["trace[1797360015] 'process raft request' (duration: 307.543389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:58:29.159855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:58:28.851707Z","time spent":"308.001885ms","remote":"127.0.0.1:50432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4213,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T05:58:32.559155Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680219220187253042,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T05:58:32.988039Z","caller":"traceutil/trace.go:171","msg":"trace[632705914] linearizableReadLoop","detail":"{readStateIndex:767; appliedIndex:766; }","duration":"929.862628ms","start":"2026-04-17T05:58:32.058149Z","end":"2026-04-17T05:58:32.988011Z","steps":["trace[632705914] 'read index received' (duration: 929.592638ms)","trace[632705914] 'applied index is now lower than readState.Index' (duration: 268.91µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:58:32.98818Z","caller":"traceutil/trace.go:171","msg":"trace[505853496] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"995.853092ms","start":"2026-04-17T05:58:31.992314Z","end":"2026-04-17T05:58:32.988167Z","steps":["trace[505853496] 'process raft request' (duration: 995.498599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:58:32.98834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:58:31.992266Z","time spent":"995.945366ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T05:58:32.988469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.347508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T05:58:32.988442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.794222ms","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-04-17T05:58:32.988486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"930.340686ms","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-04-17T05:58:32.988511Z","caller":"traceutil/trace.go:171","msg":"trace[28064283] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:736; }","duration":"478.431051ms","start":"2026-04-17T05:58:32.510064Z","end":"2026-04-17T05:58:32.988495Z","steps":["trace[28064283] 'agreement among raft nodes before linearized reading' (duration: 478.359629ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:32.988517Z","caller":"traceutil/trace.go:171","msg":"trace[615589920] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:736; }","duration":"543.926457ms","start":"2026-04-17T05:58:32.444577Z","end":"2026-04-17T05:58:32.988503Z","steps":["trace[615589920] 'agreement among raft nodes before linearized reading' (duration: 543.682918ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:32.988534Z","caller":"traceutil/trace.go:171","msg":"trace[1760064355] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:736; }","duration":"930.441231ms","start":"2026-04-17T05:58:32.05808Z","end":"2026-04-17T05:58:32.988521Z","steps":["trace[1760064355] 'agreement among raft nodes before linearized reading' (duration: 930.295196ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:58:32.988551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:58:32.510034Z","time spent":"478.511524ms","remote":"127.0.0.1:49924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T05:58:32.988554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:58:32.444553Z","time spent":"543.992979ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-17T05:58:32.988573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:58:32.05805Z","time spent":"930.511164ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T05:58:32.98878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.262989ms","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-04-17T05:58:32.988887Z","caller":"traceutil/trace.go:171","msg":"trace[1093751632] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:736; }","duration":"318.413684ms","start":"2026-04-17T05:58:32.670444Z","end":"2026-04-17T05:58:32.988858Z","steps":["trace[1093751632] 'agreement among raft nodes before linearized reading' (duration: 317.978919ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:58:32.988936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:58:32.67042Z","time spent":"318.506808ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-17T05:58:33.481915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.299336ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T05:58:33.482052Z","caller":"traceutil/trace.go:171","msg":"trace[295220631] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"487.310864ms","start":"2026-04-17T05:58:32.994718Z","end":"2026-04-17T05:58:33.482029Z","steps":["trace[295220631] 'process raft request' (duration: 178.77326ms)","trace[295220631] 'compare' (duration: 308.160741ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:58:33.482123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:58:32.994698Z","time spent":"487.389717ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T05:58:33.54553Z","caller":"traceutil/trace.go:171","msg":"trace[1751840179] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"550.569347ms","start":"2026-04-17T05:58:32.994913Z","end":"2026-04-17T05:58:33.545482Z","steps":["trace[1751840179] 'process raft request' (duration: 550.386871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:58:33.545792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:58:32.994882Z","time spent":"550.765155ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T05:58:33.546047Z","caller":"traceutil/trace.go:171","msg":"trace[662443696] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"550.831998ms","start":"2026-04-17T05:58:32.99519Z","end":"2026-04-17T05:58:33.546022Z","steps":["trace[662443696] 'process raft request' (duration: 550.162313ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:58:33.546207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:58:32.995145Z","time spent":"550.966293ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T05:58:33.546207Z","caller":"traceutil/trace.go:171","msg":"trace[1566379192] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"551.316186ms","start":"2026-04-17T05:58:32.994876Z","end":"2026-04-17T05:58:33.546192Z","steps":["trace[1566379192] 'process raft request' (duration: 550.238396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:58:33.546292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:58:32.994845Z","time spent":"551.404109ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T05:58:34.879209Z","caller":"traceutil/trace.go:171","msg":"trace[2080788180] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"206.603484ms","start":"2026-04-17T05:58:34.672574Z","end":"2026-04-17T05:58:34.879178Z","steps":["trace[2080788180] 'process raft request' (duration: 206.376535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:58:35.731624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.80982ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T05:58:35.731784Z","caller":"traceutil/trace.go:171","msg":"trace[239724975] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"130.658858ms","start":"2026-04-17T05:58:35.601101Z","end":"2026-04-17T05:58:35.73176Z","steps":["trace[239724975] 'process raft request' (duration: 15.565128ms)","trace[239724975] 'compare' (duration: 114.645354ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:58:36.333623Z","caller":"traceutil/trace.go:171","msg":"trace[233818026] linearizableReadLoop","detail":"{readStateIndex:787; appliedIndex:786; }","duration":"163.037238ms","start":"2026-04-17T05:58:36.170562Z","end":"2026-04-17T05:58:36.333599Z","steps":["trace[233818026] 'read index received' (duration: 162.752377ms)","trace[233818026] 'applied index is now lower than readState.Index' (duration: 283.211µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:58:36.333714Z","caller":"traceutil/trace.go:171","msg":"trace[768742374] transaction","detail":"{read_only:false; number_of_response:1; response_revision:755; }","duration":"208.895247ms","start":"2026-04-17T05:58:36.124778Z","end":"2026-04-17T05:58:36.333673Z","steps":["trace[768742374] 'process raft request' (duration: 208.647958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:58:36.333799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.204283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T05:58:36.33383Z","caller":"traceutil/trace.go:171","msg":"trace[552989474] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:755; }","duration":"163.314608ms","start":"2026-04-17T05:58:36.170506Z","end":"2026-04-17T05:58:36.33382Z","steps":["trace[552989474] 'agreement among raft nodes before linearized reading' (duration: 163.191833ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:49.316141Z","caller":"traceutil/trace.go:171","msg":"trace[2023650023] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"205.017185ms","start":"2026-04-17T05:58:49.111094Z","end":"2026-04-17T05:58:49.316112Z","steps":["trace[2023650023] 'process raft request' (duration: 160.818655ms)","trace[2023650023] 'compare' (duration: 43.994422ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:58:49.74586Z","caller":"traceutil/trace.go:171","msg":"trace[1468616217] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"102.705249ms","start":"2026-04-17T05:58:49.643127Z","end":"2026-04-17T05:58:49.745832Z","steps":["trace[1468616217] 'process raft request' (duration: 101.232296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:58:49.955912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.537561ms","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-04-17T05:58:49.956023Z","caller":"traceutil/trace.go:171","msg":"trace[1613363476] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:893; }","duration":"100.716188ms","start":"2026-04-17T05:58:49.855281Z","end":"2026-04-17T05:58:49.955997Z","steps":["trace[1613363476] 'range keys from in-memory index tree' (duration: 100.308803ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:59:12.392922Z","caller":"traceutil/trace.go:171","msg":"trace[1234338018] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"147.308214ms","start":"2026-04-17T05:59:12.245583Z","end":"2026-04-17T05:59:12.392892Z","steps":["trace[1234338018] 'process raft request' (duration: 147.11202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:59:14.043029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.696199ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-17T05:59:14.043195Z","caller":"traceutil/trace.go:171","msg":"trace[127332970] linearizableReadLoop","detail":"{readStateIndex:1014; appliedIndex:1013; }","duration":"120.109746ms","start":"2026-04-17T05:59:13.923068Z","end":"2026-04-17T05:59:14.043178Z","steps":["trace[127332970] 'read index received' (duration: 51.301µs)","trace[127332970] 'applied index is now lower than readState.Index' (duration: 120.056535ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:59:14.043519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.440754ms","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-04-17T05:59:14.043554Z","caller":"traceutil/trace.go:171","msg":"trace[1204195203] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:973; }","duration":"120.547236ms","start":"2026-04-17T05:59:13.922996Z","end":"2026-04-17T05:59:14.043544Z","steps":["trace[1204195203] 'agreement among raft nodes before linearized reading' (duration: 120.29943ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:59:14.172523Z","caller":"traceutil/trace.go:171","msg":"trace[1158543421] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"124.31393ms","start":"2026-04-17T05:59:14.048182Z","end":"2026-04-17T05:59:14.172496Z","steps":["trace[1158543421] 'process raft request' (duration: 124.027764ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:59:14.187657Z","caller":"traceutil/trace.go:171","msg":"trace[29676288] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"138.843164ms","start":"2026-04-17T05:59:14.048772Z","end":"2026-04-17T05:59:14.187615Z","steps":["trace[29676288] 'process raft request' (duration: 138.800443ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:59:14.187882Z","caller":"traceutil/trace.go:171","msg":"trace[1388179870] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"139.089419ms","start":"2026-04-17T05:59:14.048772Z","end":"2026-04-17T05:59:14.187861Z","steps":["trace[1388179870] 'process raft request' (duration: 138.66294ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:59:28.648976Z","caller":"traceutil/trace.go:171","msg":"trace[724320921] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"132.803339ms","start":"2026-04-17T05:59:28.516147Z","end":"2026-04-17T05:59:28.64895Z","steps":["trace[724320921] 'process raft request' (duration: 132.645806ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:59:38.438573Z","caller":"traceutil/trace.go:171","msg":"trace[916766206] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"100.329174ms","start":"2026-04-17T05:59:38.33821Z","end":"2026-04-17T05:59:38.43854Z","steps":["trace[916766206] 'process raft request' (duration: 99.958626ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:02:10.515292Z","caller":"traceutil/trace.go:171","msg":"trace[1567660266] linearizableReadLoop","detail":"{readStateIndex:1628; appliedIndex:1627; }","duration":"147.017714ms","start":"2026-04-17T06:02:10.36825Z","end":"2026-04-17T06:02:10.515268Z","steps":["trace[1567660266] 'read index received' (duration: 146.724787ms)","trace[1567660266] 'applied index is now lower than readState.Index' (duration: 291.527µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:02:10.515395Z","caller":"traceutil/trace.go:171","msg":"trace[1534007379] transaction","detail":"{read_only:false; response_revision:1552; number_of_response:1; }","duration":"211.078025ms","start":"2026-04-17T06:02:10.304296Z","end":"2026-04-17T06:02:10.515374Z","steps":["trace[1534007379] 'process raft request' (duration: 210.746466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:02:10.515495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.219428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:02:10.515526Z","caller":"traceutil/trace.go:171","msg":"trace[1966675430] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1552; }","duration":"147.320151ms","start":"2026-04-17T06:02:10.368195Z","end":"2026-04-17T06:02:10.515515Z","steps":["trace[1966675430] 'agreement among raft nodes before linearized reading' (duration: 147.243589ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:02:12.402554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.582622135s","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-04-17T06:02:12.40264Z","caller":"traceutil/trace.go:171","msg":"trace[1512187608] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1552; }","duration":"1.582766309s","start":"2026-04-17T06:02:10.819853Z","end":"2026-04-17T06:02:12.40262Z","steps":["trace[1512187608] 'range keys from in-memory index tree' (duration: 1.582359389s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:02:12.402625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.018222181s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-17T06:02:12.40269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:02:10.819824Z","time spent":"1.58285569s","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-17T06:02:12.402742Z","caller":"traceutil/trace.go:171","msg":"trace[503608961] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1552; }","duration":"1.018398145s","start":"2026-04-17T06:02:11.384319Z","end":"2026-04-17T06:02:12.402717Z","steps":["trace[503608961] 'range keys from in-memory index tree' (duration: 1.017999996s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:02:12.402851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:02:11.384298Z","time spent":"1.018541529s","remote":"127.0.0.1:50226","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-04-17T06:02:12.403242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.64544ms","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-04-17T06:02:12.403343Z","caller":"traceutil/trace.go:171","msg":"trace[1605026068] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1552; }","duration":"625.788844ms","start":"2026-04-17T06:02:11.777528Z","end":"2026-04-17T06:02:12.403317Z","steps":["trace[1605026068] 'range keys from in-memory index tree' (duration: 625.394784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:02:12.403397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:02:11.777457Z","time spent":"625.929037ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T06:02:12.4035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.822411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T06:02:12.403544Z","caller":"traceutil/trace.go:171","msg":"trace[1912885840] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1552; }","duration":"502.869102ms","start":"2026-04-17T06:02:11.900659Z","end":"2026-04-17T06:02:12.403528Z","steps":["trace[1912885840] 'count revisions from in-memory index tree' (duration: 502.732509ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:02:12.403576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:02:11.90063Z","time spent":"502.936523ms","remote":"127.0.0.1:50476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":29,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-04-17T06:02:12.403742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"968.662988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-17T06:02:12.403781Z","caller":"traceutil/trace.go:171","msg":"trace[801347663] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1552; }","duration":"968.73806ms","start":"2026-04-17T06:02:11.435032Z","end":"2026-04-17T06:02:12.40377Z","steps":["trace[801347663] 'range keys from in-memory index tree' (duration: 968.512205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:02:12.403853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:02:11.435008Z","time spent":"968.837672ms","remote":"127.0.0.1:50226","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-04-17T06:02:12.404056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"893.134951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:02:12.404085Z","caller":"traceutil/trace.go:171","msg":"trace[2135627360] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1552; }","duration":"893.206033ms","start":"2026-04-17T06:02:11.510869Z","end":"2026-04-17T06:02:12.404075Z","steps":["trace[2135627360] 'range keys from in-memory index tree' (duration: 893.014909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:02:12.404112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:02:11.510845Z","time spent":"893.260204ms","remote":"127.0.0.1:49924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T06:02:12.404515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.981257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-17T06:02:12.404625Z","caller":"traceutil/trace.go:171","msg":"trace[339354909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1552; }","duration":"747.12413ms","start":"2026-04-17T06:02:11.657477Z","end":"2026-04-17T06:02:12.404601Z","steps":["trace[339354909] 'range keys from in-memory index tree' (duration: 746.750021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:02:12.404676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:02:11.657454Z","time spent":"747.207222ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-17T06:02:12.909072Z","caller":"traceutil/trace.go:171","msg":"trace[547891363] transaction","detail":"{read_only:false; response_revision:1554; number_of_response:1; }","duration":"499.177568ms","start":"2026-04-17T06:02:12.409861Z","end":"2026-04-17T06:02:12.909038Z","steps":["trace[547891363] 'process raft request' (duration: 498.718778ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:02:12.909301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:02:12.409834Z","time spent":"499.335042ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:02:12.909495Z","caller":"traceutil/trace.go:171","msg":"trace[472650485] transaction","detail":"{read_only:false; response_revision:1557; number_of_response:1; }","duration":"498.558604ms","start":"2026-04-17T06:02:12.410904Z","end":"2026-04-17T06:02:12.909463Z","steps":["trace[472650485] 'process raft request' (duration: 498.470012ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:02:12.909576Z","caller":"traceutil/trace.go:171","msg":"trace[742726850] transaction","detail":"{read_only:false; response_revision:1556; number_of_response:1; }","duration":"498.686487ms","start":"2026-04-17T06:02:12.410871Z","end":"2026-04-17T06:02:12.909557Z","steps":["trace[742726850] 'process raft request' (duration: 498.407521ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:02:12.909524Z","caller":"traceutil/trace.go:171","msg":"trace[128888585] transaction","detail":"{read_only:false; response_revision:1555; number_of_response:1; }","duration":"499.400342ms","start":"2026-04-17T06:02:12.410075Z","end":"2026-04-17T06:02:12.909475Z","steps":["trace[128888585] 'process raft request' (duration: 498.899621ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:02:12.909677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:02:12.410857Z","time spent":"498.767949ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:02:12.909693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:02:12.410842Z","time spent":"498.752518ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:02:12.909792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:02:12.410022Z","time spent":"499.629018ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:02:12.910049Z","caller":"traceutil/trace.go:171","msg":"trace[1108228037] transaction","detail":"{read_only:false; response_revision:1558; number_of_response:1; }","duration":"388.94599ms","start":"2026-04-17T06:02:12.521088Z","end":"2026-04-17T06:02:12.910034Z","steps":["trace[1108228037] 'process raft request' (duration: 388.837267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:02:12.910113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:02:12.521057Z","time spent":"389.027642ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:02:16.612689Z","caller":"traceutil/trace.go:171","msg":"trace[898808167] linearizableReadLoop","detail":"{readStateIndex:1643; appliedIndex:1642; }","duration":"102.508543ms","start":"2026-04-17T06:02:16.510159Z","end":"2026-04-17T06:02:16.612667Z","steps":["trace[898808167] 'read index received' (duration: 102.400811ms)","trace[898808167] 'applied index is now lower than readState.Index' (duration: 106.872µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:02:16.612819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.630476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:02:16.61284Z","caller":"traceutil/trace.go:171","msg":"trace[885467951] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1566; }","duration":"102.695057ms","start":"2026-04-17T06:02:16.510138Z","end":"2026-04-17T06:02:16.612833Z","steps":["trace[885467951] 'agreement among raft nodes before linearized reading' (duration: 102.609015ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:02:16.612877Z","caller":"traceutil/trace.go:171","msg":"trace[1380480348] transaction","detail":"{read_only:false; response_revision:1566; number_of_response:1; }","duration":"108.852975ms","start":"2026-04-17T06:02:16.503983Z","end":"2026-04-17T06:02:16.612836Z","steps":["trace[1380480348] 'process raft request' (duration: 108.517937ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:02:39.473575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.840672ms","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-04-17T06:02:39.473709Z","caller":"traceutil/trace.go:171","msg":"trace[1514067986] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1640; }","duration":"697.002846ms","start":"2026-04-17T06:02:38.776677Z","end":"2026-04-17T06:02:39.47368Z","steps":["trace[1514067986] 'range keys from in-memory index tree' (duration: 696.652208ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:02:39.473792Z","caller":"traceutil/trace.go:171","msg":"trace[1909913868] linearizableReadLoop","detail":"{readStateIndex:1723; appliedIndex:1722; }","duration":"437.25901ms","start":"2026-04-17T06:02:39.036514Z","end":"2026-04-17T06:02:39.473773Z","steps":["trace[1909913868] 'read index received' (duration: 408.853697ms)","trace[1909913868] 'applied index is now lower than readState.Index' (duration: 28.403663ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:02:39.473772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:02:38.776665Z","time spent":"697.091777ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-17T06:02:39.473954Z","caller":"traceutil/trace.go:171","msg":"trace[1859122842] transaction","detail":"{read_only:false; response_revision:1641; number_of_response:1; }","duration":"444.401758ms","start":"2026-04-17T06:02:39.0295Z","end":"2026-04-17T06:02:39.473902Z","steps":["trace[1859122842] 'process raft request' (duration: 415.965005ms)","trace[1859122842] 'compare' (duration: 28.037115ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:02:39.474027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.514795ms","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-04-17T06:02:39.474067Z","caller":"traceutil/trace.go:171","msg":"trace[1663243225] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1641; }","duration":"437.603407ms","start":"2026-04-17T06:02:39.036452Z","end":"2026-04-17T06:02:39.474055Z","steps":["trace[1663243225] 'agreement among raft nodes before linearized reading' (duration: 437.445543ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:02:39.474097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:02:39.036426Z","time spent":"437.663899ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-17T06:02:39.474229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.903144ms","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-04-17T06:02:39.474258Z","caller":"traceutil/trace.go:171","msg":"trace[317255125] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1641; }","duration":"428.958084ms","start":"2026-04-17T06:02:39.045289Z","end":"2026-04-17T06:02:39.474247Z","steps":["trace[317255125] 'agreement among raft nodes before linearized reading' (duration: 428.881472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:02:39.474285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:02:39.045276Z","time spent":"429.003146ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T06:02:39.474445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.217256ms","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-04-17T06:02:39.47451Z","caller":"traceutil/trace.go:171","msg":"trace[1239213625] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1641; }","duration":"423.306019ms","start":"2026-04-17T06:02:39.051193Z","end":"2026-04-17T06:02:39.474499Z","steps":["trace[1239213625] 'agreement among raft nodes before linearized reading' (duration: 423.176646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:02:39.474546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:02:39.051181Z","time spent":"423.358009ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-17T06:02:39.47464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:02:39.029452Z","time spent":"445.091264ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:02:39.474845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.156069ms","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-04-17T06:02:39.47495Z","caller":"traceutil/trace.go:171","msg":"trace[1032714396] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1641; }","duration":"429.365684ms","start":"2026-04-17T06:02:39.045559Z","end":"2026-04-17T06:02:39.474925Z","steps":["trace[1032714396] 'agreement among raft nodes before linearized reading' (duration: 428.813942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:02:39.474993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:02:39.045544Z","time spent":"429.439565ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-17T06:02:41.647869Z","caller":"traceutil/trace.go:171","msg":"trace[161687104] transaction","detail":"{read_only:false; response_revision:1648; number_of_response:1; }","duration":"126.361204ms","start":"2026-04-17T06:02:41.521455Z","end":"2026-04-17T06:02:41.647816Z","steps":["trace[161687104] 'process raft request' (duration: 110.128342ms)","trace[161687104] 'compare' (duration: 15.918175ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:02:41.647958Z","caller":"traceutil/trace.go:171","msg":"trace[18730665] transaction","detail":"{read_only:false; response_revision:1649; number_of_response:1; }","duration":"126.384985ms","start":"2026-04-17T06:02:41.521565Z","end":"2026-04-17T06:02:41.64795Z","steps":["trace[18730665] 'process raft request' (duration: 126.118359ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:02:41.648082Z","caller":"traceutil/trace.go:171","msg":"trace[1959783274] transaction","detail":"{read_only:false; response_revision:1650; number_of_response:1; }","duration":"126.303544ms","start":"2026-04-17T06:02:41.521723Z","end":"2026-04-17T06:02:41.648026Z","steps":["trace[1959783274] 'process raft request' (duration: 126.008087ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:02:41.64822Z","caller":"traceutil/trace.go:171","msg":"trace[829922687] transaction","detail":"{read_only:false; response_revision:1652; number_of_response:1; }","duration":"126.104469ms","start":"2026-04-17T06:02:41.522073Z","end":"2026-04-17T06:02:41.648177Z","steps":["trace[829922687] 'process raft request' (duration: 125.730561ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:02:41.647878Z","caller":"traceutil/trace.go:171","msg":"trace[1803598826] transaction","detail":"{read_only:false; response_revision:1651; number_of_response:1; }","duration":"125.929375ms","start":"2026-04-17T06:02:41.521915Z","end":"2026-04-17T06:02:41.647844Z","steps":["trace[1803598826] 'process raft request' (duration: 125.850293ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:02:43.619465Z","caller":"traceutil/trace.go:171","msg":"trace[1122666377] linearizableReadLoop","detail":"{readStateIndex:1735; appliedIndex:1734; }","duration":"103.24035ms","start":"2026-04-17T06:02:43.516191Z","end":"2026-04-17T06:02:43.619431Z","steps":["trace[1122666377] 'read index received' (duration: 102.839751ms)","trace[1122666377] 'applied index is now lower than readState.Index' (duration: 399.049µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:02:43.619519Z","caller":"traceutil/trace.go:171","msg":"trace[1437380875] transaction","detail":"{read_only:false; response_revision:1653; number_of_response:1; }","duration":"235.481175ms","start":"2026-04-17T06:02:43.383994Z","end":"2026-04-17T06:02:43.619475Z","steps":["trace[1437380875] 'process raft request' (duration: 235.044305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:02:43.619674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.480295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:02:43.619966Z","caller":"traceutil/trace.go:171","msg":"trace[1887733975] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1653; }","duration":"103.69879ms","start":"2026-04-17T06:02:43.516151Z","end":"2026-04-17T06:02:43.61985Z","steps":["trace[1887733975] 'agreement among raft nodes before linearized reading' (duration: 103.439494ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:02:43.846706Z","caller":"traceutil/trace.go:171","msg":"trace[616082928] linearizableReadLoop","detail":"{readStateIndex:1736; appliedIndex:1735; }","duration":"227.116738ms","start":"2026-04-17T06:02:43.619553Z","end":"2026-04-17T06:02:43.84667Z","steps":["trace[616082928] 'read index received' (duration: 226.811202ms)","trace[616082928] 'applied index is now lower than readState.Index' (duration: 303.976µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:02:43.846789Z","caller":"traceutil/trace.go:171","msg":"trace[146308806] transaction","detail":"{read_only:false; response_revision:1654; number_of_response:1; }","duration":"323.859224ms","start":"2026-04-17T06:02:43.522858Z","end":"2026-04-17T06:02:43.846717Z","steps":["trace[146308806] 'process raft request' (duration: 323.601029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:02:43.846983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.161695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-17T06:02:43.847019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:02:43.522823Z","time spent":"324.070049ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:02:43.847042Z","caller":"traceutil/trace.go:171","msg":"trace[1603089819] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1654; }","duration":"321.282557ms","start":"2026-04-17T06:02:43.525746Z","end":"2026-04-17T06:02:43.847028Z","steps":["trace[1603089819] 'agreement among raft nodes before linearized reading' (duration: 321.095553ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:02:43.847083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:02:43.525725Z","time spent":"321.348818ms","remote":"127.0.0.1:50158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":43,"response size":29,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-04-17T06:02:43.896232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.943474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:02:43.896319Z","caller":"traceutil/trace.go:171","msg":"trace[1583640094] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1654; }","duration":"275.081308ms","start":"2026-04-17T06:02:43.621218Z","end":"2026-04-17T06:02:43.896299Z","steps":["trace[1583640094] 'agreement among raft nodes before linearized reading' (duration: 274.945954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:02:43.896477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.358241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-17T06:02:43.896565Z","caller":"traceutil/trace.go:171","msg":"trace[1553619280] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1654; }","duration":"243.549994ms","start":"2026-04-17T06:02:43.652993Z","end":"2026-04-17T06:02:43.896543Z","steps":["trace[1553619280] 'agreement among raft nodes before linearized reading' (duration: 243.237347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:02:43.896588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.592876ms","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-04-17T06:02:43.896635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.133095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-17T06:02:43.896664Z","caller":"traceutil/trace.go:171","msg":"trace[637945457] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1654; }","duration":"243.692428ms","start":"2026-04-17T06:02:43.652959Z","end":"2026-04-17T06:02:43.896652Z","steps":["trace[637945457] 'agreement among raft nodes before linearized reading' (duration: 243.500314ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:02:43.896669Z","caller":"traceutil/trace.go:171","msg":"trace[1717409640] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1654; }","duration":"243.234878ms","start":"2026-04-17T06:02:43.653424Z","end":"2026-04-17T06:02:43.896659Z","steps":["trace[1717409640] 'agreement among raft nodes before linearized reading' (duration: 243.094115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:02:43.89675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.510724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-17T06:02:43.897037Z","caller":"traceutil/trace.go:171","msg":"trace[424709842] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1654; }","duration":"243.822171ms","start":"2026-04-17T06:02:43.653206Z","end":"2026-04-17T06:02:43.897028Z","steps":["trace[424709842] 'agreement among raft nodes before linearized reading' (duration: 243.107535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:02:43.896694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.094735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-17T06:02:43.897119Z","caller":"traceutil/trace.go:171","msg":"trace[15641208] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1654; }","duration":"243.706108ms","start":"2026-04-17T06:02:43.653398Z","end":"2026-04-17T06:02:43.897104Z","steps":["trace[15641208] 'agreement among raft nodes before linearized reading' (duration: 242.794177ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:02:58.012937Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680219220187255584,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T06:02:58.445229Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.170077693s","expected-duration":"1s"} {"level":"info","ts":"2026-04-17T06:02:58.445752Z","caller":"traceutil/trace.go:171","msg":"trace[1092547735] transaction","detail":"{read_only:false; response_revision:1751; number_of_response:1; }","duration":"1.170786849s","start":"2026-04-17T06:02:57.274936Z","end":"2026-04-17T06:02:58.445723Z","steps":["trace[1092547735] 'process raft request' (duration: 1.170629706s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:02:58.445905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:02:57.274903Z","time spent":"1.170925922s","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:02:58.456173Z","caller":"traceutil/trace.go:171","msg":"trace[1214990837] linearizableReadLoop","detail":"{readStateIndex:1839; appliedIndex:1837; }","duration":"944.091728ms","start":"2026-04-17T06:02:57.512058Z","end":"2026-04-17T06:02:58.456149Z","steps":["trace[1214990837] 'read index received' (duration: 933.571284ms)","trace[1214990837] 'applied index is now lower than readState.Index' (duration: 10.519514ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:02:58.456272Z","caller":"traceutil/trace.go:171","msg":"trace[1513510096] transaction","detail":"{read_only:false; response_revision:1752; number_of_response:1; }","duration":"549.091491ms","start":"2026-04-17T06:02:57.907168Z","end":"2026-04-17T06:02:58.456259Z","steps":["trace[1513510096] 'process raft request' (duration: 548.871276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:02:58.456377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"944.335094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T06:02:58.456385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:02:57.907143Z","time spent":"549.156402ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:02:58.456437Z","caller":"traceutil/trace.go:171","msg":"trace[2008775155] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1752; }","duration":"944.453317ms","start":"2026-04-17T06:02:57.511965Z","end":"2026-04-17T06:02:58.456418Z","steps":["trace[2008775155] 'agreement among raft nodes before linearized reading' (duration: 944.370075ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:02:58.456471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:02:57.511924Z","time spent":"944.539948ms","remote":"127.0.0.1:49924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T06:02:58.456477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.502197ms","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-04-17T06:02:58.456521Z","caller":"traceutil/trace.go:171","msg":"trace[1175696094] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1752; }","duration":"438.588319ms","start":"2026-04-17T06:02:58.01792Z","end":"2026-04-17T06:02:58.456509Z","steps":["trace[1175696094] 'agreement among raft nodes before linearized reading' (duration: 438.420096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:02:58.45655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:02:58.0179Z","time spent":"438.643241ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T06:02:58.45676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.601438ms","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-04-17T06:02:58.456804Z","caller":"traceutil/trace.go:171","msg":"trace[1472552031] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1752; }","duration":"389.68969ms","start":"2026-04-17T06:02:58.067103Z","end":"2026-04-17T06:02:58.456793Z","steps":["trace[1472552031] 'agreement among raft nodes before linearized reading' (duration: 389.555537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:02:58.456826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.897796ms","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-04-17T06:02:58.456832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:02:58.067066Z","time spent":"389.759831ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-17T06:02:58.456855Z","caller":"traceutil/trace.go:171","msg":"trace[1155433417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1752; }","duration":"394.947177ms","start":"2026-04-17T06:02:58.0619Z","end":"2026-04-17T06:02:58.456847Z","steps":["trace[1155433417] 'agreement among raft nodes before linearized reading' (duration: 394.714102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:02:58.456875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:02:58.06189Z","time spent":"394.981168ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-17T06:02:58.456894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.394861ms","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-04-17T06:02:58.456962Z","caller":"traceutil/trace.go:171","msg":"trace[1637687883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1752; }","duration":"397.533035ms","start":"2026-04-17T06:02:58.059412Z","end":"2026-04-17T06:02:58.456945Z","steps":["trace[1637687883] 'agreement among raft nodes before linearized reading' (duration: 397.282379ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:02:58.457007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:02:58.059397Z","time spent":"397.598546ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T06:02:58.458261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.184405ms","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-04-17T06:02:58.458889Z","caller":"traceutil/trace.go:171","msg":"trace[1566561809] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1752; }","duration":"396.932581ms","start":"2026-04-17T06:02:58.061941Z","end":"2026-04-17T06:02:58.458874Z","steps":["trace[1566561809] 'agreement among raft nodes before linearized reading' (duration: 394.608969ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:02:58.45894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:02:58.061937Z","time spent":"396.990563ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-17T06:02:59.026825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:02:58.459722Z","time spent":"567.096612ms","remote":"127.0.0.1:49984","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-17T06:02:59.034393Z","caller":"traceutil/trace.go:171","msg":"trace[1685878621] transaction","detail":"{read_only:false; response_revision:1753; number_of_response:1; }","duration":"572.950221ms","start":"2026-04-17T06:02:58.461417Z","end":"2026-04-17T06:02:59.034367Z","steps":["trace[1685878621] 'process raft request' (duration: 572.307267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:02:59.034533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:02:58.461404Z","time spent":"573.051603ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:02:59.036639Z","caller":"traceutil/trace.go:171","msg":"trace[4361409] transaction","detail":"{read_only:false; response_revision:1755; number_of_response:1; }","duration":"548.314733ms","start":"2026-04-17T06:02:58.488306Z","end":"2026-04-17T06:02:59.036621Z","steps":["trace[4361409] 'process raft request' (duration: 547.938495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:02:59.036721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:02:58.488285Z","time spent":"548.393065ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:02:59.036782Z","caller":"traceutil/trace.go:171","msg":"trace[298433788] transaction","detail":"{read_only:false; response_revision:1756; number_of_response:1; }","duration":"548.264252ms","start":"2026-04-17T06:02:58.488494Z","end":"2026-04-17T06:02:59.036758Z","steps":["trace[298433788] 'process raft request' (duration: 547.969155ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:02:59.03702Z","caller":"traceutil/trace.go:171","msg":"trace[192610501] transaction","detail":"{read_only:false; response_revision:1754; number_of_response:1; }","duration":"575.553429ms","start":"2026-04-17T06:02:58.461454Z","end":"2026-04-17T06:02:59.037007Z","steps":["trace[192610501] 'process raft request' (duration: 572.764547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:02:59.037044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:02:58.488478Z","time spent":"548.369125ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:02:59.037081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:02:58.46145Z","time spent":"575.60216ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:02:59.037162Z","caller":"traceutil/trace.go:171","msg":"trace[323687038] transaction","detail":"{read_only:false; response_revision:1757; number_of_response:1; }","duration":"547.285069ms","start":"2026-04-17T06:02:58.489867Z","end":"2026-04-17T06:02:59.037152Z","steps":["trace[323687038] 'process raft request' (duration: 546.624485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:02:59.0385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:02:58.489842Z","time spent":"547.355702ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:03:11.238044Z","caller":"traceutil/trace.go:171","msg":"trace[973349781] linearizableReadLoop","detail":"{readStateIndex:1885; appliedIndex:1883; }","duration":"108.267262ms","start":"2026-04-17T06:03:11.129751Z","end":"2026-04-17T06:03:11.238019Z","steps":["trace[973349781] 'read index received' (duration: 64.423435ms)","trace[973349781] 'applied index is now lower than readState.Index' (duration: 43.842437ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:03:11.238386Z","caller":"traceutil/trace.go:171","msg":"trace[1230701890] transaction","detail":"{read_only:false; response_revision:1794; number_of_response:1; }","duration":"119.8785ms","start":"2026-04-17T06:03:11.118489Z","end":"2026-04-17T06:03:11.238367Z","steps":["trace[1230701890] 'process raft request' (duration: 75.573923ms)","trace[1230701890] 'compare' (duration: 43.731584ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:03:11.238577Z","caller":"traceutil/trace.go:171","msg":"trace[507041035] transaction","detail":"{read_only:false; response_revision:1795; number_of_response:1; }","duration":"119.86069ms","start":"2026-04-17T06:03:11.118706Z","end":"2026-04-17T06:03:11.238567Z","steps":["trace[507041035] 'process raft request' (duration: 119.255837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:03:11.238848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.07894ms","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-04-17T06:03:11.238893Z","caller":"traceutil/trace.go:171","msg":"trace[2002489488] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1795; }","duration":"109.167822ms","start":"2026-04-17T06:03:11.129714Z","end":"2026-04-17T06:03:11.238882Z","steps":["trace[2002489488] 'agreement among raft nodes before linearized reading' (duration: 108.976338ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:03:11.239144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.96333ms","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-04-17T06:03:11.239189Z","caller":"traceutil/trace.go:171","msg":"trace[942115530] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1795; }","duration":"102.039602ms","start":"2026-04-17T06:03:11.13714Z","end":"2026-04-17T06:03:11.239179Z","steps":["trace[942115530] 'agreement among raft nodes before linearized reading' (duration: 101.92217ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:03:11.520292Z","caller":"traceutil/trace.go:171","msg":"trace[1977192379] transaction","detail":"{read_only:false; response_revision:1797; number_of_response:1; }","duration":"276.97893ms","start":"2026-04-17T06:03:11.243188Z","end":"2026-04-17T06:03:11.520167Z","steps":["trace[1977192379] 'process raft request' (duration: 276.418408ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:03:12.980819Z","caller":"traceutil/trace.go:171","msg":"trace[351880411] linearizableReadLoop","detail":"{readStateIndex:1888; appliedIndex:1887; }","duration":"466.100882ms","start":"2026-04-17T06:03:12.514692Z","end":"2026-04-17T06:03:12.980793Z","steps":["trace[351880411] 'read index received' (duration: 465.894998ms)","trace[351880411] 'applied index is now lower than readState.Index' (duration: 204.834µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:03:12.980975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.261117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:03:12.981009Z","caller":"traceutil/trace.go:171","msg":"trace[1981992043] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1798; }","duration":"466.363509ms","start":"2026-04-17T06:03:12.514636Z","end":"2026-04-17T06:03:12.980999Z","steps":["trace[1981992043] 'agreement among raft nodes before linearized reading' (duration: 466.258427ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:03:12.981051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:03:12.514612Z","time spent":"466.43105ms","remote":"127.0.0.1:49924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T06:03:12.981434Z","caller":"traceutil/trace.go:171","msg":"trace[1205752537] transaction","detail":"{read_only:false; response_revision:1798; number_of_response:1; }","duration":"479.986281ms","start":"2026-04-17T06:03:12.50143Z","end":"2026-04-17T06:03:12.981416Z","steps":["trace[1205752537] 'process raft request' (duration: 479.223024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:03:12.981543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:03:12.501395Z","time spent":"480.080023ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:03:14.911072Z","caller":"traceutil/trace.go:171","msg":"trace[1637023634] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"153.447168ms","start":"2026-04-17T06:03:14.757594Z","end":"2026-04-17T06:03:14.911041Z","steps":["trace[1637023634] 'process raft request' (duration: 153.267224ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:03:17.085453Z","caller":"traceutil/trace.go:171","msg":"trace[1658150411] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"164.646247ms","start":"2026-04-17T06:03:16.920777Z","end":"2026-04-17T06:03:17.085423Z","steps":["trace[1658150411] 'process raft request' (duration: 88.539202ms)","trace[1658150411] 'compare' (duration: 75.527293ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:03:18.118515Z","caller":"traceutil/trace.go:171","msg":"trace[1480484106] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"562.162812ms","start":"2026-04-17T06:03:17.556325Z","end":"2026-04-17T06:03:18.118487Z","steps":["trace[1480484106] 'process raft request' (duration: 561.976078ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:03:18.118701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:03:17.556311Z","time spent":"562.292695ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:03:19.646871Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680219220187255825,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T06:03:19.909157Z","caller":"traceutil/trace.go:171","msg":"trace[930195192] linearizableReadLoop","detail":"{readStateIndex:1952; appliedIndex:1951; }","duration":"762.889352ms","start":"2026-04-17T06:03:19.146234Z","end":"2026-04-17T06:03:19.909124Z","steps":["trace[930195192] 'read index received' (duration: 762.612826ms)","trace[930195192] 'applied index is now lower than readState.Index' (duration: 274.796µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:03:19.9094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.642879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T06:03:19.909421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.159548ms","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-04-17T06:03:19.909463Z","caller":"traceutil/trace.go:171","msg":"trace[75161057] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1860; }","duration":"257.764911ms","start":"2026-04-17T06:03:19.651679Z","end":"2026-04-17T06:03:19.909444Z","steps":["trace[75161057] 'agreement among raft nodes before linearized reading' (duration: 257.656359ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:03:19.909459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.68108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:03:19.909483Z","caller":"traceutil/trace.go:171","msg":"trace[484536446] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1860; }","duration":"763.284621ms","start":"2026-04-17T06:03:19.146182Z","end":"2026-04-17T06:03:19.909467Z","steps":["trace[484536446] 'agreement among raft nodes before linearized reading' (duration: 763.076836ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:03:19.909521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:03:19.146158Z","time spent":"763.358293ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-17T06:03:19.909522Z","caller":"traceutil/trace.go:171","msg":"trace[642137805] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1860; }","duration":"165.803343ms","start":"2026-04-17T06:03:19.7437Z","end":"2026-04-17T06:03:19.909504Z","steps":["trace[642137805] 'agreement among raft nodes before linearized reading' (duration: 165.66363ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:03:19.90951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.890208ms","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-04-17T06:03:19.909606Z","caller":"traceutil/trace.go:171","msg":"trace[1136227144] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1860; }","duration":"629.026371ms","start":"2026-04-17T06:03:19.280538Z","end":"2026-04-17T06:03:19.909564Z","steps":["trace[1136227144] 'agreement among raft nodes before linearized reading' (duration: 628.788696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:03:19.90965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:03:19.280514Z","time spent":"629.127263ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-17T06:03:19.909821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.435685ms","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-04-17T06:03:19.90984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.89933ms","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-04-17T06:03:19.909851Z","caller":"traceutil/trace.go:171","msg":"trace[1916526425] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1860; }","duration":"627.505617ms","start":"2026-04-17T06:03:19.282337Z","end":"2026-04-17T06:03:19.909843Z","steps":["trace[1916526425] 'agreement among raft nodes before linearized reading' (duration: 627.425735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:03:19.909884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:03:19.282318Z","time spent":"627.560759ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-17T06:03:19.909889Z","caller":"traceutil/trace.go:171","msg":"trace[312524883] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1860; }","duration":"550.999003ms","start":"2026-04-17T06:03:19.358876Z","end":"2026-04-17T06:03:19.909875Z","steps":["trace[312524883] 'agreement among raft nodes before linearized reading' (duration: 550.86391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:03:19.90993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:03:19.358847Z","time spent":"551.073364ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-17T06:03:19.90923Z","caller":"traceutil/trace.go:171","msg":"trace[1605530947] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"817.788126ms","start":"2026-04-17T06:03:19.091396Z","end":"2026-04-17T06:03:19.909184Z","steps":["trace[1605530947] 'process raft request' (duration: 817.50539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:03:19.910204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:03:19.09136Z","time spent":"818.794028ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:03:20.372349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.421525ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T06:03:20.372623Z","caller":"traceutil/trace.go:171","msg":"trace[139278169] linearizableReadLoop","detail":"{readStateIndex:1956; appliedIndex:1952; }","duration":"248.526035ms","start":"2026-04-17T06:03:20.124075Z","end":"2026-04-17T06:03:20.372601Z","steps":["trace[139278169] 'read index received' (duration: 112.743351ms)","trace[139278169] 'applied index is now lower than readState.Index' (duration: 135.781074ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:03:20.372927Z","caller":"traceutil/trace.go:171","msg":"trace[1756328348] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"458.310098ms","start":"2026-04-17T06:03:19.914597Z","end":"2026-04-17T06:03:20.372907Z","steps":["trace[1756328348] 'process raft request' (duration: 457.93466ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:03:20.372938Z","caller":"traceutil/trace.go:171","msg":"trace[1428172764] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"458.767578ms","start":"2026-04-17T06:03:19.914134Z","end":"2026-04-17T06:03:20.372901Z","steps":["trace[1428172764] 'process raft request' (duration: 458.352069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:03:20.372999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:03:19.914562Z","time spent":"458.406182ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:03:20.372984Z","caller":"traceutil/trace.go:171","msg":"trace[910291031] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"458.236567ms","start":"2026-04-17T06:03:19.914708Z","end":"2026-04-17T06:03:20.372945Z","steps":["trace[910291031] 'process raft request' (duration: 457.852428ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:03:20.373243Z","caller":"traceutil/trace.go:171","msg":"trace[71847486] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"458.82562ms","start":"2026-04-17T06:03:19.914115Z","end":"2026-04-17T06:03:20.372941Z","steps":["trace[71847486] 'process raft request' (duration: 120.691788ms)","trace[71847486] 'compare' (duration: 337.256722ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:03:20.373163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:03:19.914117Z","time spent":"458.896322ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:03:20.373925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:03:19.914099Z","time spent":"459.758211ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:03:20.373621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:03:19.914689Z","time spent":"458.581135ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T06:03:20.373684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.61872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-17T06:03:20.374246Z","caller":"traceutil/trace.go:171","msg":"trace[932085378] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1864; }","duration":"250.184403ms","start":"2026-04-17T06:03:20.124009Z","end":"2026-04-17T06:03:20.374194Z","steps":["trace[932085378] 'agreement among raft nodes before linearized reading' (duration: 248.646268ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:03:20.607585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.516157ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T06:03:20.607791Z","caller":"traceutil/trace.go:171","msg":"trace[219400134] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"228.099743ms","start":"2026-04-17T06:03:20.379651Z","end":"2026-04-17T06:03:20.60775Z","steps":["trace[219400134] 'process raft request' (duration: 107.30155ms)","trace[219400134] 'compare' (duration: 120.325883ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:03:20.852301Z","caller":"traceutil/trace.go:171","msg":"trace[998735212] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"197.309421ms","start":"2026-04-17T06:03:20.654963Z","end":"2026-04-17T06:03:20.852273Z","steps":["trace[998735212] 'process raft request' (duration: 197.092876ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:03:21.832964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.863761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:03:21.833076Z","caller":"traceutil/trace.go:171","msg":"trace[199310193] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1866; }","duration":"323.031755ms","start":"2026-04-17T06:03:21.510016Z","end":"2026-04-17T06:03:21.833047Z","steps":["trace[199310193] 'range keys from in-memory index tree' (duration: 322.766919ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:03:21.833125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:03:21.509995Z","time spent":"323.117957ms","remote":"127.0.0.1:49924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T06:03:22.032483Z","caller":"traceutil/trace.go:171","msg":"trace[26411361] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"116.603501ms","start":"2026-04-17T06:03:21.915844Z","end":"2026-04-17T06:03:22.032448Z","steps":["trace[26411361] 'process raft request' (duration: 116.427058ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:03:22.622654Z","caller":"traceutil/trace.go:171","msg":"trace[1987814785] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"143.693235ms","start":"2026-04-17T06:03:22.478932Z","end":"2026-04-17T06:03:22.622626Z","steps":["trace[1987814785] 'process raft request' (duration: 143.48145ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:03:22.623067Z","caller":"traceutil/trace.go:171","msg":"trace[400207961] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"143.986341ms","start":"2026-04-17T06:03:22.479059Z","end":"2026-04-17T06:03:22.623045Z","steps":["trace[400207961] 'process raft request' (duration: 143.526501ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:03:22.623614Z","caller":"traceutil/trace.go:171","msg":"trace[360600867] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"144.373031ms","start":"2026-04-17T06:03:22.479226Z","end":"2026-04-17T06:03:22.623599Z","steps":["trace[360600867] 'process raft request' (duration: 143.737727ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:03:22.623659Z","caller":"traceutil/trace.go:171","msg":"trace[463994511] linearizableReadLoop","detail":"{readStateIndex:1965; appliedIndex:1960; }","duration":"114.151656ms","start":"2026-04-17T06:03:22.509496Z","end":"2026-04-17T06:03:22.623647Z","steps":["trace[463994511] 'read index received' (duration: 21.52056ms)","trace[463994511] 'applied index is now lower than readState.Index' (duration: 92.630446ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:03:22.623771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.255728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:03:22.623802Z","caller":"traceutil/trace.go:171","msg":"trace[1602176137] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1873; }","duration":"114.3277ms","start":"2026-04-17T06:03:22.509465Z","end":"2026-04-17T06:03:22.623792Z","steps":["trace[1602176137] 'agreement among raft nodes before linearized reading' (duration: 114.244258ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:03:22.623902Z","caller":"traceutil/trace.go:171","msg":"trace[489283892] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"144.330728ms","start":"2026-04-17T06:03:22.479564Z","end":"2026-04-17T06:03:22.623895Z","steps":["trace[489283892] 'process raft request' (duration: 143.98169ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:03:22.951262Z","caller":"traceutil/trace.go:171","msg":"trace[1354617458] linearizableReadLoop","detail":"{readStateIndex:1966; appliedIndex:1965; }","duration":"167.340233ms","start":"2026-04-17T06:03:22.783891Z","end":"2026-04-17T06:03:22.951231Z","steps":["trace[1354617458] 'read index received' (duration: 82.593373ms)","trace[1354617458] 'applied index is now lower than readState.Index' (duration: 84.74458ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:03:22.951368Z","caller":"traceutil/trace.go:171","msg":"trace[749347536] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"323.137156ms","start":"2026-04-17T06:03:22.628197Z","end":"2026-04-17T06:03:22.951334Z","steps":["trace[749347536] 'process raft request' (duration: 238.360295ms)","trace[749347536] 'compare' (duration: 83.123134ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:03:22.951427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.505956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T06:03:22.951464Z","caller":"traceutil/trace.go:171","msg":"trace[2061072595] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1874; }","duration":"167.626449ms","start":"2026-04-17T06:03:22.783825Z","end":"2026-04-17T06:03:22.951452Z","steps":["trace[2061072595] 'agreement among raft nodes before linearized reading' (duration: 167.504666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:03:22.951526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:03:22.628179Z","time spent":"323.266149ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:03:38.354529Z","caller":"traceutil/trace.go:171","msg":"trace[749038493] linearizableReadLoop","detail":"{readStateIndex:2080; appliedIndex:2079; }","duration":"239.274466ms","start":"2026-04-17T06:03:38.115228Z","end":"2026-04-17T06:03:38.354503Z","steps":["trace[749038493] 'read index received' (duration: 239.03733ms)","trace[749038493] 'applied index is now lower than readState.Index' (duration: 233.846µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:03:38.355531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.271458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-04-17T06:03:38.355666Z","caller":"traceutil/trace.go:171","msg":"trace[1874226191] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1985; }","duration":"240.393221ms","start":"2026-04-17T06:03:38.115187Z","end":"2026-04-17T06:03:38.35558Z","steps":["trace[1874226191] 'agreement among raft nodes before linearized reading' (duration: 239.418349ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:03:38.360701Z","caller":"traceutil/trace.go:171","msg":"trace[1758516435] transaction","detail":"{read_only:false; response_revision:1985; number_of_response:1; }","duration":"271.183978ms","start":"2026-04-17T06:03:38.089493Z","end":"2026-04-17T06:03:38.360677Z","steps":["trace[1758516435] 'process raft request' (duration: 264.843956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:03:38.872162Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680219220187256099,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T06:03:39.372332Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680219220187256099,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T06:03:39.390803Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.020816765s","expected-duration":"1s"} {"level":"info","ts":"2026-04-17T06:03:39.41656Z","caller":"traceutil/trace.go:171","msg":"trace[640899358] transaction","detail":"{read_only:false; response_revision:1986; number_of_response:1; }","duration":"1.046681052s","start":"2026-04-17T06:03:38.369848Z","end":"2026-04-17T06:03:39.416529Z","steps":["trace[640899358] 'process raft request' (duration: 1.021256945s)","trace[640899358] 'compare' (duration: 25.283224ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:03:39.416775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:03:38.369813Z","time spent":"1.046886828s","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:03:39.470094Z","caller":"traceutil/trace.go:171","msg":"trace[505575471] linearizableReadLoop","detail":"{readStateIndex:2082; appliedIndex:2080; }","duration":"1.098876787s","start":"2026-04-17T06:03:38.37116Z","end":"2026-04-17T06:03:39.470037Z","steps":["trace[505575471] 'read index received' (duration: 1.019955546s)","trace[505575471] 'applied index is now lower than readState.Index' (duration: 78.919591ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:03:39.470615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.099423629s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:03:39.470681Z","caller":"traceutil/trace.go:171","msg":"trace[421619545] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1987; }","duration":"1.099564252s","start":"2026-04-17T06:03:38.371099Z","end":"2026-04-17T06:03:39.470663Z","steps":["trace[421619545] 'agreement among raft nodes before linearized reading' (duration: 1.099429969s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:03:39.47073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:03:38.371075Z","time spent":"1.099641764s","remote":"127.0.0.1:50094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-04-17T06:03:39.470991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.02551354s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-17T06:03:39.471055Z","caller":"traceutil/trace.go:171","msg":"trace[8352852] transaction","detail":"{read_only:false; response_revision:1987; number_of_response:1; }","duration":"413.742687ms","start":"2026-04-17T06:03:39.057298Z","end":"2026-04-17T06:03:39.471041Z","steps":["trace[8352852] 'process raft request' (duration: 408.217564ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:03:39.471121Z","caller":"traceutil/trace.go:171","msg":"trace[1100280542] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:1987; }","duration":"1.025684924s","start":"2026-04-17T06:03:38.445381Z","end":"2026-04-17T06:03:39.471066Z","steps":["trace[1100280542] 'agreement among raft nodes before linearized reading' (duration: 1.025342136s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:03:39.471182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:03:38.445353Z","time spent":"1.025819637s","remote":"127.0.0.1:49984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.72\" "} {"level":"warn","ts":"2026-04-17T06:03:39.471466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.069553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-04-17T06:03:39.471488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"960.182744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:03:39.471511Z","caller":"traceutil/trace.go:171","msg":"trace[1469398509] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1987; }","duration":"800.155594ms","start":"2026-04-17T06:03:38.671342Z","end":"2026-04-17T06:03:39.471497Z","steps":["trace[1469398509] 'agreement among raft nodes before linearized reading' (duration: 800.039572ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:03:39.471526Z","caller":"traceutil/trace.go:171","msg":"trace[2112935502] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1987; }","duration":"960.268426ms","start":"2026-04-17T06:03:38.511247Z","end":"2026-04-17T06:03:39.471515Z","steps":["trace[2112935502] 'agreement among raft nodes before linearized reading' (duration: 960.209564ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:03:39.471545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.270863ms","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-04-17T06:03:39.471569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:03:38.511216Z","time spent":"960.346618ms","remote":"127.0.0.1:49924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T06:03:39.471543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:03:38.671319Z","time spent":"800.216066ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-04-17T06:03:39.471648Z","caller":"traceutil/trace.go:171","msg":"trace[307240453] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1987; }","duration":"429.367745ms","start":"2026-04-17T06:03:39.042206Z","end":"2026-04-17T06:03:39.471574Z","steps":["trace[307240453] 'agreement among raft nodes before linearized reading' (duration: 429.284703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:03:39.472678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.007878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-17T06:03:39.472749Z","caller":"traceutil/trace.go:171","msg":"trace[93900941] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1987; }","duration":"764.121821ms","start":"2026-04-17T06:03:38.708606Z","end":"2026-04-17T06:03:39.472728Z","steps":["trace[93900941] 'agreement among raft nodes before linearized reading' (duration: 763.242021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:03:39.472789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:03:38.708571Z","time spent":"764.211794ms","remote":"127.0.0.1:50226","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-04-17T06:03:39.471467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.128635ms","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-04-17T06:03:39.473106Z","caller":"traceutil/trace.go:171","msg":"trace[713853279] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1987; }","duration":"762.773182ms","start":"2026-04-17T06:03:38.71031Z","end":"2026-04-17T06:03:39.473083Z","steps":["trace[713853279] 'agreement among raft nodes before linearized reading' (duration: 761.031783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:03:39.473237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:03:38.710303Z","time spent":"762.917025ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T06:03:39.472232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.546371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-17T06:03:39.473358Z","caller":"traceutil/trace.go:171","msg":"trace[2043694014] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1987; }","duration":"761.726888ms","start":"2026-04-17T06:03:38.711614Z","end":"2026-04-17T06:03:39.473341Z","steps":["trace[2043694014] 'agreement among raft nodes before linearized reading' (duration: 760.360257ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:03:39.473396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:03:38.711597Z","time spent":"761.786669ms","remote":"127.0.0.1:50226","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-04-17T06:03:39.472715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:03:39.042183Z","time spent":"430.511372ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-17T06:03:39.472283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.376395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-17T06:03:39.47349Z","caller":"traceutil/trace.go:171","msg":"trace[1660791283] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1987; }","duration":"760.622144ms","start":"2026-04-17T06:03:38.712859Z","end":"2026-04-17T06:03:39.473481Z","steps":["trace[1660791283] 'agreement among raft nodes before linearized reading' (duration: 759.089349ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:03:39.473521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:03:38.71285Z","time spent":"760.663095ms","remote":"127.0.0.1:50226","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-04-17T06:03:39.472415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:03:39.057281Z","time spent":"414.980994ms","remote":"127.0.0.1:50226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:03:39.598905Z","caller":"traceutil/trace.go:171","msg":"trace[1879689512] transaction","detail":"{read_only:false; response_revision:1993; number_of_response:1; }","duration":"120.524738ms","start":"2026-04-17T06:03:39.478353Z","end":"2026-04-17T06:03:39.598878Z","steps":["trace[1879689512] 'process raft request' (duration: 120.485817ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:03:39.599297Z","caller":"traceutil/trace.go:171","msg":"trace[31420620] transaction","detail":"{read_only:false; response_revision:1988; number_of_response:1; }","duration":"121.343567ms","start":"2026-04-17T06:03:39.477908Z","end":"2026-04-17T06:03:39.599252Z","steps":["trace[31420620] 'process raft request' (duration: 120.59983ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:03:39.599389Z","caller":"traceutil/trace.go:171","msg":"trace[1394575292] transaction","detail":"{read_only:false; response_revision:1991; number_of_response:1; }","duration":"121.04433ms","start":"2026-04-17T06:03:39.478332Z","end":"2026-04-17T06:03:39.599377Z","steps":["trace[1394575292] 'process raft request' (duration: 120.403616ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:03:39.599441Z","caller":"traceutil/trace.go:171","msg":"trace[256816061] transaction","detail":"{read_only:false; response_revision:1989; number_of_response:1; }","duration":"121.365367ms","start":"2026-04-17T06:03:39.477994Z","end":"2026-04-17T06:03:39.599359Z","steps":["trace[256816061] 'process raft request' (duration: 120.663051ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:03:39.599751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.628287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:03:39.5998Z","caller":"traceutil/trace.go:171","msg":"trace[143364768] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1993; }","duration":"123.710029ms","start":"2026-04-17T06:03:39.476078Z","end":"2026-04-17T06:03:39.599788Z","steps":["trace[143364768] 'agreement among raft nodes before linearized reading' (duration: 123.623397ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:03:39.599828Z","caller":"traceutil/trace.go:171","msg":"trace[334840442] transaction","detail":"{read_only:false; response_revision:1992; number_of_response:1; }","duration":"121.390167ms","start":"2026-04-17T06:03:39.478343Z","end":"2026-04-17T06:03:39.599733Z","steps":["trace[334840442] 'process raft request' (duration: 120.461586ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:03:39.599699Z","caller":"traceutil/trace.go:171","msg":"trace[1551359406] transaction","detail":"{read_only:false; response_revision:1990; number_of_response:1; }","duration":"120.940208ms","start":"2026-04-17T06:03:39.478258Z","end":"2026-04-17T06:03:39.599199Z","steps":["trace[1551359406] 'process raft request' (duration: 120.438726ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:03:39.640109Z","caller":"traceutil/trace.go:171","msg":"trace[1329532329] transaction","detail":"{read_only:false; response_revision:1994; number_of_response:1; }","duration":"136.013323ms","start":"2026-04-17T06:03:39.504068Z","end":"2026-04-17T06:03:39.640081Z","steps":["trace[1329532329] 'process raft request' (duration: 135.851679ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:03:39.640143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.015567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:03:39.640213Z","caller":"traceutil/trace.go:171","msg":"trace[846192054] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1994; }","duration":"129.099849ms","start":"2026-04-17T06:03:39.511096Z","end":"2026-04-17T06:03:39.640196Z","steps":["trace[846192054] 'agreement among raft nodes before linearized reading' (duration: 128.962016ms)"],"step_count":1}