{"level":"warn","ts":"2026-04-17T04:08:22.430179Z","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-17T04:08:22.430319Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.57: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.57:2380","--initial-cluster=instance=https://199.204.45.57:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.57:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.57: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-17T04:08:22.430554Z","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-17T04:08:22.43057Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-04-17T04:08:22.430599Z","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-17T04:08:22.431648Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"]} {"level":"info","ts":"2026-04-17T04:08:22.431753Z","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.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.57: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-17T04:08:22.436176Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.104169ms"} {"level":"info","ts":"2026-04-17T04:08:22.445569Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b6478f0ac5aeb8e1","cluster-id":"6c58cff80d4a5b62"} {"level":"info","ts":"2026-04-17T04:08:22.445854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=()"} {"level":"info","ts":"2026-04-17T04:08:22.445924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 0"} {"level":"info","ts":"2026-04-17T04:08:22.445972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b6478f0ac5aeb8e1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-17T04:08:22.446072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 1"} {"level":"info","ts":"2026-04-17T04:08:22.446155Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"warn","ts":"2026-04-17T04:08:22.457822Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-17T04:08:22.459278Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-17T04:08:22.460401Z","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-17T04:08:22.462743Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b6478f0ac5aeb8e1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-17T04:08:22.463381Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b6478f0ac5aeb8e1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-17T04:08:22.463454Z","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-17T04:08:22.463591Z","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-17T04:08:22.463613Z","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-17T04:08:22.464921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"info","ts":"2026-04-17T04:08:22.465628Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","added-peer-id":"b6478f0ac5aeb8e1","added-peer-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-04-17T04:08:22.469035Z","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-17T04:08:22.469217Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-04-17T04:08:22.469318Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-04-17T04:08:22.469611Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b6478f0ac5aeb8e1","initial-advertise-peer-urls":["https://199.204.45.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-17T04:08:22.469794Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-17T04:08:23.048326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 is starting a new election at term 1"} {"level":"info","ts":"2026-04-17T04:08:23.048457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-17T04:08:23.048497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgPreVoteResp from b6478f0ac5aeb8e1 at term 1"} {"level":"info","ts":"2026-04-17T04:08:23.048521Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became candidate at term 2"} {"level":"info","ts":"2026-04-17T04:08:23.048532Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgVoteResp from b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-04-17T04:08:23.048551Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became leader at term 2"} {"level":"info","ts":"2026-04-17T04:08:23.048565Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b6478f0ac5aeb8e1 elected leader b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-04-17T04:08:23.049775Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b6478f0ac5aeb8e1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.57:2379]}","request-path":"/0/members/b6478f0ac5aeb8e1/attributes","cluster-id":"6c58cff80d4a5b62","publish-timeout":"7s"} {"level":"info","ts":"2026-04-17T04:08:23.049819Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T04:08:23.050037Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T04:08:23.049992Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T04:08:23.050168Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-17T04:08:23.050326Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-17T04:08:23.051516Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T04:08:23.051698Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T04:08:23.051791Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T04:08:23.053727Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-17T04:08:23.053857Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.57:2379"} {"level":"info","ts":"2026-04-17T04:08:27.89616Z","caller":"traceutil/trace.go:171","msg":"trace[1339052395] transaction","detail":"{read_only:false; response_revision:209; number_of_response:1; }","duration":"569.166283ms","start":"2026-04-17T04:08:27.326965Z","end":"2026-04-17T04:08:27.896131Z","steps":["trace[1339052395] 'process raft request' (duration: 568.99505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:08:27.896718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:08:27.326944Z","time spent":"569.309485ms","remote":"127.0.0.1:49226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T04:08:28.418606Z","caller":"traceutil/trace.go:171","msg":"trace[1393334188] transaction","detail":"{read_only:false; response_revision:210; number_of_response:1; }","duration":"1.086794817s","start":"2026-04-17T04:08:27.331787Z","end":"2026-04-17T04:08:28.418582Z","steps":["trace[1393334188] 'process raft request' (duration: 1.079299761s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:08:28.418989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:08:27.331756Z","time spent":"1.086920979s","remote":"127.0.0.1:49214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4002,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T04:08:28.419163Z","caller":"traceutil/trace.go:171","msg":"trace[1706494797] transaction","detail":"{read_only:false; response_revision:211; number_of_response:1; }","duration":"532.060355ms","start":"2026-04-17T04:08:27.887089Z","end":"2026-04-17T04:08:28.419149Z","steps":["trace[1706494797] 'process raft request' (duration: 531.355012ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:08:28.419115Z","caller":"traceutil/trace.go:171","msg":"trace[53269220] transaction","detail":"{read_only:false; response_revision:212; number_of_response:1; }","duration":"442.677612ms","start":"2026-04-17T04:08:27.976409Z","end":"2026-04-17T04:08:28.419087Z","steps":["trace[53269220] 'process raft request' (duration: 442.515119ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:08:28.41937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:08:27.97637Z","time spent":"442.908357ms","remote":"127.0.0.1:49226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3959,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T04:08:28.419223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:08:27.887049Z","time spent":"532.150527ms","remote":"127.0.0.1:49226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T04:08:28.419769Z","caller":"traceutil/trace.go:171","msg":"trace[1340741691] linearizableReadLoop","detail":"{readStateIndex:215; appliedIndex:213; }","duration":"534.677007ms","start":"2026-04-17T04:08:27.883973Z","end":"2026-04-17T04:08:28.41865Z","steps":["trace[1340741691] 'read index received' (duration: 11.951153ms)","trace[1340741691] 'applied index is now lower than readState.Index' (duration: 522.724384ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:08:28.420115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.42328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:08:28.42024Z","caller":"traceutil/trace.go:171","msg":"trace[804916282] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:212; }","duration":"413.600084ms","start":"2026-04-17T04:08:28.006627Z","end":"2026-04-17T04:08:28.420227Z","steps":["trace[804916282] 'agreement among raft nodes before linearized reading' (duration: 413.39424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:08:28.420365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:08:28.006596Z","time spent":"413.757086ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T04:08:28.420181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.156724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:08:28.420662Z","caller":"traceutil/trace.go:171","msg":"trace[333285062] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:212; }","duration":"536.722155ms","start":"2026-04-17T04:08:27.883909Z","end":"2026-04-17T04:08:28.420631Z","steps":["trace[333285062] 'agreement among raft nodes before linearized reading' (duration: 535.885729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:08:28.420732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:08:27.883884Z","time spent":"536.830378ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T04:08:29.163215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.516624ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:08:29.16347Z","caller":"traceutil/trace.go:171","msg":"trace[216191263] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"245.002916ms","start":"2026-04-17T04:08:28.918427Z","end":"2026-04-17T04:08:29.16343Z","steps":["trace[216191263] 'process raft request' (duration: 63.010761ms)","trace[216191263] 'compare' (duration: 181.219929ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:08:29.163675Z","caller":"traceutil/trace.go:171","msg":"trace[1698388186] transaction","detail":"{read_only:false; number_of_response:0; response_revision:260; }","duration":"154.52504ms","start":"2026-04-17T04:08:29.009139Z","end":"2026-04-17T04:08:29.163664Z","steps":["trace[1698388186] 'process raft request' (duration: 154.50027ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:08:29.163799Z","caller":"traceutil/trace.go:171","msg":"trace[856981001] transaction","detail":"{read_only:false; number_of_response:0; response_revision:259; }","duration":"154.962758ms","start":"2026-04-17T04:08:29.00881Z","end":"2026-04-17T04:08:29.163772Z","steps":["trace[856981001] 'process raft request' (duration: 154.704413ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:08:29.163863Z","caller":"traceutil/trace.go:171","msg":"trace[1242830234] transaction","detail":"{read_only:false; number_of_response:0; response_revision:260; }","duration":"154.754414ms","start":"2026-04-17T04:08:29.009099Z","end":"2026-04-17T04:08:29.163854Z","steps":["trace[1242830234] 'process raft request' (duration: 154.517609ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:08:29.163823Z","caller":"traceutil/trace.go:171","msg":"trace[917121882] transaction","detail":"{read_only:false; response_revision:259; number_of_response:1; }","duration":"171.48347ms","start":"2026-04-17T04:08:28.992298Z","end":"2026-04-17T04:08:29.163781Z","steps":["trace[917121882] 'process raft request' (duration: 171.077493ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:08:29.163887Z","caller":"traceutil/trace.go:171","msg":"trace[791814471] transaction","detail":"{read_only:false; number_of_response:0; response_revision:260; }","duration":"154.812165ms","start":"2026-04-17T04:08:29.009044Z","end":"2026-04-17T04:08:29.163856Z","steps":["trace[791814471] 'process raft request' (duration: 154.55244ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:08:29.163944Z","caller":"traceutil/trace.go:171","msg":"trace[1840111843] transaction","detail":"{read_only:false; response_revision:260; number_of_response:1; }","duration":"155.002269ms","start":"2026-04-17T04:08:29.008924Z","end":"2026-04-17T04:08:29.163926Z","steps":["trace[1840111843] 'process raft request' (duration: 154.630882ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:08:29.163968Z","caller":"traceutil/trace.go:171","msg":"trace[450018850] linearizableReadLoop","detail":"{readStateIndex:264; appliedIndex:263; }","duration":"232.548472ms","start":"2026-04-17T04:08:28.931397Z","end":"2026-04-17T04:08:29.163945Z","steps":["trace[450018850] 'read index received' (duration: 49.962265ms)","trace[450018850] 'applied index is now lower than readState.Index' (duration: 182.583627ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:08:29.163941Z","caller":"traceutil/trace.go:171","msg":"trace[943246042] transaction","detail":"{read_only:false; number_of_response:0; response_revision:259; }","duration":"171.368418ms","start":"2026-04-17T04:08:28.992519Z","end":"2026-04-17T04:08:29.163887Z","steps":["trace[943246042] 'process raft request' (duration: 170.948309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:08:29.164151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.723195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:08:29.164196Z","caller":"traceutil/trace.go:171","msg":"trace[1304784304] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:0; response_revision:260; }","duration":"232.816507ms","start":"2026-04-17T04:08:28.931362Z","end":"2026-04-17T04:08:29.164178Z","steps":["trace[1304784304] 'agreement among raft nodes before linearized reading' (duration: 232.662814ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:08:29.710103Z","caller":"traceutil/trace.go:171","msg":"trace[586178945] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"126.871659ms","start":"2026-04-17T04:08:29.583185Z","end":"2026-04-17T04:08:29.710057Z","steps":["trace[586178945] 'process raft request' (duration: 126.600454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:08:30.096517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.114781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3974"} {"level":"info","ts":"2026-04-17T04:08:30.096664Z","caller":"traceutil/trace.go:171","msg":"trace[109007970] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:270; }","duration":"116.310164ms","start":"2026-04-17T04:08:29.98033Z","end":"2026-04-17T04:08:30.09664Z","steps":["trace[109007970] 'agreement among raft nodes before linearized reading' (duration: 116.056089ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:08:52.861642Z","caller":"traceutil/trace.go:171","msg":"trace[1799337988] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"214.5556ms","start":"2026-04-17T04:08:52.647058Z","end":"2026-04-17T04:08:52.861613Z","steps":["trace[1799337988] 'process raft request' (duration: 204.214015ms)","trace[1799337988] 'compare' (duration: 10.224913ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:08:52.862749Z","caller":"traceutil/trace.go:171","msg":"trace[1844217317] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"209.984858ms","start":"2026-04-17T04:08:52.652733Z","end":"2026-04-17T04:08:52.862718Z","steps":["trace[1844217317] 'process raft request' (duration: 209.687402ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:08:52.86321Z","caller":"traceutil/trace.go:171","msg":"trace[1753075915] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"206.585073ms","start":"2026-04-17T04:08:52.656612Z","end":"2026-04-17T04:08:52.863197Z","steps":["trace[1753075915] 'process raft request' (duration: 206.048101ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:08:58.441464Z","caller":"traceutil/trace.go:171","msg":"trace[656317834] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"206.9032ms","start":"2026-04-17T04:08:58.234522Z","end":"2026-04-17T04:08:58.441425Z","steps":["trace[656317834] 'process raft request' (duration: 206.118595ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:08:58.817106Z","caller":"traceutil/trace.go:171","msg":"trace[1435191737] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"317.094769ms","start":"2026-04-17T04:08:58.49993Z","end":"2026-04-17T04:08:58.817024Z","steps":["trace[1435191737] 'process raft request' (duration: 288.4307ms)","trace[1435191737] 'compare' (duration: 28.459075ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:08:58.817348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:08:58.499907Z","time spent":"317.317463ms","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T04:08:58.817467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.092224ms","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-17T04:08:58.81756Z","caller":"traceutil/trace.go:171","msg":"trace[591327643] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:572; }","duration":"204.246647ms","start":"2026-04-17T04:08:58.613294Z","end":"2026-04-17T04:08:58.817541Z","steps":["trace[591327643] 'agreement among raft nodes before linearized reading' (duration: 203.825429ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:08:58.817079Z","caller":"traceutil/trace.go:171","msg":"trace[1644073636] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:594; }","duration":"203.637305ms","start":"2026-04-17T04:08:58.613344Z","end":"2026-04-17T04:08:58.816981Z","steps":["trace[1644073636] 'read index received' (duration: 175.003247ms)","trace[1644073636] 'applied index is now lower than readState.Index' (duration: 28.632498ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:08:59.994138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.608973ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:08:59.994249Z","caller":"traceutil/trace.go:171","msg":"trace[675484085] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"1.100608412s","start":"2026-04-17T04:08:58.893622Z","end":"2026-04-17T04:08:59.994231Z","steps":["trace[675484085] 'process raft request' (duration: 635.808665ms)","trace[675484085] 'compare' (duration: 464.392009ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:08:59.99431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:08:58.893596Z","time spent":"1.100686203s","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T04:08:59.999761Z","caller":"traceutil/trace.go:171","msg":"trace[7647373] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"1.103428148s","start":"2026-04-17T04:08:58.896278Z","end":"2026-04-17T04:08:59.999706Z","steps":["trace[7647373] 'process raft request' (duration: 1.103151452s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:08:59.999974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:08:58.896266Z","time spent":"1.103589481s","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T04:09:00.000825Z","caller":"traceutil/trace.go:171","msg":"trace[1038726677] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"717.300886ms","start":"2026-04-17T04:08:59.283479Z","end":"2026-04-17T04:09:00.00078Z","steps":["trace[1038726677] 'process raft request' (duration: 716.428908ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:09:00.001054Z","caller":"traceutil/trace.go:171","msg":"trace[2141243703] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"682.173838ms","start":"2026-04-17T04:08:59.318865Z","end":"2026-04-17T04:09:00.001038Z","steps":["trace[2141243703] 'process raft request' (duration: 681.831201ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:09:00.001062Z","caller":"traceutil/trace.go:171","msg":"trace[757026652] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:596; }","duration":"950.294756ms","start":"2026-04-17T04:08:59.050725Z","end":"2026-04-17T04:09:00.001019Z","steps":["trace[757026652] 'read index received' (duration: 478.738745ms)","trace[757026652] 'applied index is now lower than readState.Index' (duration: 471.552631ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:09:00.001114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:08:59.318834Z","time spent":"682.250379ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:09:00.001366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"950.631913ms","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-17T04:09:00.001451Z","caller":"traceutil/trace.go:171","msg":"trace[1445512117] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:577; }","duration":"950.772835ms","start":"2026-04-17T04:08:59.050656Z","end":"2026-04-17T04:09:00.001429Z","steps":["trace[1445512117] 'agreement among raft nodes before linearized reading' (duration: 950.47895ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:09:00.001506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:08:59.050625Z","time spent":"950.868488ms","remote":"127.0.0.1:49292","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-17T04:09:00.001091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:08:59.283454Z","time spent":"717.476ms","remote":"127.0.0.1:49174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036201,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:09:00.011445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.998043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:09:00.011578Z","caller":"traceutil/trace.go:171","msg":"trace[1182244934] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:578; }","duration":"206.174417ms","start":"2026-04-17T04:08:59.805385Z","end":"2026-04-17T04:09:00.01156Z","steps":["trace[1182244934] 'agreement among raft nodes before linearized reading' (duration: 205.965433ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:09:00.011915Z","caller":"traceutil/trace.go:171","msg":"trace[2112928671] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"361.322201ms","start":"2026-04-17T04:08:59.650581Z","end":"2026-04-17T04:09:00.011903Z","steps":["trace[2112928671] 'process raft request' (duration: 360.632976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:09:00.012015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:08:59.65055Z","time spent":"361.390292ms","remote":"127.0.0.1:49214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4538,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T04:09:13.718649Z","caller":"traceutil/trace.go:171","msg":"trace[287710816] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"106.168992ms","start":"2026-04-17T04:09:13.612449Z","end":"2026-04-17T04:09:13.718618Z","steps":["trace[287710816] 'process raft request' (duration: 105.933077ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:09:16.581657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.585832ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T04:09:16.581938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"842.174225ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:09:16.58205Z","caller":"traceutil/trace.go:171","msg":"trace[1085463066] linearizableReadLoop","detail":"{readStateIndex:766; appliedIndex:765; }","duration":"495.080493ms","start":"2026-04-17T04:09:16.086957Z","end":"2026-04-17T04:09:16.582037Z","steps":["trace[1085463066] 'read index received' (duration: 50.201µs)","trace[1085463066] 'applied index is now lower than readState.Index' (duration: 495.028782ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:09:16.582139Z","caller":"traceutil/trace.go:171","msg":"trace[1865008163] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"852.38447ms","start":"2026-04-17T04:09:15.729745Z","end":"2026-04-17T04:09:16.58213Z","steps":["trace[1865008163] 'compare' (duration: 842.076133ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:09:16.5822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:09:15.729729Z","time spent":"852.440091ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:09:16.582441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.771917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-17T04:09:16.582537Z","caller":"traceutil/trace.go:171","msg":"trace[753846226] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:739; }","duration":"470.9205ms","start":"2026-04-17T04:09:16.111592Z","end":"2026-04-17T04:09:16.582513Z","steps":["trace[753846226] 'agreement among raft nodes before linearized reading' (duration: 470.662425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:09:16.582613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:09:16.111567Z","time spent":"471.035392ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T04:09:16.582867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.90944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-17T04:09:16.582878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.775897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.57\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-17T04:09:16.58294Z","caller":"traceutil/trace.go:171","msg":"trace[1312671583] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:739; }","duration":"496.011002ms","start":"2026-04-17T04:09:16.086898Z","end":"2026-04-17T04:09:16.582909Z","steps":["trace[1312671583] 'agreement among raft nodes before linearized reading' (duration: 495.845279ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:09:16.582941Z","caller":"traceutil/trace.go:171","msg":"trace[1594718991] range","detail":"{range_begin:/registry/masterleases/199.204.45.57; range_end:; response_count:1; response_revision:739; }","duration":"455.87252ms","start":"2026-04-17T04:09:16.12705Z","end":"2026-04-17T04:09:16.582923Z","steps":["trace[1594718991] 'agreement among raft nodes before linearized reading' (duration: 455.661476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:09:16.583003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:09:16.12703Z","time spent":"455.963001ms","remote":"127.0.0.1:49108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.57\" "} {"level":"warn","ts":"2026-04-17T04:09:16.583005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.92565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-x67dv\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-04-17T04:09:16.5831Z","caller":"traceutil/trace.go:171","msg":"trace[1769103628] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-x67dv; range_end:; response_count:1; response_revision:739; }","duration":"462.103674ms","start":"2026-04-17T04:09:16.12098Z","end":"2026-04-17T04:09:16.583083Z","steps":["trace[1769103628] 'agreement among raft nodes before linearized reading' (duration: 461.89379ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:09:16.583126Z","caller":"traceutil/trace.go:171","msg":"trace[251133207] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:738; }","duration":"145.882838ms","start":"2026-04-17T04:09:16.43603Z","end":"2026-04-17T04:09:16.581913Z","steps":["trace[251133207] 'range keys from in-memory index tree' (duration: 145.562702ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:09:16.583165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:09:16.120958Z","time spent":"462.196496ms","remote":"127.0.0.1:49226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-x67dv\" "} {"level":"warn","ts":"2026-04-17T04:09:16.583007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:09:16.086878Z","time spent":"496.114454ms","remote":"127.0.0.1:49292","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-17T04:09:16.582975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.140225ms","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-17T04:09:16.583328Z","caller":"traceutil/trace.go:171","msg":"trace[527230922] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:739; }","duration":"458.540113ms","start":"2026-04-17T04:09:16.124763Z","end":"2026-04-17T04:09:16.583304Z","steps":["trace[527230922] 'agreement among raft nodes before linearized reading' (duration: 458.004673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:09:16.583368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:09:16.12474Z","time spent":"458.615864ms","remote":"127.0.0.1:49292","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-17T04:10:19.37114Z","caller":"traceutil/trace.go:171","msg":"trace[493893362] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"465.022809ms","start":"2026-04-17T04:10:18.906083Z","end":"2026-04-17T04:10:19.371106Z","steps":["trace[493893362] 'process raft request' (duration: 464.811635ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:10:19.371241Z","caller":"traceutil/trace.go:171","msg":"trace[1254642972] linearizableReadLoop","detail":"{readStateIndex:1082; appliedIndex:1081; }","duration":"400.654243ms","start":"2026-04-17T04:10:18.970567Z","end":"2026-04-17T04:10:19.371221Z","steps":["trace[1254642972] 'read index received' (duration: 400.394378ms)","trace[1254642972] 'applied index is now lower than readState.Index' (duration: 258.415µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:10:19.371301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:10:18.905967Z","time spent":"465.248173ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:10:19.3714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.820367ms","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-17T04:10:19.371424Z","caller":"traceutil/trace.go:171","msg":"trace[652590814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1042; }","duration":"400.895988ms","start":"2026-04-17T04:10:18.970521Z","end":"2026-04-17T04:10:19.371417Z","steps":["trace[652590814] 'agreement among raft nodes before linearized reading' (duration: 400.787246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:10:19.371448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:10:18.970503Z","time spent":"400.93845ms","remote":"127.0.0.1:49292","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-17T04:10:19.372742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.008928ms","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-17T04:10:19.372809Z","caller":"traceutil/trace.go:171","msg":"trace[1667067635] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1043; }","duration":"392.104219ms","start":"2026-04-17T04:10:18.980688Z","end":"2026-04-17T04:10:19.372792Z","steps":["trace[1667067635] 'agreement among raft nodes before linearized reading' (duration: 391.921345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:10:19.372853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:10:18.980674Z","time spent":"392.16993ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-17T04:10:19.373131Z","caller":"traceutil/trace.go:171","msg":"trace[2094284006] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"131.490116ms","start":"2026-04-17T04:10:19.241626Z","end":"2026-04-17T04:10:19.373116Z","steps":["trace[2094284006] 'process raft request' (duration: 130.866552ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:10:19.373409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.178767ms","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-17T04:10:19.373449Z","caller":"traceutil/trace.go:171","msg":"trace[1899656091] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1043; }","duration":"126.220728ms","start":"2026-04-17T04:10:19.247218Z","end":"2026-04-17T04:10:19.373439Z","steps":["trace[1899656091] 'agreement among raft nodes before linearized reading' (duration: 126.084375ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:10:19.373605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.616239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T04:10:19.373641Z","caller":"traceutil/trace.go:171","msg":"trace[322756744] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1043; }","duration":"295.68203ms","start":"2026-04-17T04:10:19.07795Z","end":"2026-04-17T04:10:19.373632Z","steps":["trace[322756744] 'agreement among raft nodes before linearized reading' (duration: 295.615849ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:10:19.373818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.896576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-17T04:10:19.373855Z","caller":"traceutil/trace.go:171","msg":"trace[1534668113] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1043; }","duration":"392.952306ms","start":"2026-04-17T04:10:18.980892Z","end":"2026-04-17T04:10:19.373844Z","steps":["trace[1534668113] 'agreement among raft nodes before linearized reading' (duration: 392.867045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:10:19.373891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:10:18.980882Z","time spent":"392.998017ms","remote":"127.0.0.1:49292","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-17T04:10:42.604416Z","caller":"traceutil/trace.go:171","msg":"trace[1959391844] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"241.79417ms","start":"2026-04-17T04:10:42.362585Z","end":"2026-04-17T04:10:42.604379Z","steps":["trace[1959391844] 'process raft request' (duration: 241.638056ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:10:43.894354Z","caller":"traceutil/trace.go:171","msg":"trace[564844372] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"331.767949ms","start":"2026-04-17T04:10:43.562558Z","end":"2026-04-17T04:10:43.894326Z","steps":["trace[564844372] 'process raft request' (duration: 306.490893ms)","trace[564844372] 'compare' (duration: 25.179764ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:10:43.894616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:10:43.562544Z","time spent":"331.981214ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:10:44.885416Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.016269677s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-17T04:10:45.080456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.706108ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:10:45.080562Z","caller":"traceutil/trace.go:171","msg":"trace[282931980] linearizableReadLoop","detail":"{readStateIndex:1168; appliedIndex:1166; }","duration":"1.47844506s","start":"2026-04-17T04:10:43.602076Z","end":"2026-04-17T04:10:45.080521Z","steps":["trace[282931980] 'read index received' (duration: 267.083377ms)","trace[282931980] 'applied index is now lower than readState.Index' (duration: 1.211360623s)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:10:45.080814Z","caller":"traceutil/trace.go:171","msg":"trace[1983239794] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"1.516935067s","start":"2026-04-17T04:10:43.563864Z","end":"2026-04-17T04:10:45.080799Z","steps":["trace[1983239794] 'process raft request' (duration: 1.321821581s)","trace[1983239794] 'compare' (duration: 194.33172ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:10:45.080932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:10:43.56374Z","time spent":"1.517110101s","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:10:45.081165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.475664784s","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-17T04:10:45.08125Z","caller":"traceutil/trace.go:171","msg":"trace[368699571] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1123; }","duration":"1.475817437s","start":"2026-04-17T04:10:43.60541Z","end":"2026-04-17T04:10:45.081228Z","steps":["trace[368699571] 'agreement among raft nodes before linearized reading' (duration: 1.475582612s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:10:45.081321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:10:43.605388Z","time spent":"1.47592501s","remote":"127.0.0.1:49292","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-17T04:10:45.081477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.47940086s","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-17T04:10:45.081508Z","caller":"traceutil/trace.go:171","msg":"trace[1248054018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1123; }","duration":"1.479466632s","start":"2026-04-17T04:10:43.602033Z","end":"2026-04-17T04:10:45.0815Z","steps":["trace[1248054018] 'agreement among raft nodes before linearized reading' (duration: 1.47938731s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:10:45.081534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:10:43.602021Z","time spent":"1.479507033s","remote":"127.0.0.1:49292","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-17T04:10:45.081555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.756778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-17T04:10:45.081581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.167795033s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:10:45.081616Z","caller":"traceutil/trace.go:171","msg":"trace[1003669514] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1123; }","duration":"120.85701ms","start":"2026-04-17T04:10:44.960741Z","end":"2026-04-17T04:10:45.081598Z","steps":["trace[1003669514] 'agreement among raft nodes before linearized reading' (duration: 120.750498ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:10:45.081614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.275578586s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:10:45.081679Z","caller":"traceutil/trace.go:171","msg":"trace[62734890] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1123; }","duration":"1.168013058s","start":"2026-04-17T04:10:43.913635Z","end":"2026-04-17T04:10:45.081648Z","steps":["trace[62734890] 'agreement among raft nodes before linearized reading' (duration: 1.167801444s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:10:45.081742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:10:43.913603Z","time spent":"1.16812665s","remote":"127.0.0.1:36862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-17T04:10:45.08168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.475507991s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-17T04:10:45.081856Z","caller":"traceutil/trace.go:171","msg":"trace[1878165768] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1123; }","duration":"1.475694655s","start":"2026-04-17T04:10:43.606152Z","end":"2026-04-17T04:10:45.081846Z","steps":["trace[1878165768] 'agreement among raft nodes before linearized reading' (duration: 1.475481271s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:10:45.081888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:10:43.606143Z","time spent":"1.475736405s","remote":"127.0.0.1:49292","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-17T04:10:45.081851Z","caller":"traceutil/trace.go:171","msg":"trace[327904484] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1123; }","duration":"1.275739879s","start":"2026-04-17T04:10:43.805987Z","end":"2026-04-17T04:10:45.081727Z","steps":["trace[327904484] 'agreement among raft nodes before linearized reading' (duration: 1.275608727s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:10:45.081986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:10:43.805961Z","time spent":"1.275979375s","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T04:10:46.606748Z","caller":"traceutil/trace.go:171","msg":"trace[1632009415] linearizableReadLoop","detail":"{readStateIndex:1173; appliedIndex:1172; }","duration":"475.588166ms","start":"2026-04-17T04:10:46.131133Z","end":"2026-04-17T04:10:46.606721Z","steps":["trace[1632009415] 'read index received' (duration: 475.336301ms)","trace[1632009415] 'applied index is now lower than readState.Index' (duration: 250.965µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:10:46.606783Z","caller":"traceutil/trace.go:171","msg":"trace[1673550974] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"702.006392ms","start":"2026-04-17T04:10:45.904759Z","end":"2026-04-17T04:10:46.606765Z","steps":["trace[1673550974] 'process raft request' (duration: 701.776648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:10:46.606915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:10:45.904742Z","time spent":"702.099135ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:10:46.606992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.841671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.57\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-17T04:10:46.607075Z","caller":"traceutil/trace.go:171","msg":"trace[336671283] range","detail":"{range_begin:/registry/masterleases/199.204.45.57; range_end:; response_count:1; response_revision:1128; }","duration":"475.980974ms","start":"2026-04-17T04:10:46.131075Z","end":"2026-04-17T04:10:46.607056Z","steps":["trace[336671283] 'agreement among raft nodes before linearized reading' (duration: 475.76003ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:10:46.607121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:10:46.131057Z","time spent":"476.055785ms","remote":"127.0.0.1:49108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.57\" "} {"level":"info","ts":"2026-04-17T04:10:46.81364Z","caller":"traceutil/trace.go:171","msg":"trace[1985613317] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"157.554899ms","start":"2026-04-17T04:10:46.656045Z","end":"2026-04-17T04:10:46.8136Z","steps":["trace[1985613317] 'process raft request' (duration: 157.397386ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:10:47.303821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.321551ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:10:47.304044Z","caller":"traceutil/trace.go:171","msg":"trace[1450432524] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"161.658423ms","start":"2026-04-17T04:10:47.142366Z","end":"2026-04-17T04:10:47.304025Z","steps":["trace[1450432524] 'process raft request' (duration: 161.618222ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:10:47.304085Z","caller":"traceutil/trace.go:171","msg":"trace[750537849] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"162.243784ms","start":"2026-04-17T04:10:47.1418Z","end":"2026-04-17T04:10:47.304043Z","steps":["trace[750537849] 'process raft request' (duration: 56.602336ms)","trace[750537849] 'compare' (duration: 105.178609ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:10:47.30411Z","caller":"traceutil/trace.go:171","msg":"trace[1208652250] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"161.931458ms","start":"2026-04-17T04:10:47.142145Z","end":"2026-04-17T04:10:47.304076Z","steps":["trace[1208652250] 'process raft request' (duration: 161.791695ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:14:46.093997Z","caller":"traceutil/trace.go:171","msg":"trace[1902127472] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"130.253542ms","start":"2026-04-17T04:14:45.963715Z","end":"2026-04-17T04:14:46.093969Z","steps":["trace[1902127472] 'process raft request' (duration: 129.931194ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:14:54.944385Z","caller":"traceutil/trace.go:171","msg":"trace[975698562] linearizableReadLoop","detail":"{readStateIndex:2042; appliedIndex:2040; }","duration":"140.156672ms","start":"2026-04-17T04:14:54.804207Z","end":"2026-04-17T04:14:54.944364Z","steps":["trace[975698562] 'read index received' (duration: 27.485626ms)","trace[975698562] 'applied index is now lower than readState.Index' (duration: 112.669716ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:14:54.944405Z","caller":"traceutil/trace.go:171","msg":"trace[879162760] transaction","detail":"{read_only:false; response_revision:1947; number_of_response:1; }","duration":"152.678009ms","start":"2026-04-17T04:14:54.7917Z","end":"2026-04-17T04:14:54.944378Z","steps":["trace[879162760] 'process raft request' (duration: 152.531016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:14:54.944511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.293716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:14:54.944538Z","caller":"traceutil/trace.go:171","msg":"trace[99087046] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1947; }","duration":"140.392538ms","start":"2026-04-17T04:14:54.804137Z","end":"2026-04-17T04:14:54.944529Z","steps":["trace[99087046] 'agreement among raft nodes before linearized reading' (duration: 140.308926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:15:01.622502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.405993ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:15:01.622673Z","caller":"traceutil/trace.go:171","msg":"trace[91167333] transaction","detail":"{read_only:false; response_revision:1967; number_of_response:1; }","duration":"651.662637ms","start":"2026-04-17T04:15:00.970987Z","end":"2026-04-17T04:15:01.622649Z","steps":["trace[91167333] 'process raft request' (duration: 48.011047ms)","trace[91167333] 'compare' (duration: 603.063505ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:15:01.622743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:15:00.970971Z","time spent":"651.736138ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:15:02.191522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.517359ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-17T04:15:02.192319Z","caller":"traceutil/trace.go:171","msg":"trace[1361407895] linearizableReadLoop","detail":"{readStateIndex:2064; appliedIndex:2063; }","duration":"679.905012ms","start":"2026-04-17T04:15:01.512333Z","end":"2026-04-17T04:15:02.192238Z","steps":["trace[1361407895] 'read index received' (duration: 127.639795ms)","trace[1361407895] 'applied index is now lower than readState.Index' (duration: 552.165615ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:15:02.192926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.586601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:15:02.192992Z","caller":"traceutil/trace.go:171","msg":"trace[48693301] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1967; }","duration":"680.681323ms","start":"2026-04-17T04:15:01.51228Z","end":"2026-04-17T04:15:02.192961Z","steps":["trace[48693301] 'agreement among raft nodes before linearized reading' (duration: 680.417626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:15:02.193031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:15:01.51223Z","time spent":"680.791326ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T04:15:02.193502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.034356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:15:02.193552Z","caller":"traceutil/trace.go:171","msg":"trace[257740417] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1967; }","duration":"491.112918ms","start":"2026-04-17T04:15:01.702415Z","end":"2026-04-17T04:15:02.193527Z","steps":["trace[257740417] 'agreement among raft nodes before linearized reading' (duration: 490.668217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:15:02.193585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:15:01.702393Z","time spent":"491.18216ms","remote":"127.0.0.1:49446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-04-17T04:15:02.193698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.010427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:15:02.193809Z","caller":"traceutil/trace.go:171","msg":"trace[1134151322] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1967; }","duration":"392.13975ms","start":"2026-04-17T04:15:01.801634Z","end":"2026-04-17T04:15:02.193774Z","steps":["trace[1134151322] 'agreement among raft nodes before linearized reading' (duration: 391.936725ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:15:02.193875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:15:01.801583Z","time spent":"392.279593ms","remote":"127.0.0.1:49058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T04:15:02.971451Z","caller":"traceutil/trace.go:171","msg":"trace[1259743785] linearizableReadLoop","detail":"{readStateIndex:2065; appliedIndex:2064; }","duration":"431.856997ms","start":"2026-04-17T04:15:02.539572Z","end":"2026-04-17T04:15:02.971429Z","steps":["trace[1259743785] 'read index received' (duration: 431.783035ms)","trace[1259743785] 'applied index is now lower than readState.Index' (duration: 72.862µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:15:02.971571Z","caller":"traceutil/trace.go:171","msg":"trace[286439851] transaction","detail":"{read_only:false; response_revision:1968; number_of_response:1; }","duration":"766.335954ms","start":"2026-04-17T04:15:02.20519Z","end":"2026-04-17T04:15:02.971526Z","steps":["trace[286439851] 'process raft request' (duration: 766.067697ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:15:02.971786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:15:02.205169Z","time spent":"766.511339ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:15:02.971793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.225176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-17T04:15:02.971848Z","caller":"traceutil/trace.go:171","msg":"trace[724283846] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1968; }","duration":"432.281118ms","start":"2026-04-17T04:15:02.539552Z","end":"2026-04-17T04:15:02.971834Z","steps":["trace[724283846] 'agreement among raft nodes before linearized reading' (duration: 432.035672ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:15:02.971872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:15:02.539467Z","time spent":"432.4ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T04:15:04.325798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"917.709731ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:15:04.325926Z","caller":"traceutil/trace.go:171","msg":"trace[710056796] linearizableReadLoop","detail":"{readStateIndex:2066; appliedIndex:2065; }","duration":"1.354313536s","start":"2026-04-17T04:15:02.971593Z","end":"2026-04-17T04:15:04.325906Z","steps":["trace[710056796] 'read index received' (duration: 436.424541ms)","trace[710056796] 'applied index is now lower than readState.Index' (duration: 917.887665ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:15:04.326161Z","caller":"traceutil/trace.go:171","msg":"trace[865636151] transaction","detail":"{read_only:false; response_revision:1969; number_of_response:1; }","duration":"1.638935691s","start":"2026-04-17T04:15:02.687213Z","end":"2026-04-17T04:15:04.326149Z","steps":["trace[865636151] 'process raft request' (duration: 720.775709ms)","trace[865636151] 'compare' (duration: 917.532026ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:15:04.32622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:15:02.687193Z","time spent":"1.638996482s","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:15:04.326536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.391055217s","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-17T04:15:04.326566Z","caller":"traceutil/trace.go:171","msg":"trace[1411118322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1969; }","duration":"1.391113909s","start":"2026-04-17T04:15:02.935442Z","end":"2026-04-17T04:15:04.326556Z","steps":["trace[1411118322] 'agreement among raft nodes before linearized reading' (duration: 1.391002246s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:15:04.326614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:15:02.935417Z","time spent":"1.39117021s","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-17T04:15:04.326884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.522697664s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:15:04.326991Z","caller":"traceutil/trace.go:171","msg":"trace[736385684] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1969; }","duration":"1.522838287s","start":"2026-04-17T04:15:02.804127Z","end":"2026-04-17T04:15:04.326965Z","steps":["trace[736385684] 'agreement among raft nodes before linearized reading' (duration: 1.522468198s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:15:04.327009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.586268245s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-17T04:15:04.327058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:15:02.804115Z","time spent":"1.52293307s","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T04:15:04.327094Z","caller":"traceutil/trace.go:171","msg":"trace[210344851] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1969; }","duration":"1.5864177s","start":"2026-04-17T04:15:02.740656Z","end":"2026-04-17T04:15:04.327073Z","steps":["trace[210344851] 'agreement among raft nodes before linearized reading' (duration: 1.586156482s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:15:04.32715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:15:02.740637Z","time spent":"1.586504063s","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-17T04:15:04.327926Z","caller":"traceutil/trace.go:171","msg":"trace[2061570491] transaction","detail":"{read_only:false; response_revision:1970; number_of_response:1; }","duration":"1.351060293s","start":"2026-04-17T04:15:02.976833Z","end":"2026-04-17T04:15:04.327894Z","steps":["trace[2061570491] 'process raft request' (duration: 1.350950891s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:15:04.328043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:15:02.976806Z","time spent":"1.351166206s","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:15:04.329982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.915482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-17T04:15:04.330003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.636449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:15:04.330082Z","caller":"traceutil/trace.go:171","msg":"trace[162526181] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1971; }","duration":"470.763392ms","start":"2026-04-17T04:15:03.859296Z","end":"2026-04-17T04:15:04.330059Z","steps":["trace[162526181] 'agreement among raft nodes before linearized reading' (duration: 470.641769ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:15:04.330135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:15:03.859272Z","time spent":"470.852144ms","remote":"127.0.0.1:49328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-17T04:15:04.330238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.4767ms","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-17T04:15:04.330273Z","caller":"traceutil/trace.go:171","msg":"trace[840351125] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1971; }","duration":"703.547812ms","start":"2026-04-17T04:15:03.626716Z","end":"2026-04-17T04:15:04.330264Z","steps":["trace[840351125] 'agreement among raft nodes before linearized reading' (duration: 703.421188ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:15:04.330306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:15:03.626689Z","time spent":"703.607773ms","remote":"127.0.0.1:49292","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-17T04:15:04.330371Z","caller":"traceutil/trace.go:171","msg":"trace[880434064] transaction","detail":"{read_only:false; response_revision:1971; number_of_response:1; }","duration":"875.04706ms","start":"2026-04-17T04:15:03.455308Z","end":"2026-04-17T04:15:04.330356Z","steps":["trace[880434064] 'process raft request' (duration: 874.376812ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:15:04.33048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:15:03.455277Z","time spent":"875.127421ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T04:15:04.330049Z","caller":"traceutil/trace.go:171","msg":"trace[1237147255] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1971; }","duration":"285.014165ms","start":"2026-04-17T04:15:04.045018Z","end":"2026-04-17T04:15:04.330032Z","steps":["trace[1237147255] 'agreement among raft nodes before linearized reading' (duration: 284.874301ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:15:27.245803Z","caller":"traceutil/trace.go:171","msg":"trace[76826983] linearizableReadLoop","detail":"{readStateIndex:2150; appliedIndex:2149; }","duration":"121.88078ms","start":"2026-04-17T04:15:27.123901Z","end":"2026-04-17T04:15:27.245782Z","steps":["trace[76826983] 'read index received' (duration: 121.676725ms)","trace[76826983] 'applied index is now lower than readState.Index' (duration: 203.155µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:15:27.246037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.084285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T04:15:27.246134Z","caller":"traceutil/trace.go:171","msg":"trace[1695934322] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:2048; }","duration":"122.263209ms","start":"2026-04-17T04:15:27.123858Z","end":"2026-04-17T04:15:27.246121Z","steps":["trace[1695934322] 'agreement among raft nodes before linearized reading' (duration: 122.087165ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:15:27.246269Z","caller":"traceutil/trace.go:171","msg":"trace[1449464768] transaction","detail":"{read_only:false; response_revision:2048; number_of_response:1; }","duration":"158.482397ms","start":"2026-04-17T04:15:27.087755Z","end":"2026-04-17T04:15:27.246237Z","steps":["trace[1449464768] 'process raft request' (duration: 157.876542ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:15:28.765454Z","caller":"traceutil/trace.go:171","msg":"trace[430518449] linearizableReadLoop","detail":"{readStateIndex:2151; appliedIndex:2150; }","duration":"315.274971ms","start":"2026-04-17T04:15:28.450146Z","end":"2026-04-17T04:15:28.765421Z","steps":["trace[430518449] 'read index received' (duration: 314.908811ms)","trace[430518449] 'applied index is now lower than readState.Index' (duration: 365.05µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:15:28.765683Z","caller":"traceutil/trace.go:171","msg":"trace[1934024303] transaction","detail":"{read_only:false; response_revision:2049; number_of_response:1; }","duration":"358.64169ms","start":"2026-04-17T04:15:28.407024Z","end":"2026-04-17T04:15:28.765665Z","steps":["trace[1934024303] 'process raft request' (duration: 358.121227ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:15:28.765886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.463119ms","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-17T04:15:28.765949Z","caller":"traceutil/trace.go:171","msg":"trace[1283971361] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2049; }","duration":"308.55792ms","start":"2026-04-17T04:15:28.457375Z","end":"2026-04-17T04:15:28.765933Z","steps":["trace[1283971361] 'agreement among raft nodes before linearized reading' (duration: 308.397636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:15:28.765983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:15:28.457362Z","time spent":"308.615452ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-17T04:15:28.76586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:15:28.406992Z","time spent":"358.736502ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:15:28.766068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.917196ms","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-17T04:15:28.766055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.156765ms","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-17T04:15:28.766144Z","caller":"traceutil/trace.go:171","msg":"trace[384187413] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2049; }","duration":"316.036409ms","start":"2026-04-17T04:15:28.450089Z","end":"2026-04-17T04:15:28.766126Z","steps":["trace[384187413] 'agreement among raft nodes before linearized reading' (duration: 315.746932ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:15:28.766171Z","caller":"traceutil/trace.go:171","msg":"trace[1780189912] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2049; }","duration":"309.31321ms","start":"2026-04-17T04:15:28.456833Z","end":"2026-04-17T04:15:28.766146Z","steps":["trace[1780189912] 'agreement among raft nodes before linearized reading' (duration: 308.970241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:15:28.766204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:15:28.450063Z","time spent":"316.122762ms","remote":"127.0.0.1:49292","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-17T04:15:28.766242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:15:28.456813Z","time spent":"309.403011ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-17T04:15:28.766698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.904165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-17T04:15:28.766893Z","caller":"traceutil/trace.go:171","msg":"trace[1562388175] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2049; }","duration":"310.118841ms","start":"2026-04-17T04:15:28.456752Z","end":"2026-04-17T04:15:28.766871Z","steps":["trace[1562388175] 'agreement among raft nodes before linearized reading' (duration: 309.046263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:15:28.766991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:15:28.456697Z","time spent":"310.279124ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T04:15:29.306195Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322102455880958835,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T04:15:30.357183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"987.580295ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:15:30.357407Z","caller":"traceutil/trace.go:171","msg":"trace[1972296341] transaction","detail":"{read_only:false; response_revision:2050; number_of_response:1; }","duration":"1.586751197s","start":"2026-04-17T04:15:28.770604Z","end":"2026-04-17T04:15:30.357355Z","steps":["trace[1972296341] 'process raft request' (duration: 598.834214ms)","trace[1972296341] 'compare' (duration: 986.99733ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:15:30.358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:15:28.770578Z","time spent":"1.586889882s","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:15:30.528668Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.011762662s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-17T04:15:30.732215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.266925ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:15:30.732495Z","caller":"traceutil/trace.go:171","msg":"trace[1483774606] linearizableReadLoop","detail":"{readStateIndex:2156; appliedIndex:2151; }","duration":"1.92726312s","start":"2026-04-17T04:15:28.805207Z","end":"2026-04-17T04:15:30.73247Z","steps":["trace[1483774606] 'read index received' (duration: 564.152592ms)","trace[1483774606] 'applied index is now lower than readState.Index' (duration: 1.363108978s)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:15:30.732571Z","caller":"traceutil/trace.go:171","msg":"trace[464674888] transaction","detail":"{read_only:false; response_revision:2052; number_of_response:1; }","duration":"1.961548862s","start":"2026-04-17T04:15:28.770988Z","end":"2026-04-17T04:15:30.732537Z","steps":["trace[464674888] 'process raft request' (duration: 1.961350687s)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:15:30.732614Z","caller":"traceutil/trace.go:171","msg":"trace[1307038064] transaction","detail":"{read_only:false; response_revision:2053; number_of_response:1; }","duration":"1.961176803s","start":"2026-04-17T04:15:28.771425Z","end":"2026-04-17T04:15:30.732602Z","steps":["trace[1307038064] 'process raft request' (duration: 1.960970978s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:15:30.732685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:15:28.771413Z","time spent":"1.961234045s","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T04:15:30.732694Z","caller":"traceutil/trace.go:171","msg":"trace[118117699] transaction","detail":"{read_only:false; response_revision:2051; number_of_response:1; }","duration":"1.961965653s","start":"2026-04-17T04:15:28.770704Z","end":"2026-04-17T04:15:30.732669Z","steps":["trace[118117699] 'process raft request' (duration: 1.758136195s)","trace[118117699] 'compare' (duration: 203.11517ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:15:30.732716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:15:28.770973Z","time spent":"1.961663325s","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:15:30.732781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:15:28.770682Z","time spent":"1.962053785s","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:15:30.732846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.927651411s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:15:30.732871Z","caller":"traceutil/trace.go:171","msg":"trace[1234921279] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2054; }","duration":"1.927791975s","start":"2026-04-17T04:15:28.805071Z","end":"2026-04-17T04:15:30.732863Z","steps":["trace[1234921279] 'agreement among raft nodes before linearized reading' (duration: 1.927741144s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:15:30.732886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:15:28.805041Z","time spent":"1.927842665s","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T04:15:30.732884Z","caller":"traceutil/trace.go:171","msg":"trace[1063274673] transaction","detail":"{read_only:false; response_revision:2054; number_of_response:1; }","duration":"1.486749509s","start":"2026-04-17T04:15:29.246112Z","end":"2026-04-17T04:15:30.732862Z","steps":["trace[1063274673] 'process raft request' (duration: 1.486314467s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:15:30.732999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:15:29.24608Z","time spent":"1.486858941s","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:15:30.732993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.482303688s","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-17T04:15:30.733062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.976125ms","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-17T04:15:30.733089Z","caller":"traceutil/trace.go:171","msg":"trace[2067982875] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2054; }","duration":"1.48240599s","start":"2026-04-17T04:15:29.250676Z","end":"2026-04-17T04:15:30.733082Z","steps":["trace[2067982875] 'agreement among raft nodes before linearized reading' (duration: 1.482199675s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:15:30.733109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:15:29.250628Z","time spent":"1.482474152s","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-17T04:15:30.733109Z","caller":"traceutil/trace.go:171","msg":"trace[224296836] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:2054; }","duration":"613.057048ms","start":"2026-04-17T04:15:30.120038Z","end":"2026-04-17T04:15:30.733095Z","steps":["trace[224296836] 'agreement among raft nodes before linearized reading' (duration: 612.984236ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:15:30.733154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:15:30.120012Z","time spent":"613.12947ms","remote":"127.0.0.1:49458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-04-17T04:15:31.419133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.152489ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-17T04:15:32.628903Z","caller":"traceutil/trace.go:171","msg":"trace[864236177] transaction","detail":"{read_only:false; response_revision:2057; number_of_response:1; }","duration":"262.846148ms","start":"2026-04-17T04:15:32.366029Z","end":"2026-04-17T04:15:32.628875Z","steps":["trace[864236177] 'process raft request' (duration: 262.654333ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:15:32.877784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.62328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-17T04:15:32.87786Z","caller":"traceutil/trace.go:171","msg":"trace[1717605124] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2057; }","duration":"139.709922ms","start":"2026-04-17T04:15:32.738133Z","end":"2026-04-17T04:15:32.877843Z","steps":["trace[1717605124] 'range keys from in-memory index tree' (duration: 139.434426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:15:32.877948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.162301ms","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-17T04:15:32.878001Z","caller":"traceutil/trace.go:171","msg":"trace[238474535] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2057; }","duration":"112.231664ms","start":"2026-04-17T04:15:32.765756Z","end":"2026-04-17T04:15:32.877988Z","steps":["trace[238474535] 'range keys from in-memory index tree' (duration: 112.071279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:15:32.878025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.604554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-17T04:15:32.87812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.459691ms","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-17T04:15:32.878119Z","caller":"traceutil/trace.go:171","msg":"trace[1357135273] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2057; }","duration":"140.86331ms","start":"2026-04-17T04:15:32.737231Z","end":"2026-04-17T04:15:32.878094Z","steps":["trace[1357135273] 'range keys from in-memory index tree' (duration: 140.300546ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:15:32.878156Z","caller":"traceutil/trace.go:171","msg":"trace[967984375] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2057; }","duration":"140.513542ms","start":"2026-04-17T04:15:32.737632Z","end":"2026-04-17T04:15:32.878146Z","steps":["trace[967984375] 'range keys from in-memory index tree' (duration: 140.335528ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:15:44.942946Z","caller":"traceutil/trace.go:171","msg":"trace[1727915662] transaction","detail":"{read_only:false; response_revision:2147; number_of_response:1; }","duration":"262.680515ms","start":"2026-04-17T04:15:44.68025Z","end":"2026-04-17T04:15:44.94293Z","steps":["trace[1727915662] 'process raft request' (duration: 262.561632ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:15:44.94488Z","caller":"traceutil/trace.go:171","msg":"trace[88445222] linearizableReadLoop","detail":"{readStateIndex:2255; appliedIndex:2254; }","duration":"182.73817ms","start":"2026-04-17T04:15:44.762115Z","end":"2026-04-17T04:15:44.944853Z","steps":["trace[88445222] 'read index received' (duration: 180.930775ms)","trace[88445222] 'applied index is now lower than readState.Index' (duration: 1.806345ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:15:44.944921Z","caller":"traceutil/trace.go:171","msg":"trace[1647188194] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"214.960549ms","start":"2026-04-17T04:15:44.729915Z","end":"2026-04-17T04:15:44.944875Z","steps":["trace[1647188194] 'process raft request' (duration: 214.746583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:15:44.945338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.997708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T04:15:44.945408Z","caller":"traceutil/trace.go:171","msg":"trace[1045972585] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2149; }","duration":"183.334437ms","start":"2026-04-17T04:15:44.762062Z","end":"2026-04-17T04:15:44.945396Z","steps":["trace[1045972585] 'agreement among raft nodes before linearized reading' (duration: 182.937607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:15:47.226034Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322102455880959054,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T04:15:47.274564Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.123938355s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-17T04:15:47.275222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:15:46.150482Z","time spent":"1.124732185s","remote":"127.0.0.1:49108","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-17T04:15:47.279736Z","caller":"traceutil/trace.go:171","msg":"trace[83561304] linearizableReadLoop","detail":"{readStateIndex:2262; appliedIndex:2260; }","duration":"554.208672ms","start":"2026-04-17T04:15:46.725509Z","end":"2026-04-17T04:15:47.279718Z","steps":["trace[83561304] 'read index received' (duration: 549.482264ms)","trace[83561304] 'applied index is now lower than readState.Index' (duration: 4.725428ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:15:47.27981Z","caller":"traceutil/trace.go:171","msg":"trace[1412473847] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"328.618878ms","start":"2026-04-17T04:15:46.951158Z","end":"2026-04-17T04:15:47.279777Z","steps":["trace[1412473847] 'process raft request' (duration: 328.394012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:15:47.279909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.383237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T04:15:47.279948Z","caller":"traceutil/trace.go:171","msg":"trace[112102439] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:2154; }","duration":"554.4818ms","start":"2026-04-17T04:15:46.725454Z","end":"2026-04-17T04:15:47.279936Z","steps":["trace[112102439] 'agreement among raft nodes before linearized reading' (duration: 554.349306ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:15:47.279954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:15:46.951131Z","time spent":"328.74125ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:15:47.27998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:15:46.725427Z","time spent":"554.54358ms","remote":"127.0.0.1:49536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-17T04:15:47.280659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.308605ms","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-17T04:15:47.280715Z","caller":"traceutil/trace.go:171","msg":"trace[1854928680] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2155; }","duration":"333.401377ms","start":"2026-04-17T04:15:46.947301Z","end":"2026-04-17T04:15:47.280702Z","steps":["trace[1854928680] 'agreement among raft nodes before linearized reading' (duration: 333.224122ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:15:47.280716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.019097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-17T04:15:47.280749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:15:46.947275Z","time spent":"333.466949ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-17T04:15:47.280757Z","caller":"traceutil/trace.go:171","msg":"trace[111465051] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2155; }","duration":"325.084268ms","start":"2026-04-17T04:15:46.955661Z","end":"2026-04-17T04:15:47.280745Z","steps":["trace[111465051] 'agreement among raft nodes before linearized reading' (duration: 324.969996ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:15:47.280791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:15:46.955654Z","time spent":"325.128019ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T04:15:47.280851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.504955ms","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-17T04:15:47.280889Z","caller":"traceutil/trace.go:171","msg":"trace[1415547472] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2155; }","duration":"324.574076ms","start":"2026-04-17T04:15:46.956306Z","end":"2026-04-17T04:15:47.28088Z","steps":["trace[1415547472] 'agreement among raft nodes before linearized reading' (duration: 324.457213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:15:47.280913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.276993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-17T04:15:47.280921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:15:46.956292Z","time spent":"324.623538ms","remote":"127.0.0.1:49292","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-17T04:15:47.280941Z","caller":"traceutil/trace.go:171","msg":"trace[1316046767] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2155; }","duration":"325.327885ms","start":"2026-04-17T04:15:46.955605Z","end":"2026-04-17T04:15:47.280933Z","steps":["trace[1316046767] 'agreement among raft nodes before linearized reading' (duration: 325.255403ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:15:47.280963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:15:46.955592Z","time spent":"325.365626ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-17T04:15:47.281046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.025268ms","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-17T04:15:47.28109Z","caller":"traceutil/trace.go:171","msg":"trace[1915695571] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2155; }","duration":"327.09954ms","start":"2026-04-17T04:15:46.953982Z","end":"2026-04-17T04:15:47.281081Z","steps":["trace[1915695571] 'agreement among raft nodes before linearized reading' (duration: 326.974077ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:15:47.281118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:15:46.953969Z","time spent":"327.143011ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-17T04:16:03.465562Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.003665159s","expected-duration":"1s"} {"level":"info","ts":"2026-04-17T04:16:03.466005Z","caller":"traceutil/trace.go:171","msg":"trace[92344204] transaction","detail":"{read_only:false; response_revision:2207; number_of_response:1; }","duration":"1.004276614s","start":"2026-04-17T04:16:02.46171Z","end":"2026-04-17T04:16:03.465987Z","steps":["trace[92344204] 'process raft request' (duration: 1.004165441s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:16:03.466118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:16:02.46168Z","time spent":"1.004386917s","remote":"127.0.0.1:49164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":837,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T04:16:06.870219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.465941ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:16:06.870354Z","caller":"traceutil/trace.go:171","msg":"trace[1628104265] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2242; }","duration":"434.628335ms","start":"2026-04-17T04:16:06.435704Z","end":"2026-04-17T04:16:06.870332Z","steps":["trace[1628104265] 'range keys from in-memory index tree' (duration: 434.44458ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:16:06.924474Z","caller":"traceutil/trace.go:171","msg":"trace[1102100157] linearizableReadLoop","detail":"{readStateIndex:2355; appliedIndex:2354; }","duration":"457.238772ms","start":"2026-04-17T04:16:06.467199Z","end":"2026-04-17T04:16:06.924438Z","steps":["trace[1102100157] 'read index received' (duration: 456.996406ms)","trace[1102100157] 'applied index is now lower than readState.Index' (duration: 240.956µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:16:06.925154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.906226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:16:06.925307Z","caller":"traceutil/trace.go:171","msg":"trace[1304937064] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2243; }","duration":"120.080581ms","start":"2026-04-17T04:16:06.805161Z","end":"2026-04-17T04:16:06.925242Z","steps":["trace[1304937064] 'agreement among raft nodes before linearized reading' (duration: 119.861255ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:16:06.925564Z","caller":"traceutil/trace.go:171","msg":"trace[842080061] transaction","detail":"{read_only:false; response_revision:2243; number_of_response:1; }","duration":"567.911486ms","start":"2026-04-17T04:16:06.35763Z","end":"2026-04-17T04:16:06.925542Z","steps":["trace[842080061] 'process raft request' (duration: 566.583193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:16:06.925733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.069565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:16:06.925813Z","caller":"traceutil/trace.go:171","msg":"trace[514498235] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:2243; }","duration":"117.147387ms","start":"2026-04-17T04:16:06.808641Z","end":"2026-04-17T04:16:06.925788Z","steps":["trace[514498235] 'agreement among raft nodes before linearized reading' (duration: 117.018194ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:16:06.92611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.039502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-7m4vv\" ","response":"range_response_count:1 size:11057"} {"level":"warn","ts":"2026-04-17T04:16:06.926358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:16:06.357618Z","time spent":"568.005939ms","remote":"127.0.0.1:49422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":797,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T04:16:06.926869Z","caller":"traceutil/trace.go:171","msg":"trace[746428801] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-7m4vv; range_end:; response_count:1; response_revision:2243; }","duration":"459.037277ms","start":"2026-04-17T04:16:06.467151Z","end":"2026-04-17T04:16:06.926189Z","steps":["trace[746428801] 'agreement among raft nodes before linearized reading' (duration: 457.914689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:16:06.927063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:16:06.467125Z","time spent":"459.874859ms","remote":"127.0.0.1:49226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":11080,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-7m4vv\" "} {"level":"warn","ts":"2026-04-17T04:16:10.053925Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322102455880959299,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T04:16:10.085595Z","caller":"traceutil/trace.go:171","msg":"trace[1113067269] transaction","detail":"{read_only:false; response_revision:2270; number_of_response:1; }","duration":"543.784691ms","start":"2026-04-17T04:16:09.541777Z","end":"2026-04-17T04:16:10.085562Z","steps":["trace[1113067269] 'process raft request' (duration: 543.460683ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:16:10.085568Z","caller":"traceutil/trace.go:171","msg":"trace[1949179850] linearizableReadLoop","detail":"{readStateIndex:2382; appliedIndex:2381; }","duration":"532.735964ms","start":"2026-04-17T04:16:09.552794Z","end":"2026-04-17T04:16:10.08553Z","steps":["trace[1949179850] 'read index received' (duration: 532.275463ms)","trace[1949179850] 'applied index is now lower than readState.Index' (duration: 458.541µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:16:10.085823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:16:09.541752Z","time spent":"543.902334ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:16:10.085864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.049851ms","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-17T04:16:10.085993Z","caller":"traceutil/trace.go:171","msg":"trace[1816179912] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2270; }","duration":"533.242146ms","start":"2026-04-17T04:16:09.552734Z","end":"2026-04-17T04:16:10.085976Z","steps":["trace[1816179912] 'agreement among raft nodes before linearized reading' (duration: 532.933418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:16:10.086039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:16:09.55271Z","time spent":"533.320738ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-17T04:16:10.08603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.367213ms","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-17T04:16:10.086088Z","caller":"traceutil/trace.go:171","msg":"trace[1946553465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2270; }","duration":"512.468255ms","start":"2026-04-17T04:16:09.573605Z","end":"2026-04-17T04:16:10.086073Z","steps":["trace[1946553465] 'agreement among raft nodes before linearized reading' (duration: 512.131517ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:16:10.086097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.143772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-17T04:16:10.086132Z","caller":"traceutil/trace.go:171","msg":"trace[492072171] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2270; }","duration":"505.207174ms","start":"2026-04-17T04:16:09.580914Z","end":"2026-04-17T04:16:10.086121Z","steps":["trace[492072171] 'agreement among raft nodes before linearized reading' (duration: 505.091531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:16:10.086133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:16:09.573581Z","time spent":"512.540468ms","remote":"127.0.0.1:49292","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-17T04:16:10.086163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:16:09.580896Z","time spent":"505.260625ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T04:16:10.086266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.909581ms","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-17T04:16:10.086274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.282196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:16:10.086295Z","caller":"traceutil/trace.go:171","msg":"trace[1067499437] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2270; }","duration":"511.975113ms","start":"2026-04-17T04:16:09.574312Z","end":"2026-04-17T04:16:10.086287Z","steps":["trace[1067499437] 'agreement among raft nodes before linearized reading' (duration: 511.888181ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:16:10.086308Z","caller":"traceutil/trace.go:171","msg":"trace[1018837056] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2270; }","duration":"280.871041ms","start":"2026-04-17T04:16:09.805406Z","end":"2026-04-17T04:16:10.086299Z","steps":["trace[1018837056] 'agreement among raft nodes before linearized reading' (duration: 280.82ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:16:10.086323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:16:09.574289Z","time spent":"512.027343ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-17T04:16:10.086443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.47793ms","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-17T04:16:10.08654Z","caller":"traceutil/trace.go:171","msg":"trace[17620294] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2270; }","duration":"505.633254ms","start":"2026-04-17T04:16:09.580883Z","end":"2026-04-17T04:16:10.086517Z","steps":["trace[17620294] 'agreement among raft nodes before linearized reading' (duration: 505.283386ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:16:10.086615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:16:09.580866Z","time spent":"505.739258ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-17T04:16:10.341407Z","caller":"traceutil/trace.go:171","msg":"trace[2024078249] transaction","detail":"{read_only:false; response_revision:2271; number_of_response:1; }","duration":"250.203581ms","start":"2026-04-17T04:16:10.091172Z","end":"2026-04-17T04:16:10.341376Z","steps":["trace[2024078249] 'process raft request' (duration: 223.277577ms)","trace[2024078249] 'compare' (duration: 26.76207ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:16:10.505913Z","caller":"traceutil/trace.go:171","msg":"trace[37973825] transaction","detail":"{read_only:false; response_revision:2272; number_of_response:1; }","duration":"413.782193ms","start":"2026-04-17T04:16:10.09208Z","end":"2026-04-17T04:16:10.505863Z","steps":["trace[37973825] 'process raft request' (duration: 402.242404ms)","trace[37973825] 'compare' (duration: 11.144089ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:16:10.506005Z","caller":"traceutil/trace.go:171","msg":"trace[611482377] transaction","detail":"{read_only:false; response_revision:2273; number_of_response:1; }","duration":"413.849924ms","start":"2026-04-17T04:16:10.092127Z","end":"2026-04-17T04:16:10.505977Z","steps":["trace[611482377] 'process raft request' (duration: 413.495366ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:16:10.506149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:16:10.092059Z","time spent":"413.977497ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:16:10.506204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:16:10.09209Z","time spent":"413.996138ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T04:16:10.506342Z","caller":"traceutil/trace.go:171","msg":"trace[1243138731] transaction","detail":"{read_only:false; response_revision:2274; number_of_response:1; }","duration":"413.782732ms","start":"2026-04-17T04:16:10.09254Z","end":"2026-04-17T04:16:10.506323Z","steps":["trace[1243138731] 'process raft request' (duration: 413.389432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:16:10.506463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:16:10.092525Z","time spent":"413.862594ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T04:16:10.506573Z","caller":"traceutil/trace.go:171","msg":"trace[803955073] linearizableReadLoop","detail":"{readStateIndex:2387; appliedIndex:2382; }","duration":"372.693083ms","start":"2026-04-17T04:16:10.133858Z","end":"2026-04-17T04:16:10.506551Z","steps":["trace[803955073] 'read index received' (duration: 180.522526ms)","trace[803955073] 'applied index is now lower than readState.Index' (duration: 192.168807ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:16:10.506595Z","caller":"traceutil/trace.go:171","msg":"trace[1141523435] transaction","detail":"{read_only:false; response_revision:2275; number_of_response:1; }","duration":"413.942435ms","start":"2026-04-17T04:16:10.092638Z","end":"2026-04-17T04:16:10.50658Z","steps":["trace[1141523435] 'process raft request' (duration: 413.588587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:16:10.506717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:16:10.092624Z","time spent":"414.001868ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:16:10.506861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.96987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1948"} {"level":"info","ts":"2026-04-17T04:16:10.506963Z","caller":"traceutil/trace.go:171","msg":"trace[2029815017] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:2275; }","duration":"373.128994ms","start":"2026-04-17T04:16:10.133814Z","end":"2026-04-17T04:16:10.506943Z","steps":["trace[2029815017] 'agreement among raft nodes before linearized reading' (duration: 372.807765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:16:10.507031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:16:10.133797Z","time spent":"373.216786ms","remote":"127.0.0.1:49226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":1971,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "} {"level":"warn","ts":"2026-04-17T04:16:11.792768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.806947ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-17T04:16:12.002085Z","caller":"traceutil/trace.go:171","msg":"trace[841804061] linearizableReadLoop","detail":"{readStateIndex:2389; appliedIndex:2388; }","duration":"197.849709ms","start":"2026-04-17T04:16:11.804214Z","end":"2026-04-17T04:16:12.002064Z","steps":["trace[841804061] 'read index received' (duration: 197.606833ms)","trace[841804061] 'applied index is now lower than readState.Index' (duration: 241.666µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:16:12.00223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.000383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:16:12.002456Z","caller":"traceutil/trace.go:171","msg":"trace[397702274] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2276; }","duration":"198.266029ms","start":"2026-04-17T04:16:11.804172Z","end":"2026-04-17T04:16:12.002438Z","steps":["trace[397702274] 'agreement among raft nodes before linearized reading' (duration: 198.002052ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:16:12.002468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.338796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:16:12.002522Z","caller":"traceutil/trace.go:171","msg":"trace[380788928] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:2276; }","duration":"112.415948ms","start":"2026-04-17T04:16:11.890095Z","end":"2026-04-17T04:16:12.002511Z","steps":["trace[380788928] 'agreement among raft nodes before linearized reading' (duration: 112.297125ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:16:12.002199Z","caller":"traceutil/trace.go:171","msg":"trace[1251447577] transaction","detail":"{read_only:false; response_revision:2276; number_of_response:1; }","duration":"304.79921ms","start":"2026-04-17T04:16:11.697369Z","end":"2026-04-17T04:16:12.002168Z","steps":["trace[1251447577] 'process raft request' (duration: 304.510783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:16:12.002676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:16:11.69734Z","time spent":"305.266432ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:16:27.034329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.790694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T04:16:27.034389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.601459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:16:27.03444Z","caller":"traceutil/trace.go:171","msg":"trace[2128969706] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:2393; }","duration":"149.947238ms","start":"2026-04-17T04:16:26.884475Z","end":"2026-04-17T04:16:27.034422Z","steps":["trace[2128969706] 'range keys from in-memory index tree' (duration: 149.722523ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:16:27.034505Z","caller":"traceutil/trace.go:171","msg":"trace[2044754432] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2393; }","duration":"228.766454ms","start":"2026-04-17T04:16:26.805716Z","end":"2026-04-17T04:16:27.034482Z","steps":["trace[2044754432] 'range keys from in-memory index tree' (duration: 228.494377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:16:27.541959Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322102455880959574,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T04:16:27.747693Z","caller":"traceutil/trace.go:171","msg":"trace[353919210] linearizableReadLoop","detail":"{readStateIndex:2510; appliedIndex:2509; }","duration":"706.493229ms","start":"2026-04-17T04:16:27.041176Z","end":"2026-04-17T04:16:27.747669Z","steps":["trace[353919210] 'read index received' (duration: 706.302945ms)","trace[353919210] 'applied index is now lower than readState.Index' (duration: 189.084µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:16:27.747766Z","caller":"traceutil/trace.go:171","msg":"trace[1819644562] transaction","detail":"{read_only:false; response_revision:2394; number_of_response:1; }","duration":"707.776292ms","start":"2026-04-17T04:16:27.039949Z","end":"2026-04-17T04:16:27.747725Z","steps":["trace[1819644562] 'process raft request' (duration: 707.534256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:16:27.747919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.728135ms","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-17T04:16:27.747988Z","caller":"traceutil/trace.go:171","msg":"trace[1364777861] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:2394; }","duration":"706.829848ms","start":"2026-04-17T04:16:27.041141Z","end":"2026-04-17T04:16:27.747971Z","steps":["trace[1364777861] 'agreement among raft nodes before linearized reading' (duration: 706.713265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:16:27.748044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:16:27.041127Z","time spent":"706.907329ms","remote":"127.0.0.1:49202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-04-17T04:16:27.74828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:16:27.039926Z","time spent":"708.08924ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:16:27.748765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.310576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-04-17T04:16:27.748844Z","caller":"traceutil/trace.go:171","msg":"trace[261788896] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2394; }","duration":"282.442169ms","start":"2026-04-17T04:16:27.466384Z","end":"2026-04-17T04:16:27.748827Z","steps":["trace[261788896] 'agreement among raft nodes before linearized reading' (duration: 281.604558ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:16:28.309736Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322102455880959577,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T04:16:28.992946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.30257ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:16:28.993119Z","caller":"traceutil/trace.go:171","msg":"trace[1543756333] transaction","detail":"{read_only:false; response_revision:2395; number_of_response:1; }","duration":"1.239210934s","start":"2026-04-17T04:16:27.753894Z","end":"2026-04-17T04:16:28.993105Z","steps":["trace[1543756333] 'process raft request' (duration: 888.687147ms)","trace[1543756333] 'compare' (duration: 350.130206ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:16:28.993175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:16:27.753874Z","time spent":"1.239277805s","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T04:16:28.993292Z","caller":"traceutil/trace.go:171","msg":"trace[1822432694] linearizableReadLoop","detail":"{readStateIndex:2512; appliedIndex:2510; }","duration":"1.183791465s","start":"2026-04-17T04:16:27.809492Z","end":"2026-04-17T04:16:28.993283Z","steps":["trace[1822432694] 'read index received' (duration: 832.99962ms)","trace[1822432694] 'applied index is now lower than readState.Index' (duration: 350.791034ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:16:28.993399Z","caller":"traceutil/trace.go:171","msg":"trace[978367571] transaction","detail":"{read_only:false; response_revision:2396; number_of_response:1; }","duration":"750.469582ms","start":"2026-04-17T04:16:28.242922Z","end":"2026-04-17T04:16:28.993392Z","steps":["trace[978367571] 'process raft request' (duration: 750.101522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:16:28.993444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:16:28.242892Z","time spent":"750.528664ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:16:28.993513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.184034541s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:16:28.993535Z","caller":"traceutil/trace.go:171","msg":"trace[2022817463] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2396; }","duration":"1.184086552s","start":"2026-04-17T04:16:27.809442Z","end":"2026-04-17T04:16:28.993528Z","steps":["trace[2022817463] 'agreement among raft nodes before linearized reading' (duration: 1.184049831s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:16:28.993555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:16:27.809428Z","time spent":"1.184122773s","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T04:16:28.993922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.180895ms","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:535"} {"level":"info","ts":"2026-04-17T04:16:28.993953Z","caller":"traceutil/trace.go:171","msg":"trace[1079370815] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2396; }","duration":"592.243597ms","start":"2026-04-17T04:16:28.401698Z","end":"2026-04-17T04:16:28.993942Z","steps":["trace[1079370815] 'agreement among raft nodes before linearized reading' (duration: 592.149214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:16:28.993938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.952668ms","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":"warn","ts":"2026-04-17T04:16:28.993997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:16:28.40168Z","time spent":"592.285327ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-17T04:16:28.994018Z","caller":"traceutil/trace.go:171","msg":"trace[135957356] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:2396; }","duration":"497.068691ms","start":"2026-04-17T04:16:28.496931Z","end":"2026-04-17T04:16:28.994Z","steps":["trace[135957356] 'agreement among raft nodes before linearized reading' (duration: 496.943498ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:16:28.994066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:16:28.496918Z","time spent":"497.137573ms","remote":"127.0.0.1:36904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-04-17T04:16:28.994096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.229969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-17T04:16:28.994086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.229579ms","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-17T04:16:28.994181Z","caller":"traceutil/trace.go:171","msg":"trace[1888304237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2396; }","duration":"344.346812ms","start":"2026-04-17T04:16:28.6498Z","end":"2026-04-17T04:16:28.994147Z","steps":["trace[1888304237] 'agreement among raft nodes before linearized reading' (duration: 344.072335ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:16:28.994194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.47305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-17T04:16:28.994214Z","caller":"traceutil/trace.go:171","msg":"trace[1585655535] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2396; }","duration":"347.534571ms","start":"2026-04-17T04:16:28.646676Z","end":"2026-04-17T04:16:28.994211Z","steps":["trace[1585655535] 'agreement among raft nodes before linearized reading' (duration: 347.49118ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:16:28.994228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:16:28.646652Z","time spent":"347.573363ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T04:16:28.994249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:16:28.649789Z","time spent":"344.426424ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-17T04:16:28.994337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.006967ms","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-17T04:16:28.994355Z","caller":"traceutil/trace.go:171","msg":"trace[747782379] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2396; }","duration":"337.058709ms","start":"2026-04-17T04:16:28.657292Z","end":"2026-04-17T04:16:28.994351Z","steps":["trace[747782379] 'agreement among raft nodes before linearized reading' (duration: 337.017488ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:16:28.994367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:16:28.657253Z","time spent":"337.11155ms","remote":"127.0.0.1:49292","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-17T04:16:28.994111Z","caller":"traceutil/trace.go:171","msg":"trace[851589114] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2396; }","duration":"577.270871ms","start":"2026-04-17T04:16:28.416835Z","end":"2026-04-17T04:16:28.994106Z","steps":["trace[851589114] 'agreement among raft nodes before linearized reading' (duration: 577.22846ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:16:28.994437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.317196ms","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-17T04:16:28.994478Z","caller":"traceutil/trace.go:171","msg":"trace[1989032643] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2396; }","duration":"341.378708ms","start":"2026-04-17T04:16:28.65309Z","end":"2026-04-17T04:16:28.994469Z","steps":["trace[1989032643] 'agreement among raft nodes before linearized reading' (duration: 341.257085ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:16:28.99451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:16:28.653043Z","time spent":"341.46121ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-17T04:16:28.994511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:16:28.416824Z","time spent":"577.585778ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-17T04:16:29.398188Z","caller":"traceutil/trace.go:171","msg":"trace[551217798] transaction","detail":"{read_only:false; response_revision:2398; number_of_response:1; }","duration":"397.073514ms","start":"2026-04-17T04:16:29.001078Z","end":"2026-04-17T04:16:29.398151Z","steps":["trace[551217798] 'process raft request' (duration: 396.780767ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:16:29.398247Z","caller":"traceutil/trace.go:171","msg":"trace[965500834] transaction","detail":"{read_only:false; response_revision:2399; number_of_response:1; }","duration":"397.113014ms","start":"2026-04-17T04:16:29.001104Z","end":"2026-04-17T04:16:29.398217Z","steps":["trace[965500834] 'process raft request' (duration: 396.92108ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:16:29.398413Z","caller":"traceutil/trace.go:171","msg":"trace[1607759969] linearizableReadLoop","detail":"{readStateIndex:2516; appliedIndex:2513; }","duration":"395.579706ms","start":"2026-04-17T04:16:29.002818Z","end":"2026-04-17T04:16:29.398398Z","steps":["trace[1607759969] 'read index received' (duration: 46.65545ms)","trace[1607759969] 'applied index is now lower than readState.Index' (duration: 348.923506ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T04:16:29.398517Z","caller":"traceutil/trace.go:171","msg":"trace[708159485] transaction","detail":"{read_only:false; response_revision:2400; number_of_response:1; }","duration":"395.846392ms","start":"2026-04-17T04:16:29.002658Z","end":"2026-04-17T04:16:29.398505Z","steps":["trace[708159485] 'process raft request' (duration: 395.506334ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:16:29.398591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:16:29.002647Z","time spent":"395.898923ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T04:16:29.39871Z","caller":"traceutil/trace.go:171","msg":"trace[1857830811] transaction","detail":"{read_only:false; response_revision:2401; number_of_response:1; }","duration":"395.156225ms","start":"2026-04-17T04:16:29.003545Z","end":"2026-04-17T04:16:29.398701Z","steps":["trace[1857830811] 'process raft request' (duration: 394.741675ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:16:29.39878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:16:29.003536Z","time spent":"395.207416ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:16:29.398759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:16:29.001059Z","time spent":"397.413843ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T04:16:29.398894Z","caller":"traceutil/trace.go:171","msg":"trace[614828779] transaction","detail":"{read_only:false; response_revision:2402; number_of_response:1; }","duration":"394.523669ms","start":"2026-04-17T04:16:29.004358Z","end":"2026-04-17T04:16:29.398882Z","steps":["trace[614828779] 'process raft request' (duration: 394.000186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:16:29.398963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:16:29.004341Z","time spent":"394.58301ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:16:29.399089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.265243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:16:29.399133Z","caller":"traceutil/trace.go:171","msg":"trace[930404032] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2402; }","duration":"396.321774ms","start":"2026-04-17T04:16:29.002801Z","end":"2026-04-17T04:16:29.399123Z","steps":["trace[930404032] 'agreement among raft nodes before linearized reading' (duration: 396.254532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:16:29.399172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:16:29.002794Z","time spent":"396.370496ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T04:16:29.399384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.148734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-17T04:16:29.399441Z","caller":"traceutil/trace.go:171","msg":"trace[1164791594] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2402; }","duration":"144.238456ms","start":"2026-04-17T04:16:29.255191Z","end":"2026-04-17T04:16:29.399429Z","steps":["trace[1164791594] 'agreement among raft nodes before linearized reading' (duration: 144.071131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T04:16:29.398368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T04:16:29.001042Z","time spent":"397.231408ms","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T04:16:29.614098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.751212ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T04:16:29.61432Z","caller":"traceutil/trace.go:171","msg":"trace[572915991] transaction","detail":"{read_only:false; response_revision:2403; number_of_response:1; }","duration":"209.759968ms","start":"2026-04-17T04:16:29.404534Z","end":"2026-04-17T04:16:29.614294Z","steps":["trace[572915991] 'process raft request' (duration: 89.712959ms)","trace[572915991] 'compare' (duration: 119.624889ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T04:16:30.260141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.727266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T04:16:30.260319Z","caller":"traceutil/trace.go:171","msg":"trace[485271644] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:2405; }","duration":"267.044093ms","start":"2026-04-17T04:16:29.99324Z","end":"2026-04-17T04:16:30.260284Z","steps":["trace[485271644] 'count revisions from in-memory index tree' (duration: 266.621543ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T04:16:53.279537Z","caller":"traceutil/trace.go:171","msg":"trace[1660341351] transaction","detail":"{read_only:false; response_revision:2514; number_of_response:1; }","duration":"124.246401ms","start":"2026-04-17T04:16:53.155257Z","end":"2026-04-17T04:16:53.279503Z","steps":["trace[1660341351] 'process raft request' (duration: 80.927723ms)","trace[1660341351] 'compare' (duration: 43.199166ms)"],"step_count":2}