{"level":"warn","ts":"2026-03-29T01:53:54.296519Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-29T01:53:54.296614Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.65: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.65:2380","--initial-cluster=instance=https://199.204.45.65:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.65:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.65: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-03-29T01:53:54.297168Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-29T01:53:54.29719Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.65:2380"]} {"level":"info","ts":"2026-03-29T01:53:54.297218Z","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-03-29T01:53:54.29802Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"]} {"level":"info","ts":"2026-03-29T01:53:54.298117Z","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.65:2380"],"listen-peer-urls":["https://199.204.45.65:2380"],"advertise-client-urls":["https://199.204.45.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.65: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-03-29T01:53:54.314729Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"16.374146ms"} {"level":"info","ts":"2026-03-29T01:53:54.335875Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cf8c3a138c9a77a2","cluster-id":"c278183e1f634dff"} {"level":"info","ts":"2026-03-29T01:53:54.335952Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=()"} {"level":"info","ts":"2026-03-29T01:53:54.33597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became follower at term 0"} {"level":"info","ts":"2026-03-29T01:53:54.335991Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cf8c3a138c9a77a2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-29T01:53:54.335997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became follower at term 1"} {"level":"info","ts":"2026-03-29T01:53:54.336027Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=(14955392318228297634)"} {"level":"warn","ts":"2026-03-29T01:53:54.344079Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-29T01:53:54.345575Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-29T01:53:54.348271Z","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-03-29T01:53:54.350865Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cf8c3a138c9a77a2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-29T01:53:54.351022Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cf8c3a138c9a77a2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-29T01:53:54.35123Z","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-03-29T01:53:54.351293Z","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-03-29T01:53:54.351309Z","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-03-29T01:53:54.355809Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=(14955392318228297634)"} {"level":"info","ts":"2026-03-29T01:53:54.356736Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c278183e1f634dff","local-member-id":"cf8c3a138c9a77a2","added-peer-id":"cf8c3a138c9a77a2","added-peer-peer-urls":["https://199.204.45.65:2380"]} {"level":"info","ts":"2026-03-29T01:53:54.358801Z","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-03-29T01:53:54.359148Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cf8c3a138c9a77a2","initial-advertise-peer-urls":["https://199.204.45.65:2380"],"listen-peer-urls":["https://199.204.45.65:2380"],"advertise-client-urls":["https://199.204.45.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-29T01:53:54.359199Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-29T01:53:54.359383Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.65:2380"} {"level":"info","ts":"2026-03-29T01:53:54.359448Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.65:2380"} {"level":"info","ts":"2026-03-29T01:53:55.13616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 is starting a new election at term 1"} {"level":"info","ts":"2026-03-29T01:53:55.136314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-29T01:53:55.136358Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 received MsgPreVoteResp from cf8c3a138c9a77a2 at term 1"} {"level":"info","ts":"2026-03-29T01:53:55.13638Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became candidate at term 2"} {"level":"info","ts":"2026-03-29T01:53:55.136398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 received MsgVoteResp from cf8c3a138c9a77a2 at term 2"} {"level":"info","ts":"2026-03-29T01:53:55.136424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became leader at term 2"} {"level":"info","ts":"2026-03-29T01:53:55.136442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cf8c3a138c9a77a2 elected leader cf8c3a138c9a77a2 at term 2"} {"level":"info","ts":"2026-03-29T01:53:55.137399Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-29T01:53:55.137738Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-29T01:53:55.137761Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-29T01:53:55.137752Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cf8c3a138c9a77a2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.65:2379]}","request-path":"/0/members/cf8c3a138c9a77a2/attributes","cluster-id":"c278183e1f634dff","publish-timeout":"7s"} {"level":"info","ts":"2026-03-29T01:53:55.1381Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-29T01:53:55.13819Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-29T01:53:55.138498Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c278183e1f634dff","local-member-id":"cf8c3a138c9a77a2","cluster-version":"3.5"} {"level":"info","ts":"2026-03-29T01:53:55.138583Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-29T01:53:55.138627Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-29T01:53:55.141114Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-29T01:53:55.147866Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.65:2379"} {"level":"info","ts":"2026-03-29T01:53:58.791182Z","caller":"traceutil/trace.go:171","msg":"trace[2006234556] transaction","detail":"{read_only:false; response_revision:205; number_of_response:1; }","duration":"162.269775ms","start":"2026-03-29T01:53:58.628892Z","end":"2026-03-29T01:53:58.791162Z","steps":["trace[2006234556] 'process raft request' (duration: 162.125911ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:53:59.418264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.667033ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:53:59.418393Z","caller":"traceutil/trace.go:171","msg":"trace[255069330] linearizableReadLoop","detail":"{readStateIndex:211; appliedIndex:210; }","duration":"216.163225ms","start":"2026-03-29T01:53:59.202214Z","end":"2026-03-29T01:53:59.418377Z","steps":["trace[255069330] 'read index received' (duration: 32.77µs)","trace[255069330] 'applied index is now lower than readState.Index' (duration: 216.129335ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:53:59.418437Z","caller":"traceutil/trace.go:171","msg":"trace[1236684851] transaction","detail":"{read_only:false; response_revision:206; number_of_response:1; }","duration":"619.814445ms","start":"2026-03-29T01:53:58.798589Z","end":"2026-03-29T01:53:59.418403Z","steps":["trace[1236684851] 'process raft request' (duration: 379.498686ms)","trace[1236684851] 'compare' (duration: 239.539429ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:53:59.418469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.255068ms","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-03-29T01:53:59.418668Z","caller":"traceutil/trace.go:171","msg":"trace[2121841428] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:206; }","duration":"216.441203ms","start":"2026-03-29T01:53:59.202172Z","end":"2026-03-29T01:53:59.418613Z","steps":["trace[2121841428] 'agreement among raft nodes before linearized reading' (duration: 216.248778ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:53:59.418744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:53:58.798573Z","time spent":"619.942378ms","remote":"127.0.0.1:59876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":525,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T01:53:59.994424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.186032ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:53:59.994679Z","caller":"traceutil/trace.go:171","msg":"trace[2082053256] transaction","detail":"{read_only:false; response_revision:207; number_of_response:1; }","duration":"583.367029ms","start":"2026-03-29T01:53:59.411266Z","end":"2026-03-29T01:53:59.994633Z","steps":["trace[2082053256] 'process raft request' (duration: 304.773697ms)","trace[2082053256] 'compare' (duration: 277.962526ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:53:59.99475Z","caller":"traceutil/trace.go:171","msg":"trace[755901238] transaction","detail":"{read_only:false; response_revision:209; number_of_response:1; }","duration":"518.061169ms","start":"2026-03-29T01:53:59.476669Z","end":"2026-03-29T01:53:59.99473Z","steps":["trace[755901238] 'process raft request' (duration: 517.993407ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:53:59.994835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:53:59.411233Z","time spent":"583.524034ms","remote":"127.0.0.1:59964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5877,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:53:59.994867Z","caller":"traceutil/trace.go:171","msg":"trace[2013065951] transaction","detail":"{read_only:false; response_revision:208; number_of_response:1; }","duration":"572.083864ms","start":"2026-03-29T01:53:59.422737Z","end":"2026-03-29T01:53:59.994821Z","steps":["trace[2013065951] 'process raft request' (duration: 571.803366ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:53:59.994837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:53:59.476655Z","time spent":"518.143172ms","remote":"127.0.0.1:59854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T01:53:59.995015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:53:59.42272Z","time spent":"572.232447ms","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T01:53:59.99547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.258243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-wpyn9f\" ","response":"range_response_count:1 size:540"} {"level":"info","ts":"2026-03-29T01:53:59.995706Z","caller":"traceutil/trace.go:171","msg":"trace[1997548490] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-wpyn9f; range_end:; response_count:1; response_revision:209; }","duration":"573.448468ms","start":"2026-03-29T01:53:59.422139Z","end":"2026-03-29T01:53:59.995588Z","steps":["trace[1997548490] 'agreement among raft nodes before linearized reading' (duration: 572.882024ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:53:59.995835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:53:59.422122Z","time spent":"573.694244ms","remote":"127.0.0.1:59876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":564,"request content":"key:\"/registry/secrets/kube-system/bootstrap-token-wpyn9f\" "} {"level":"info","ts":"2026-03-29T01:53:59.994991Z","caller":"traceutil/trace.go:171","msg":"trace[1394272786] linearizableReadLoop","detail":"{readStateIndex:213; appliedIndex:211; }","duration":"572.646848ms","start":"2026-03-29T01:53:59.422192Z","end":"2026-03-29T01:53:59.994838Z","steps":["trace[1394272786] 'read index received' (duration: 53.440299ms)","trace[1394272786] 'applied index is now lower than readState.Index' (duration: 519.204609ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:54:00.249737Z","caller":"traceutil/trace.go:171","msg":"trace[1763010845] transaction","detail":"{read_only:false; response_revision:210; number_of_response:1; }","duration":"250.392887ms","start":"2026-03-29T01:53:59.99932Z","end":"2026-03-29T01:54:00.249712Z","steps":["trace[1763010845] 'process raft request' (duration: 192.161176ms)","trace[1763010845] 'compare' (duration: 58.031685ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:54:00.249797Z","caller":"traceutil/trace.go:171","msg":"trace[539818928] transaction","detail":"{read_only:false; response_revision:212; number_of_response:1; }","duration":"248.482508ms","start":"2026-03-29T01:54:00.001292Z","end":"2026-03-29T01:54:00.249774Z","steps":["trace[539818928] 'process raft request' (duration: 248.434877ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:00.250061Z","caller":"traceutil/trace.go:171","msg":"trace[501347716] transaction","detail":"{read_only:false; response_revision:211; number_of_response:1; }","duration":"250.347866ms","start":"2026-03-29T01:53:59.999703Z","end":"2026-03-29T01:54:00.250051Z","steps":["trace[501347716] 'process raft request' (duration: 249.973507ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:00.25018Z","caller":"traceutil/trace.go:171","msg":"trace[855701895] linearizableReadLoop","detail":"{readStateIndex:216; appliedIndex:215; }","duration":"250.725586ms","start":"2026-03-29T01:53:59.99944Z","end":"2026-03-29T01:54:00.250166Z","steps":["trace[855701895] 'read index received' (duration: 192.018413ms)","trace[855701895] 'applied index is now lower than readState.Index' (duration: 58.705423ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:54:00.250372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.91677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-29T01:54:00.250449Z","caller":"traceutil/trace.go:171","msg":"trace[1448593944] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:212; }","duration":"251.086705ms","start":"2026-03-29T01:53:59.99935Z","end":"2026-03-29T01:54:00.250436Z","steps":["trace[1448593944] 'agreement among raft nodes before linearized reading' (duration: 250.945181ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:00.250492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.296691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:54:00.250635Z","caller":"traceutil/trace.go:171","msg":"trace[295521139] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:0; response_revision:212; }","duration":"242.457175ms","start":"2026-03-29T01:54:00.008118Z","end":"2026-03-29T01:54:00.250575Z","steps":["trace[295521139] 'agreement among raft nodes before linearized reading' (duration: 242.27117ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:00.500285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.728884ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:54:00.500555Z","caller":"traceutil/trace.go:171","msg":"trace[1816936863] linearizableReadLoop","detail":"{readStateIndex:233; appliedIndex:231; }","duration":"128.521358ms","start":"2026-03-29T01:54:00.372015Z","end":"2026-03-29T01:54:00.500536Z","steps":["trace[1816936863] 'read index received' (duration: 88.766688ms)","trace[1816936863] 'applied index is now lower than readState.Index' (duration: 39.75323ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:54:00.500615Z","caller":"traceutil/trace.go:171","msg":"trace[33377880] transaction","detail":"{read_only:false; response_revision:226; number_of_response:1; }","duration":"153.829201ms","start":"2026-03-29T01:54:00.346753Z","end":"2026-03-29T01:54:00.500582Z","steps":["trace[33377880] 'process raft request' (duration: 11.723278ms)","trace[33377880] 'compare' (duration: 141.58578ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:54:00.500675Z","caller":"traceutil/trace.go:171","msg":"trace[1891151701] transaction","detail":"{read_only:false; response_revision:227; number_of_response:1; }","duration":"152.447895ms","start":"2026-03-29T01:54:00.348204Z","end":"2026-03-29T01:54:00.500652Z","steps":["trace[1891151701] 'process raft request' (duration: 152.185229ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:00.500763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.740553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:54:00.500828Z","caller":"traceutil/trace.go:171","msg":"trace[1982619507] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:227; }","duration":"128.812316ms","start":"2026-03-29T01:54:00.37198Z","end":"2026-03-29T01:54:00.500792Z","steps":["trace[1982619507] 'agreement among raft nodes before linearized reading' (duration: 128.621931ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:00.70629Z","caller":"traceutil/trace.go:171","msg":"trace[1514773658] transaction","detail":"{read_only:false; response_revision:229; number_of_response:1; }","duration":"199.717127ms","start":"2026-03-29T01:54:00.506548Z","end":"2026-03-29T01:54:00.706266Z","steps":["trace[1514773658] 'process raft request' (duration: 199.618985ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:00.706412Z","caller":"traceutil/trace.go:171","msg":"trace[608893512] transaction","detail":"{read_only:false; response_revision:228; number_of_response:1; }","duration":"201.061942ms","start":"2026-03-29T01:54:00.505324Z","end":"2026-03-29T01:54:00.706386Z","steps":["trace[608893512] 'process raft request' (duration: 101.46734ms)","trace[608893512] 'compare' (duration: 99.199052ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:54:00.706552Z","caller":"traceutil/trace.go:171","msg":"trace[1484618971] linearizableReadLoop","detail":"{readStateIndex:234; appliedIndex:233; }","duration":"200.011486ms","start":"2026-03-29T01:54:00.506503Z","end":"2026-03-29T01:54:00.706514Z","steps":["trace[1484618971] 'read index received' (duration: 100.29355ms)","trace[1484618971] 'applied index is now lower than readState.Index' (duration: 99.712026ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:54:00.706839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.287824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-29T01:54:00.707508Z","caller":"traceutil/trace.go:171","msg":"trace[914509923] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:229; }","duration":"201.043712ms","start":"2026-03-29T01:54:00.506447Z","end":"2026-03-29T01:54:00.707491Z","steps":["trace[914509923] 'agreement among raft nodes before linearized reading' (duration: 200.17156ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:00.901171Z","caller":"traceutil/trace.go:171","msg":"trace[672898493] linearizableReadLoop","detail":"{readStateIndex:237; appliedIndex:236; }","duration":"143.847187ms","start":"2026-03-29T01:54:00.757297Z","end":"2026-03-29T01:54:00.901144Z","steps":["trace[672898493] 'read index received' (duration: 117.835015ms)","trace[672898493] 'applied index is now lower than readState.Index' (duration: 26.010722ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:54:00.901204Z","caller":"traceutil/trace.go:171","msg":"trace[1514099543] transaction","detail":"{read_only:false; response_revision:231; number_of_response:1; }","duration":"190.031272ms","start":"2026-03-29T01:54:00.711145Z","end":"2026-03-29T01:54:00.901176Z","steps":["trace[1514099543] 'process raft request' (duration: 163.99949ms)","trace[1514099543] 'compare' (duration: 25.750265ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:54:00.901483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.278834ms","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-03-29T01:54:00.901555Z","caller":"traceutil/trace.go:171","msg":"trace[1444269542] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:231; }","duration":"191.370046ms","start":"2026-03-29T01:54:00.710173Z","end":"2026-03-29T01:54:00.901543Z","steps":["trace[1444269542] 'agreement among raft nodes before linearized reading' (duration: 191.1267ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:00.905042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.821437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:54:00.905139Z","caller":"traceutil/trace.go:171","msg":"trace[504457844] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:0; response_revision:232; }","duration":"143.963051ms","start":"2026-03-29T01:54:00.761154Z","end":"2026-03-29T01:54:00.905117Z","steps":["trace[504457844] 'agreement among raft nodes before linearized reading' (duration: 143.750205ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:00.905322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.913112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3708"} {"level":"warn","ts":"2026-03-29T01:54:00.905393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.847402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:54:00.905438Z","caller":"traceutil/trace.go:171","msg":"trace[913616048] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:232; }","duration":"140.918693ms","start":"2026-03-29T01:54:00.764508Z","end":"2026-03-29T01:54:00.905427Z","steps":["trace[913616048] 'agreement among raft nodes before linearized reading' (duration: 140.843541ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:00.905413Z","caller":"traceutil/trace.go:171","msg":"trace[1461898414] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:232; }","duration":"115.043476ms","start":"2026-03-29T01:54:00.790356Z","end":"2026-03-29T01:54:00.905399Z","steps":["trace[1461898414] 'agreement among raft nodes before linearized reading' (duration: 114.84291ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:00.905335Z","caller":"traceutil/trace.go:171","msg":"trace[2105149499] transaction","detail":"{read_only:false; response_revision:232; number_of_response:1; }","duration":"145.601393ms","start":"2026-03-29T01:54:00.759716Z","end":"2026-03-29T01:54:00.905318Z","steps":["trace[2105149499] 'process raft request' (duration: 145.086829ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:01.218224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.177002ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:54:01.218357Z","caller":"traceutil/trace.go:171","msg":"trace[1874649555] transaction","detail":"{read_only:false; response_revision:238; number_of_response:1; }","duration":"235.767396ms","start":"2026-03-29T01:54:00.982577Z","end":"2026-03-29T01:54:01.218344Z","steps":["trace[1874649555] 'process raft request' (duration: 235.718614ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:01.218376Z","caller":"traceutil/trace.go:171","msg":"trace[72348229] linearizableReadLoop","detail":"{readStateIndex:243; appliedIndex:242; }","duration":"239.034689ms","start":"2026-03-29T01:54:00.979315Z","end":"2026-03-29T01:54:01.21835Z","steps":["trace[72348229] 'read index received' (duration: 80.704032ms)","trace[72348229] 'applied index is now lower than readState.Index' (duration: 158.328437ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:54:01.218548Z","caller":"traceutil/trace.go:171","msg":"trace[1999983454] transaction","detail":"{read_only:false; response_revision:237; number_of_response:1; }","duration":"243.969154ms","start":"2026-03-29T01:54:00.974561Z","end":"2026-03-29T01:54:01.21853Z","steps":["trace[1999983454] 'process raft request' (duration: 85.441863ms)","trace[1999983454] 'compare' (duration: 157.857464ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:54:01.21864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.448257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18a12d0652ff07bb\" ","response":"range_response_count:1 size:657"} {"level":"info","ts":"2026-03-29T01:54:01.218671Z","caller":"traceutil/trace.go:171","msg":"trace[1280271291] range","detail":"{range_begin:/registry/events/default/instance.18a12d0652ff07bb; range_end:; response_count:1; response_revision:238; }","duration":"233.481878ms","start":"2026-03-29T01:54:00.98518Z","end":"2026-03-29T01:54:01.218662Z","steps":["trace[1280271291] 'agreement among raft nodes before linearized reading' (duration: 233.430516ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:01.218767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.303152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-fskx8u\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:54:01.218789Z","caller":"traceutil/trace.go:171","msg":"trace[39782496] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-fskx8u; range_end:; response_count:0; response_revision:238; }","duration":"244.346894ms","start":"2026-03-29T01:54:00.974437Z","end":"2026-03-29T01:54:01.218784Z","steps":["trace[39782496] 'agreement among raft nodes before linearized reading' (duration: 244.313563ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:01.442678Z","caller":"traceutil/trace.go:171","msg":"trace[800503567] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"130.957049ms","start":"2026-03-29T01:54:01.311679Z","end":"2026-03-29T01:54:01.442636Z","steps":["trace[800503567] 'process raft request' (duration: 35.528322ms)","trace[800503567] 'compare' (duration: 95.237792ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:54:01.442758Z","caller":"traceutil/trace.go:171","msg":"trace[2039760826] linearizableReadLoop","detail":"{readStateIndex:247; appliedIndex:246; }","duration":"129.981296ms","start":"2026-03-29T01:54:01.312749Z","end":"2026-03-29T01:54:01.44273Z","steps":["trace[2039760826] 'read index received' (duration: 34.465787ms)","trace[2039760826] 'applied index is now lower than readState.Index' (duration: 95.512748ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:54:01.442691Z","caller":"traceutil/trace.go:171","msg":"trace[1305824012] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"128.98479ms","start":"2026-03-29T01:54:01.313681Z","end":"2026-03-29T01:54:01.442666Z","steps":["trace[1305824012] 'process raft request' (duration: 128.926038ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:01.442987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.143979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-29T01:54:01.443325Z","caller":"traceutil/trace.go:171","msg":"trace[5121189] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:242; }","duration":"130.596072ms","start":"2026-03-29T01:54:01.312714Z","end":"2026-03-29T01:54:01.44331Z","steps":["trace[5121189] 'agreement among raft nodes before linearized reading' (duration: 130.102759ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:01.755102Z","caller":"traceutil/trace.go:171","msg":"trace[1995994468] linearizableReadLoop","detail":"{readStateIndex:255; appliedIndex:254; }","duration":"170.333751ms","start":"2026-03-29T01:54:01.584737Z","end":"2026-03-29T01:54:01.75507Z","steps":["trace[1995994468] 'read index received' (duration: 142.440042ms)","trace[1995994468] 'applied index is now lower than readState.Index' (duration: 27.892559ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:54:01.755227Z","caller":"traceutil/trace.go:171","msg":"trace[2138126957] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"185.477316ms","start":"2026-03-29T01:54:01.569696Z","end":"2026-03-29T01:54:01.755173Z","steps":["trace[2138126957] 'process raft request' (duration: 157.576277ms)","trace[2138126957] 'compare' (duration: 27.641632ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:54:01.755476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.562187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18a12d0652ff5fdc\" ","response":"range_response_count:1 size:653"} {"level":"info","ts":"2026-03-29T01:54:01.755529Z","caller":"traceutil/trace.go:171","msg":"trace[726977823] range","detail":"{range_begin:/registry/events/default/instance.18a12d0652ff5fdc; range_end:; response_count:1; response_revision:249; }","duration":"170.806123ms","start":"2026-03-29T01:54:01.584705Z","end":"2026-03-29T01:54:01.755511Z","steps":["trace[726977823] 'agreement among raft nodes before linearized reading' (duration: 170.527016ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:01.755843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.453965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:54:01.756104Z","caller":"traceutil/trace.go:171","msg":"trace[1943199589] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:0; response_revision:249; }","duration":"170.791433ms","start":"2026-03-29T01:54:01.585248Z","end":"2026-03-29T01:54:01.756039Z","steps":["trace[1943199589] 'agreement among raft nodes before linearized reading' (duration: 170.27349ms)"],"step_count":1} 2026/03/29 01:54:02 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-29T01:54:02.208376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.064523ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:54:02.208569Z","caller":"traceutil/trace.go:171","msg":"trace[96218246] transaction","detail":"{read_only:false; response_revision:262; number_of_response:1; }","duration":"202.204532ms","start":"2026-03-29T01:54:02.006343Z","end":"2026-03-29T01:54:02.208548Z","steps":["trace[96218246] 'process raft request' (duration: 202.1332ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:02.853261Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620625497567830653,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T01:54:03.35444Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620625497567830653,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T01:54:03.462524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.14959628s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:54:03.462628Z","caller":"traceutil/trace.go:171","msg":"trace[390369031] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"1.158589499s","start":"2026-03-29T01:54:02.304022Z","end":"2026-03-29T01:54:03.462612Z","steps":["trace[390369031] 'compare' (duration: 1.149302363s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:03.462727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:02.304012Z","time spent":"1.15864933s","remote":"127.0.0.1:59988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":201,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T01:54:03.477451Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.032470472s","expected-duration":"1s"} {"level":"info","ts":"2026-03-29T01:54:03.477863Z","caller":"traceutil/trace.go:171","msg":"trace[526533781] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"1.171730104s","start":"2026-03-29T01:54:02.306105Z","end":"2026-03-29T01:54:03.477835Z","steps":["trace[526533781] 'process raft request' (duration: 1.171630661s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:03.477989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:02.306092Z","time spent":"1.171818535s","remote":"127.0.0.1:60222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3200,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:54:03.494899Z","caller":"traceutil/trace.go:171","msg":"trace[400966741] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"1.142383797s","start":"2026-03-29T01:54:02.352458Z","end":"2026-03-29T01:54:03.494842Z","steps":["trace[400966741] 'process raft request' (duration: 1.142216963s)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:03.49505Z","caller":"traceutil/trace.go:171","msg":"trace[717368458] linearizableReadLoop","detail":"{readStateIndex:287; appliedIndex:279; }","duration":"1.142583372s","start":"2026-03-29T01:54:02.352448Z","end":"2026-03-29T01:54:03.495032Z","steps":["trace[717368458] 'read index received' (duration: 1.125308043s)","trace[717368458] 'applied index is now lower than readState.Index' (duration: 17.274239ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:54:03.495164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:02.352433Z","time spent":"1.142608753s","remote":"127.0.0.1:59854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:54:03.495172Z","caller":"traceutil/trace.go:171","msg":"trace[1929883210] transaction","detail":"{read_only:false; number_of_response:0; response_revision:275; }","duration":"1.064895146s","start":"2026-03-29T01:54:02.430259Z","end":"2026-03-29T01:54:03.495154Z","steps":["trace[1929883210] 'process raft request' (duration: 1.064526597s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:03.495482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.143019074s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"warn","ts":"2026-03-29T01:54:03.495489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:02.430242Z","time spent":"1.065195974s","remote":"127.0.0.1:59958","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:54:03.495483Z","caller":"traceutil/trace.go:171","msg":"trace[909710323] transaction","detail":"{read_only:false; number_of_response:0; response_revision:277; }","duration":"1.019643055s","start":"2026-03-29T01:54:02.475694Z","end":"2026-03-29T01:54:03.495337Z","steps":["trace[909710323] 'process raft request' (duration: 1.019313517s)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:03.495541Z","caller":"traceutil/trace.go:171","msg":"trace[1272765245] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:277; }","duration":"1.143114005s","start":"2026-03-29T01:54:02.352415Z","end":"2026-03-29T01:54:03.495528Z","steps":["trace[1272765245] 'agreement among raft nodes before linearized reading' (duration: 1.142945911s)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:03.495531Z","caller":"traceutil/trace.go:171","msg":"trace[333869602] transaction","detail":"{read_only:false; number_of_response:0; response_revision:276; }","duration":"1.020664501s","start":"2026-03-29T01:54:02.474816Z","end":"2026-03-29T01:54:03.49548Z","steps":["trace[333869602] 'process raft request' (duration: 1.020092907s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:03.495582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:02.352397Z","time spent":"1.143177517s","remote":"127.0.0.1:60162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":688,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-03-29T01:54:03.495658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:02.474787Z","time spent":"1.020814155s","remote":"127.0.0.1:59964","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:54:03.495707Z","caller":"traceutil/trace.go:171","msg":"trace[129095458] transaction","detail":"{read_only:false; number_of_response:0; response_revision:277; }","duration":"1.020485556s","start":"2026-03-29T01:54:02.475214Z","end":"2026-03-29T01:54:03.495699Z","steps":["trace[129095458] 'process raft request' (duration: 1.019767378s)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:03.495731Z","caller":"traceutil/trace.go:171","msg":"trace[1935458078] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"1.020571699s","start":"2026-03-29T01:54:02.475147Z","end":"2026-03-29T01:54:03.495719Z","steps":["trace[1935458078] 'process raft request' (duration: 1.019786219s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:03.495766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:02.475194Z","time spent":"1.020550109s","remote":"127.0.0.1:59964","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T01:54:03.495844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:02.475135Z","time spent":"1.020662981s","remote":"127.0.0.1:59964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3959,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:54:03.495894Z","caller":"traceutil/trace.go:171","msg":"trace[1215903499] transaction","detail":"{read_only:false; response_revision:276; number_of_response:1; }","duration":"1.021173494s","start":"2026-03-29T01:54:02.474711Z","end":"2026-03-29T01:54:03.495884Z","steps":["trace[1215903499] 'process raft request' (duration: 1.020128548s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:03.495778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.14328971s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-29T01:54:03.49594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:02.474689Z","time spent":"1.021230505s","remote":"127.0.0.1:59964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3453,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T01:54:03.495949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.997868ms","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-03-29T01:54:03.495978Z","caller":"traceutil/trace.go:171","msg":"trace[1777614862] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:277; }","duration":"1.143510366s","start":"2026-03-29T01:54:02.352443Z","end":"2026-03-29T01:54:03.495953Z","steps":["trace[1777614862] 'agreement among raft nodes before linearized reading' (duration: 1.143306121s)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:03.495993Z","caller":"traceutil/trace.go:171","msg":"trace[828322821] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:277; }","duration":"559.097952ms","start":"2026-03-29T01:54:02.936883Z","end":"2026-03-29T01:54:03.495981Z","steps":["trace[828322821] 'agreement among raft nodes before linearized reading' (duration: 559.001329ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:03.495661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:02.475687Z","time spent":"1.019882321s","remote":"127.0.0.1:59964","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T01:54:03.496028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:02.936859Z","time spent":"559.161993ms","remote":"127.0.0.1:60046","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-03-29T01:54:03.496029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:02.352421Z","time spent":"1.143593637s","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-03-29T01:54:03.496139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.963328ms","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-03-29T01:54:03.496995Z","caller":"traceutil/trace.go:171","msg":"trace[204908547] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:277; }","duration":"274.79401ms","start":"2026-03-29T01:54:03.22215Z","end":"2026-03-29T01:54:03.496944Z","steps":["trace[204908547] 'agreement among raft nodes before linearized reading' (duration: 273.923837ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:12.498728Z","caller":"traceutil/trace.go:171","msg":"trace[947824855] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"118.037406ms","start":"2026-03-29T01:54:12.380662Z","end":"2026-03-29T01:54:12.4987Z","steps":["trace[947824855] 'process raft request' (duration: 117.893003ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:18.946652Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620625497567830994,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T01:54:19.447279Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620625497567830994,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T01:54:19.488419Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.102833288s","expected-duration":"1s"} {"level":"info","ts":"2026-03-29T01:54:19.48879Z","caller":"traceutil/trace.go:171","msg":"trace[2021351159] linearizableReadLoop","detail":"{readStateIndex:459; appliedIndex:458; }","duration":"1.042590952s","start":"2026-03-29T01:54:18.446179Z","end":"2026-03-29T01:54:19.48877Z","steps":["trace[2021351159] 'read index received' (duration: 1.042402678s)","trace[2021351159] 'applied index is now lower than readState.Index' (duration: 187.444µs)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:54:19.488876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.042684625s","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-03-29T01:54:19.48886Z","caller":"traceutil/trace.go:171","msg":"trace[221430065] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"1.103327881s","start":"2026-03-29T01:54:18.3855Z","end":"2026-03-29T01:54:19.488828Z","steps":["trace[221430065] 'process raft request' (duration: 1.103129436s)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:19.488914Z","caller":"traceutil/trace.go:171","msg":"trace[356334667] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:444; }","duration":"1.042761657s","start":"2026-03-29T01:54:18.446143Z","end":"2026-03-29T01:54:19.488904Z","steps":["trace[356334667] 'agreement among raft nodes before linearized reading' (duration: 1.042686355s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:19.488952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:18.446128Z","time spent":"1.042816618s","remote":"127.0.0.1:60046","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-03-29T01:54:19.489003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:18.385488Z","time spent":"1.103438253s","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:54:23.626435Z","caller":"traceutil/trace.go:171","msg":"trace[414265115] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"108.339152ms","start":"2026-03-29T01:54:23.518072Z","end":"2026-03-29T01:54:23.626412Z","steps":["trace[414265115] 'process raft request' (duration: 54.026897ms)","trace[414265115] 'compare' (duration: 54.144491ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:54:23.957981Z","caller":"traceutil/trace.go:171","msg":"trace[1407095537] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"167.176814ms","start":"2026-03-29T01:54:23.790785Z","end":"2026-03-29T01:54:23.957962Z","steps":["trace[1407095537] 'process raft request' (duration: 148.732823ms)","trace[1407095537] 'compare' (duration: 18.340909ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:54:27.864172Z","caller":"traceutil/trace.go:171","msg":"trace[1159610083] linearizableReadLoop","detail":"{readStateIndex:477; appliedIndex:476; }","duration":"458.253142ms","start":"2026-03-29T01:54:27.405896Z","end":"2026-03-29T01:54:27.864149Z","steps":["trace[1159610083] 'read index received' (duration: 458.128039ms)","trace[1159610083] 'applied index is now lower than readState.Index' (duration: 124.283µs)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:54:27.864251Z","caller":"traceutil/trace.go:171","msg":"trace[146943765] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"493.210003ms","start":"2026-03-29T01:54:27.371009Z","end":"2026-03-29T01:54:27.864219Z","steps":["trace[146943765] 'process raft request' (duration: 493.003338ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:27.864463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.510798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:54:27.86501Z","caller":"traceutil/trace.go:171","msg":"trace[243334129] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:0; response_revision:461; }","duration":"459.140014ms","start":"2026-03-29T01:54:27.405849Z","end":"2026-03-29T01:54:27.864989Z","steps":["trace[243334129] 'agreement among raft nodes before linearized reading' (duration: 458.409176ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:27.864494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:27.370982Z","time spent":"493.404258ms","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:54:27.864569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.308715ms","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-03-29T01:54:27.865223Z","caller":"traceutil/trace.go:171","msg":"trace[942679856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:461; }","duration":"226.987321ms","start":"2026-03-29T01:54:27.638216Z","end":"2026-03-29T01:54:27.865204Z","steps":["trace[942679856] 'agreement among raft nodes before linearized reading' (duration: 226.304004ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:27.865233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:27.405829Z","time spent":"459.254527ms","remote":"127.0.0.1:59988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium\" "} {"level":"warn","ts":"2026-03-29T01:54:27.864621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.633151ms","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-03-29T01:54:27.865434Z","caller":"traceutil/trace.go:171","msg":"trace[1396427063] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:461; }","duration":"332.462171ms","start":"2026-03-29T01:54:27.532953Z","end":"2026-03-29T01:54:27.865415Z","steps":["trace[1396427063] 'agreement among raft nodes before linearized reading' (duration: 331.585929ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:27.865492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:27.532937Z","time spent":"332.542694ms","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-29T01:54:27.864672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.65046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:54:27.865589Z","caller":"traceutil/trace.go:171","msg":"trace[178771085] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:461; }","duration":"348.811959ms","start":"2026-03-29T01:54:27.516761Z","end":"2026-03-29T01:54:27.865573Z","steps":["trace[178771085] 'agreement among raft nodes before linearized reading' (duration: 347.66328ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:27.864616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.913745ms","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-03-29T01:54:27.865808Z","caller":"traceutil/trace.go:171","msg":"trace[318480569] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:461; }","duration":"182.150667ms","start":"2026-03-29T01:54:27.683644Z","end":"2026-03-29T01:54:27.865795Z","steps":["trace[318480569] 'agreement among raft nodes before linearized reading' (duration: 180.841143ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:27.865741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:27.516741Z","time spent":"348.962853ms","remote":"127.0.0.1:59782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-29T01:54:28.136822Z","caller":"traceutil/trace.go:171","msg":"trace[608518899] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"267.219638ms","start":"2026-03-29T01:54:27.869584Z","end":"2026-03-29T01:54:28.136804Z","steps":["trace[608518899] 'process raft request' (duration: 171.344872ms)","trace[608518899] 'compare' (duration: 95.714922ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:54:28.137013Z","caller":"traceutil/trace.go:171","msg":"trace[1261977768] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"266.942851ms","start":"2026-03-29T01:54:27.870058Z","end":"2026-03-29T01:54:28.137001Z","steps":["trace[1261977768] 'process raft request' (duration: 266.709725ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:28.137305Z","caller":"traceutil/trace.go:171","msg":"trace[291676180] linearizableReadLoop","detail":"{readStateIndex:481; appliedIndex:477; }","duration":"266.235033ms","start":"2026-03-29T01:54:27.871034Z","end":"2026-03-29T01:54:28.137269Z","steps":["trace[291676180] 'read index received' (duration: 31.154327ms)","trace[291676180] 'applied index is now lower than readState.Index' (duration: 235.079366ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:54:28.137352Z","caller":"traceutil/trace.go:171","msg":"trace[1178113708] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"267.187277ms","start":"2026-03-29T01:54:27.870154Z","end":"2026-03-29T01:54:28.137341Z","steps":["trace[1178113708] 'process raft request' (duration: 266.818898ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:28.137468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.407339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:54:28.137573Z","caller":"traceutil/trace.go:171","msg":"trace[1338804207] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:0; response_revision:465; }","duration":"266.539992ms","start":"2026-03-29T01:54:27.871017Z","end":"2026-03-29T01:54:28.137557Z","steps":["trace[1338804207] 'agreement among raft nodes before linearized reading' (duration: 266.378287ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:28.420593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.322359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:54:28.420677Z","caller":"traceutil/trace.go:171","msg":"trace[1528162166] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:466; }","duration":"154.461832ms","start":"2026-03-29T01:54:28.266194Z","end":"2026-03-29T01:54:28.420656Z","steps":["trace[1528162166] 'range keys from in-memory index tree' (duration: 154.229325ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:28.680171Z","caller":"traceutil/trace.go:171","msg":"trace[1021048121] linearizableReadLoop","detail":"{readStateIndex:484; appliedIndex:483; }","duration":"162.27484ms","start":"2026-03-29T01:54:28.517797Z","end":"2026-03-29T01:54:28.680072Z","steps":["trace[1021048121] 'read index received' (duration: 159.630323ms)","trace[1021048121] 'applied index is now lower than readState.Index' (duration: 2.641427ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:54:28.680579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.744723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:54:28.680647Z","caller":"traceutil/trace.go:171","msg":"trace[1316124913] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:467; }","duration":"162.921457ms","start":"2026-03-29T01:54:28.517699Z","end":"2026-03-29T01:54:28.68062Z","steps":["trace[1316124913] 'agreement among raft nodes before linearized reading' (duration: 162.62092ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:28.682251Z","caller":"traceutil/trace.go:171","msg":"trace[1687143444] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"256.522946ms","start":"2026-03-29T01:54:28.425681Z","end":"2026-03-29T01:54:28.682204Z","steps":["trace[1687143444] 'process raft request' (duration: 252.126493ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:28.912357Z","caller":"traceutil/trace.go:171","msg":"trace[65776315] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"207.088825ms","start":"2026-03-29T01:54:28.705238Z","end":"2026-03-29T01:54:28.912327Z","steps":["trace[65776315] 'process raft request' (duration: 116.33364ms)","trace[65776315] 'compare' (duration: 90.574231ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:54:31.104461Z","caller":"traceutil/trace.go:171","msg":"trace[921205798] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"292.666919ms","start":"2026-03-29T01:54:30.811774Z","end":"2026-03-29T01:54:31.104441Z","steps":["trace[921205798] 'process raft request' (duration: 292.568957ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:31.352724Z","caller":"traceutil/trace.go:171","msg":"trace[1675983058] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"536.985204ms","start":"2026-03-29T01:54:30.81571Z","end":"2026-03-29T01:54:31.352695Z","steps":["trace[1675983058] 'process raft request' (duration: 536.79741ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:31.352866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:30.815702Z","time spent":"537.099068ms","remote":"127.0.0.1:59824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:54:31.353065Z","caller":"traceutil/trace.go:171","msg":"trace[1756276059] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"530.795997ms","start":"2026-03-29T01:54:30.822224Z","end":"2026-03-29T01:54:31.35302Z","steps":["trace[1756276059] 'process raft request' (duration: 530.478579ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:31.353138Z","caller":"traceutil/trace.go:171","msg":"trace[1205200482] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"337.622667ms","start":"2026-03-29T01:54:31.015485Z","end":"2026-03-29T01:54:31.353108Z","steps":["trace[1205200482] 'process raft request' (duration: 337.561195ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:31.353264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:30.822219Z","time spent":"530.942451ms","remote":"127.0.0.1:59824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T01:54:31.353286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:31.015451Z","time spent":"337.76552ms","remote":"127.0.0.1:59824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:54:31.353422Z","caller":"traceutil/trace.go:171","msg":"trace[618212597] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"525.865172ms","start":"2026-03-29T01:54:30.827548Z","end":"2026-03-29T01:54:31.353413Z","steps":["trace[618212597] 'process raft request' (duration: 525.191814ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:31.353445Z","caller":"traceutil/trace.go:171","msg":"trace[1876934320] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"532.857671ms","start":"2026-03-29T01:54:30.820575Z","end":"2026-03-29T01:54:31.353433Z","steps":["trace[1876934320] 'process raft request' (duration: 532.020719ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:31.353469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:30.827539Z","time spent":"525.910973ms","remote":"127.0.0.1:59824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T01:54:31.353502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:30.820566Z","time spent":"532.911762ms","remote":"127.0.0.1:59824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:54:31.353549Z","caller":"traceutil/trace.go:171","msg":"trace[315089410] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"493.528567ms","start":"2026-03-29T01:54:30.86001Z","end":"2026-03-29T01:54:31.353539Z","steps":["trace[315089410] 'process raft request' (duration: 492.780358ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:31.353587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:30.859961Z","time spent":"493.608739ms","remote":"127.0.0.1:59824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:54:31.353664Z","caller":"traceutil/trace.go:171","msg":"trace[972247373] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"493.584958ms","start":"2026-03-29T01:54:30.860069Z","end":"2026-03-29T01:54:31.353654Z","steps":["trace[972247373] 'process raft request' (duration: 492.766437ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:31.353661Z","caller":"traceutil/trace.go:171","msg":"trace[83390390] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"531.476195ms","start":"2026-03-29T01:54:30.822166Z","end":"2026-03-29T01:54:31.353642Z","steps":["trace[83390390] 'process raft request' (duration: 530.481719ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:31.353703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:30.860065Z","time spent":"493.620969ms","remote":"127.0.0.1:59824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:54:31.35372Z","caller":"traceutil/trace.go:171","msg":"trace[1549201026] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"493.537297ms","start":"2026-03-29T01:54:30.860138Z","end":"2026-03-29T01:54:31.353676Z","steps":["trace[1549201026] 'process raft request' (duration: 492.742706ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:31.353743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:30.822158Z","time spent":"531.546167ms","remote":"127.0.0.1:59824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:54:31.353771Z","caller":"traceutil/trace.go:171","msg":"trace[416669928] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"461.990351ms","start":"2026-03-29T01:54:30.891774Z","end":"2026-03-29T01:54:31.353764Z","steps":["trace[416669928] 'process raft request' (duration: 461.20292ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:31.353815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:30.891743Z","time spent":"462.054303ms","remote":"127.0.0.1:59824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T01:54:31.353853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:30.860135Z","time spent":"493.653951ms","remote":"127.0.0.1:59824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:54:31.354723Z","caller":"traceutil/trace.go:171","msg":"trace[179917099] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"540.667109ms","start":"2026-03-29T01:54:30.811983Z","end":"2026-03-29T01:54:31.35265Z","steps":["trace[179917099] 'process raft request' (duration: 540.372022ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:31.354815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:30.811977Z","time spent":"542.797044ms","remote":"127.0.0.1:59824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T01:54:32.019119Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620625497567831133,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T01:54:32.519564Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620625497567831133,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T01:54:32.971868Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.617765862s","expected-duration":"1s"} {"level":"info","ts":"2026-03-29T01:54:32.97606Z","caller":"traceutil/trace.go:171","msg":"trace[2016387968] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"1.865306736s","start":"2026-03-29T01:54:31.110728Z","end":"2026-03-29T01:54:32.976034Z","steps":["trace[2016387968] 'process raft request' (duration: 1.86134982s)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:32.976103Z","caller":"traceutil/trace.go:171","msg":"trace[1046178242] transaction","detail":"{read_only:false; number_of_response:1; response_revision:519; }","duration":"1.863278733s","start":"2026-03-29T01:54:31.112804Z","end":"2026-03-29T01:54:32.976083Z","steps":["trace[1046178242] 'process raft request' (duration: 1.863237882s)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:32.976113Z","caller":"traceutil/trace.go:171","msg":"trace[1800071362] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"1.864303711s","start":"2026-03-29T01:54:31.111781Z","end":"2026-03-29T01:54:32.976084Z","steps":["trace[1800071362] 'process raft request' (duration: 1.864185818s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:32.976206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:31.110707Z","time spent":"1.865438779s","remote":"127.0.0.1:60286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T01:54:32.976218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:31.112766Z","time spent":"1.863388196s","remote":"127.0.0.1:59824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7367,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:54:32.976239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:31.111771Z","time spent":"1.864411583s","remote":"127.0.0.1:59824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7317,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:54:32.991108Z","caller":"traceutil/trace.go:171","msg":"trace[1688959853] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"1.627298473s","start":"2026-03-29T01:54:31.363793Z","end":"2026-03-29T01:54:32.991091Z","steps":["trace[1688959853] 'process raft request' (duration: 1.627148389s)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:32.991193Z","caller":"traceutil/trace.go:171","msg":"trace[1667001240] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"1.615898722s","start":"2026-03-29T01:54:31.375282Z","end":"2026-03-29T01:54:32.991181Z","steps":["trace[1667001240] 'process raft request' (duration: 1.61583214s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:32.9912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:31.363767Z","time spent":"1.627390784s","remote":"127.0.0.1:60286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T01:54:32.991248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:31.375272Z","time spent":"1.615950803s","remote":"127.0.0.1:60286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:54:32.991332Z","caller":"traceutil/trace.go:171","msg":"trace[1955982082] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"1.609710662s","start":"2026-03-29T01:54:31.381614Z","end":"2026-03-29T01:54:32.991325Z","steps":["trace[1955982082] 'process raft request' (duration: 1.609525118s)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:32.99125Z","caller":"traceutil/trace.go:171","msg":"trace[854833131] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"1.624142142s","start":"2026-03-29T01:54:31.367096Z","end":"2026-03-29T01:54:32.991238Z","steps":["trace[854833131] 'process raft request' (duration: 1.623924507s)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:32.991362Z","caller":"traceutil/trace.go:171","msg":"trace[65429037] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"1.621083823s","start":"2026-03-29T01:54:31.370273Z","end":"2026-03-29T01:54:32.991357Z","steps":["trace[65429037] 'process raft request' (duration: 1.620770835s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:32.991384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:31.367087Z","time spent":"1.624283215s","remote":"127.0.0.1:60286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T01:54:32.991421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:31.370254Z","time spent":"1.621123123s","remote":"127.0.0.1:59824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:54:32.991338Z","caller":"traceutil/trace.go:171","msg":"trace[55473623] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"1.619983145s","start":"2026-03-29T01:54:31.371347Z","end":"2026-03-29T01:54:32.99133Z","steps":["trace[55473623] 'process raft request' (duration: 1.6197492s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:32.99154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:31.37134Z","time spent":"1.62018463s","remote":"127.0.0.1:60286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T01:54:32.99139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:31.381589Z","time spent":"1.609766884s","remote":"127.0.0.1:59824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:54:32.991695Z","caller":"traceutil/trace.go:171","msg":"trace[756663011] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:534; }","duration":"1.473288311s","start":"2026-03-29T01:54:31.518397Z","end":"2026-03-29T01:54:32.991685Z","steps":["trace[756663011] 'read index received' (duration: 1.45392997s)","trace[756663011] 'applied index is now lower than readState.Index' (duration: 19.357411ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:54:32.991715Z","caller":"traceutil/trace.go:171","msg":"trace[1069700071] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"1.607936147s","start":"2026-03-29T01:54:31.383772Z","end":"2026-03-29T01:54:32.991709Z","steps":["trace[1069700071] 'process raft request' (duration: 1.607840725s)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:32.991747Z","caller":"traceutil/trace.go:171","msg":"trace[638133445] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"1.561329757s","start":"2026-03-29T01:54:31.430408Z","end":"2026-03-29T01:54:32.991738Z","steps":["trace[638133445] 'process raft request' (duration: 1.561254075s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:32.991765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:31.383758Z","time spent":"1.607975098s","remote":"127.0.0.1:59824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:54:32.991784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:31.430389Z","time spent":"1.561376319s","remote":"127.0.0.1:59824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:54:32.99182Z","caller":"traceutil/trace.go:171","msg":"trace[935515512] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"1.610195404s","start":"2026-03-29T01:54:31.381618Z","end":"2026-03-29T01:54:32.991814Z","steps":["trace[935515512] 'process raft request' (duration: 1.609666311s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:32.99185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:31.381593Z","time spent":"1.610240465s","remote":"127.0.0.1:59824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:54:32.991888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.473488786s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:54:32.991925Z","caller":"traceutil/trace.go:171","msg":"trace[384699839] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:528; }","duration":"1.473571187s","start":"2026-03-29T01:54:31.518345Z","end":"2026-03-29T01:54:32.991916Z","steps":["trace[384699839] 'agreement among raft nodes before linearized reading' (duration: 1.473516436s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:32.991962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:31.51832Z","time spent":"1.473632499s","remote":"127.0.0.1:59782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-29T01:54:33.001596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"833.75598ms","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-03-29T01:54:33.001764Z","caller":"traceutil/trace.go:171","msg":"trace[806411730] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:531; }","duration":"833.969394ms","start":"2026-03-29T01:54:32.167777Z","end":"2026-03-29T01:54:33.001746Z","steps":["trace[806411730] 'agreement among raft nodes before linearized reading' (duration: 833.673007ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:33.001967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:32.167757Z","time spent":"834.20125ms","remote":"127.0.0.1:60046","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-03-29T01:54:33.002519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.700183ms","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-03-29T01:54:33.00272Z","caller":"traceutil/trace.go:171","msg":"trace[471821822] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:531; }","duration":"834.765874ms","start":"2026-03-29T01:54:32.167777Z","end":"2026-03-29T01:54:33.002543Z","steps":["trace[471821822] 'agreement among raft nodes before linearized reading' (duration: 834.712302ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:33.002755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:32.167757Z","time spent":"834.990369ms","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-29T01:54:33.003262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.319718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-03-29T01:54:33.003457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.067890689s","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-03-29T01:54:33.003493Z","caller":"traceutil/trace.go:171","msg":"trace[1196347674] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:531; }","duration":"1.068009802s","start":"2026-03-29T01:54:31.935475Z","end":"2026-03-29T01:54:33.003485Z","steps":["trace[1196347674] 'agreement among raft nodes before linearized reading' (duration: 1.067856257s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:33.003512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:31.93545Z","time spent":"1.068057463s","remote":"127.0.0.1:60046","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-03-29T01:54:33.003332Z","caller":"traceutil/trace.go:171","msg":"trace[1131799978] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:531; }","duration":"849.41291ms","start":"2026-03-29T01:54:32.1539Z","end":"2026-03-29T01:54:33.003313Z","steps":["trace[1131799978] 'agreement among raft nodes before linearized reading' (duration: 849.261576ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:33.005066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:32.15384Z","time spent":"851.209454ms","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-29T01:54:36.607085Z","caller":"traceutil/trace.go:171","msg":"trace[1325770817] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:612; }","duration":"111.424705ms","start":"2026-03-29T01:54:36.495636Z","end":"2026-03-29T01:54:36.60706Z","steps":["trace[1325770817] 'read index received' (duration: 110.542212ms)","trace[1325770817] 'applied index is now lower than readState.Index' (duration: 881.553µs)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:54:36.607146Z","caller":"traceutil/trace.go:171","msg":"trace[1418953794] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"122.56828ms","start":"2026-03-29T01:54:36.484544Z","end":"2026-03-29T01:54:36.607112Z","steps":["trace[1418953794] 'process raft request' (duration: 121.89619ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:36.60728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.612968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:54:36.607798Z","caller":"traceutil/trace.go:171","msg":"trace[1321588268] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:594; }","duration":"112.171525ms","start":"2026-03-29T01:54:36.495611Z","end":"2026-03-29T01:54:36.607783Z","steps":["trace[1321588268] 'agreement among raft nodes before linearized reading' (duration: 111.608897ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:36.607392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.628318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:54:36.607932Z","caller":"traceutil/trace.go:171","msg":"trace[530360653] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:594; }","duration":"112.193976ms","start":"2026-03-29T01:54:36.495717Z","end":"2026-03-29T01:54:36.607911Z","steps":["trace[530360653] 'agreement among raft nodes before linearized reading' (duration: 111.578767ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:37.160084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.405733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-29T01:54:37.160117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.181737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-03-29T01:54:37.160118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.829263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-29T01:54:37.160164Z","caller":"traceutil/trace.go:171","msg":"trace[1258759670] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:595; }","duration":"109.501285ms","start":"2026-03-29T01:54:37.050642Z","end":"2026-03-29T01:54:37.160143Z","steps":["trace[1258759670] 'range keys from in-memory index tree' (duration: 109.282451ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:37.160179Z","caller":"traceutil/trace.go:171","msg":"trace[424223452] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:595; }","duration":"125.289319ms","start":"2026-03-29T01:54:37.034874Z","end":"2026-03-29T01:54:37.160164Z","steps":["trace[424223452] 'range keys from in-memory index tree' (duration: 125.050785ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:37.160187Z","caller":"traceutil/trace.go:171","msg":"trace[181179042] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:595; }","duration":"120.912404ms","start":"2026-03-29T01:54:37.03926Z","end":"2026-03-29T01:54:37.160172Z","steps":["trace[181179042] 'range keys from in-memory index tree' (duration: 120.68025ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:37.160988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.892493ms","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-03-29T01:54:37.161048Z","caller":"traceutil/trace.go:171","msg":"trace[631576962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:595; }","duration":"130.980923ms","start":"2026-03-29T01:54:37.030051Z","end":"2026-03-29T01:54:37.161032Z","steps":["trace[631576962] 'range keys from in-memory index tree' (duration: 129.973209ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:37.244305Z","caller":"traceutil/trace.go:171","msg":"trace[955019515] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"180.572479ms","start":"2026-03-29T01:54:37.063707Z","end":"2026-03-29T01:54:37.24428Z","steps":["trace[955019515] 'process raft request' (duration: 180.079022ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:37.540441Z","caller":"traceutil/trace.go:171","msg":"trace[126592157] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"376.926994ms","start":"2026-03-29T01:54:37.163491Z","end":"2026-03-29T01:54:37.540418Z","steps":["trace[126592157] 'process raft request' (duration: 345.026951ms)","trace[126592157] 'compare' (duration: 31.67451ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:54:37.540614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:37.163467Z","time spent":"377.075616ms","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:54:37.540824Z","caller":"traceutil/trace.go:171","msg":"trace[1427779939] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"377.254668ms","start":"2026-03-29T01:54:37.163543Z","end":"2026-03-29T01:54:37.540798Z","steps":["trace[1427779939] 'process raft request' (duration: 376.835342ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:37.54092Z","caller":"traceutil/trace.go:171","msg":"trace[1767710213] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"376.462477ms","start":"2026-03-29T01:54:37.16445Z","end":"2026-03-29T01:54:37.540913Z","steps":["trace[1767710213] 'process raft request' (duration: 376.173293ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:37.541019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:37.164441Z","time spent":"376.514628ms","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:54:37.541024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:37.163523Z","time spent":"377.40287ms","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:54:37.541087Z","caller":"traceutil/trace.go:171","msg":"trace[917481704] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"239.426073ms","start":"2026-03-29T01:54:37.301648Z","end":"2026-03-29T01:54:37.541074Z","steps":["trace[917481704] 'process raft request' (duration: 239.015197ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:37.540877Z","caller":"traceutil/trace.go:171","msg":"trace[889972981] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"376.521017ms","start":"2026-03-29T01:54:37.164339Z","end":"2026-03-29T01:54:37.54086Z","steps":["trace[889972981] 'process raft request' (duration: 376.184962ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:37.541263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:37.164309Z","time spent":"376.920964ms","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:54:38.217593Z","caller":"traceutil/trace.go:171","msg":"trace[122649164] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"315.154434ms","start":"2026-03-29T01:54:37.902415Z","end":"2026-03-29T01:54:38.21757Z","steps":["trace[122649164] 'process raft request' (duration: 310.084458ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:38.21773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:37.90239Z","time spent":"315.266345ms","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:54:38.257935Z","caller":"traceutil/trace.go:171","msg":"trace[864488005] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:620; }","duration":"217.351402ms","start":"2026-03-29T01:54:38.040529Z","end":"2026-03-29T01:54:38.257881Z","steps":["trace[864488005] 'read index received' (duration: 172.015415ms)","trace[864488005] 'applied index is now lower than readState.Index' (duration: 45.334797ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:54:38.258193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.603585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.65\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-29T01:54:38.258242Z","caller":"traceutil/trace.go:171","msg":"trace[718042292] range","detail":"{range_begin:/registry/masterleases/199.204.45.65; range_end:; response_count:1; response_revision:603; }","duration":"217.725737ms","start":"2026-03-29T01:54:38.040495Z","end":"2026-03-29T01:54:38.258221Z","steps":["trace[718042292] 'agreement among raft nodes before linearized reading' (duration: 217.501843ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:38.258234Z","caller":"traceutil/trace.go:171","msg":"trace[1393074880] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"304.166773ms","start":"2026-03-29T01:54:37.954045Z","end":"2026-03-29T01:54:38.258211Z","steps":["trace[1393074880] 'process raft request' (duration: 300.928804ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:38.258447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:37.954025Z","time spent":"304.304324ms","remote":"127.0.0.1:59876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036193,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:54:39.093609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.974509ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:54:39.093676Z","caller":"traceutil/trace.go:171","msg":"trace[592711269] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"216.305554ms","start":"2026-03-29T01:54:38.87736Z","end":"2026-03-29T01:54:39.093665Z","steps":["trace[592711269] 'compare' (duration: 213.649702ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:39.640244Z","caller":"traceutil/trace.go:171","msg":"trace[1377792683] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:627; }","duration":"117.4454ms","start":"2026-03-29T01:54:39.522771Z","end":"2026-03-29T01:54:39.640216Z","steps":["trace[1377792683] 'read index received' (duration: 116.553746ms)","trace[1377792683] 'applied index is now lower than readState.Index' (duration: 890.594µs)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:54:39.640326Z","caller":"traceutil/trace.go:171","msg":"trace[1364574739] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"127.713415ms","start":"2026-03-29T01:54:39.512559Z","end":"2026-03-29T01:54:39.640273Z","steps":["trace[1364574739] 'process raft request' (duration: 127.103775ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:39.640404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.606992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:54:39.640467Z","caller":"traceutil/trace.go:171","msg":"trace[1076210597] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:608; }","duration":"117.686434ms","start":"2026-03-29T01:54:39.52275Z","end":"2026-03-29T01:54:39.640436Z","steps":["trace[1076210597] 'agreement among raft nodes before linearized reading' (duration: 117.602323ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:40.166316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.760415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:54:40.166388Z","caller":"traceutil/trace.go:171","msg":"trace[1375068902] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:608; }","duration":"524.930677ms","start":"2026-03-29T01:54:39.641441Z","end":"2026-03-29T01:54:40.166372Z","steps":["trace[1375068902] 'range keys from in-memory index tree' (duration: 523.822042ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:40.166569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:39.641418Z","time spent":"524.998919ms","remote":"127.0.0.1:59786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-29T01:54:40.166661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.713634ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:54:40.166986Z","caller":"traceutil/trace.go:171","msg":"trace[12405705] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"521.108391ms","start":"2026-03-29T01:54:39.645801Z","end":"2026-03-29T01:54:40.166909Z","steps":["trace[12405705] 'process raft request' (duration: 274.977111ms)","trace[12405705] 'compare' (duration: 245.48727ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:54:40.167161Z","caller":"traceutil/trace.go:171","msg":"trace[23163937] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"521.216943ms","start":"2026-03-29T01:54:39.645921Z","end":"2026-03-29T01:54:40.167138Z","steps":["trace[23163937] 'process raft request' (duration: 520.925128ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:40.167621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:39.645783Z","time spent":"521.773431ms","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:54:40.167702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:39.645911Z","time spent":"521.73681ms","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:54:40.167354Z","caller":"traceutil/trace.go:171","msg":"trace[2038206493] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"470.243239ms","start":"2026-03-29T01:54:39.697092Z","end":"2026-03-29T01:54:40.167336Z","steps":["trace[2038206493] 'process raft request' (duration: 470.193978ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:40.167948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:39.697079Z","time spent":"470.828477ms","remote":"127.0.0.1:60132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:54:40.167353Z","caller":"traceutil/trace.go:171","msg":"trace[1540944554] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"520.552853ms","start":"2026-03-29T01:54:39.646779Z","end":"2026-03-29T01:54:40.167332Z","steps":["trace[1540944554] 'process raft request' (duration: 520.305169ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:40.168105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:39.646752Z","time spent":"521.302274ms","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:54:40.167442Z","caller":"traceutil/trace.go:171","msg":"trace[788573981] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"520.39509ms","start":"2026-03-29T01:54:39.646983Z","end":"2026-03-29T01:54:40.167378Z","steps":["trace[788573981] 'process raft request' (duration: 520.242808ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:40.168258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:39.646923Z","time spent":"521.275963ms","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:54:40.809608Z","caller":"traceutil/trace.go:171","msg":"trace[1103123465] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"227.009239ms","start":"2026-03-29T01:54:40.582573Z","end":"2026-03-29T01:54:40.809582Z","steps":["trace[1103123465] 'process raft request' (duration: 225.379344ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:41.067717Z","caller":"traceutil/trace.go:171","msg":"trace[1458343275] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"141.177211ms","start":"2026-03-29T01:54:40.926511Z","end":"2026-03-29T01:54:41.067689Z","steps":["trace[1458343275] 'process raft request' (duration: 140.957047ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:41.810201Z","caller":"traceutil/trace.go:171","msg":"trace[1373025289] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"124.470073ms","start":"2026-03-29T01:54:41.685707Z","end":"2026-03-29T01:54:41.810177Z","steps":["trace[1373025289] 'process raft request' (duration: 119.238334ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:42.139233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.636859ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:54:42.13933Z","caller":"traceutil/trace.go:171","msg":"trace[600615546] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"267.56169ms","start":"2026-03-29T01:54:41.871753Z","end":"2026-03-29T01:54:42.139314Z","steps":["trace[600615546] 'process raft request' (duration: 52.781659ms)","trace[600615546] 'compare' (duration: 214.509067ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:54:42.142002Z","caller":"traceutil/trace.go:171","msg":"trace[230900431] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"267.390118ms","start":"2026-03-29T01:54:41.874601Z","end":"2026-03-29T01:54:42.141991Z","steps":["trace[230900431] 'process raft request' (duration: 267.359248ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:42.142156Z","caller":"traceutil/trace.go:171","msg":"trace[263452679] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"269.773654ms","start":"2026-03-29T01:54:41.872375Z","end":"2026-03-29T01:54:42.142148Z","steps":["trace[263452679] 'process raft request' (duration: 269.45492ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:42.142343Z","caller":"traceutil/trace.go:171","msg":"trace[1527208407] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"268.398893ms","start":"2026-03-29T01:54:41.873936Z","end":"2026-03-29T01:54:42.142335Z","steps":["trace[1527208407] 'process raft request' (duration: 267.937846ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:44.018627Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620625497567831385,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T01:54:44.519085Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620625497567831385,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T01:54:44.733061Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.333416716s","expected-duration":"1s"} {"level":"info","ts":"2026-03-29T01:54:44.799342Z","caller":"traceutil/trace.go:171","msg":"trace[1636404527] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:646; }","duration":"1.281219573s","start":"2026-03-29T01:54:43.5181Z","end":"2026-03-29T01:54:44.79932Z","steps":["trace[1636404527] 'read index received' (duration: 1.215273391s)","trace[1636404527] 'applied index is now lower than readState.Index' (duration: 65.945112ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:54:44.799468Z","caller":"traceutil/trace.go:171","msg":"trace[356617106] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"649.754614ms","start":"2026-03-29T01:54:44.149627Z","end":"2026-03-29T01:54:44.799382Z","steps":["trace[356617106] 'process raft request' (duration: 649.562481ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:44.799684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:44.149598Z","time spent":"649.967808ms","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:54:44.799776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.689721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-29T01:54:44.799689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.086959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-29T01:54:44.799825Z","caller":"traceutil/trace.go:171","msg":"trace[1469813181] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:627; }","duration":"527.762701ms","start":"2026-03-29T01:54:44.272046Z","end":"2026-03-29T01:54:44.799809Z","steps":["trace[1469813181] 'agreement among raft nodes before linearized reading' (duration: 527.67361ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:44.799851Z","caller":"traceutil/trace.go:171","msg":"trace[1384105874] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:627; }","duration":"607.319093ms","start":"2026-03-29T01:54:44.192515Z","end":"2026-03-29T01:54:44.799834Z","steps":["trace[1384105874] 'agreement among raft nodes before linearized reading' (duration: 607.026618ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:44.799872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:44.272026Z","time spent":"527.838223ms","remote":"127.0.0.1:60046","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-03-29T01:54:44.799895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:44.192482Z","time spent":"607.400295ms","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-29T01:54:44.799987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.281854603s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-29T01:54:44.80043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.5798ms","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-03-29T01:54:44.800575Z","caller":"traceutil/trace.go:171","msg":"trace[638450615] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:627; }","duration":"527.747023ms","start":"2026-03-29T01:54:44.272805Z","end":"2026-03-29T01:54:44.800552Z","steps":["trace[638450615] 'agreement among raft nodes before linearized reading' (duration: 526.838718ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:44.800646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:44.272713Z","time spent":"527.919285ms","remote":"127.0.0.1:60046","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-03-29T01:54:44.800411Z","caller":"traceutil/trace.go:171","msg":"trace[175317256] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"1.28224729s","start":"2026-03-29T01:54:43.518048Z","end":"2026-03-29T01:54:44.800295Z","steps":["trace[175317256] 'agreement among raft nodes before linearized reading' (duration: 1.281404196s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:44.800827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:43.51802Z","time spent":"1.282796758s","remote":"127.0.0.1:59782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-29T01:54:45.512332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.120783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:54:45.512411Z","caller":"traceutil/trace.go:171","msg":"trace[1910549475] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"709.275575ms","start":"2026-03-29T01:54:44.803118Z","end":"2026-03-29T01:54:45.512393Z","steps":["trace[1910549475] 'range keys from in-memory index tree' (duration: 708.99282ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:45.512414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.240688733s","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-03-29T01:54:45.512446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:44.803092Z","time spent":"709.345656ms","remote":"127.0.0.1:59786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-29T01:54:45.512449Z","caller":"traceutil/trace.go:171","msg":"trace[227852830] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:627; }","duration":"1.240780925s","start":"2026-03-29T01:54:44.271656Z","end":"2026-03-29T01:54:45.512437Z","steps":["trace[227852830] 'agreement among raft nodes before linearized reading' (duration: 528.344311ms)","trace[227852830] 'range keys from in-memory index tree' (duration: 712.325971ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:54:45.512482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:44.271623Z","time spent":"1.240845746s","remote":"127.0.0.1:60046","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-03-29T01:54:45.512485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.833131ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:54:45.512539Z","caller":"traceutil/trace.go:171","msg":"trace[1349037134] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"707.038541ms","start":"2026-03-29T01:54:44.80549Z","end":"2026-03-29T01:54:45.512528Z","steps":["trace[1349037134] 'process raft request' (duration: 18.108998ms)","trace[1349037134] 'compare' (duration: 688.73613ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:54:45.512595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:44.805459Z","time spent":"707.110853ms","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:54:45.583391Z","caller":"traceutil/trace.go:171","msg":"trace[1944291405] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"777.844692ms","start":"2026-03-29T01:54:44.805528Z","end":"2026-03-29T01:54:45.583373Z","steps":["trace[1944291405] 'process raft request' (duration: 777.561577ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:45.583506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:44.805506Z","time spent":"777.948914ms","remote":"127.0.0.1:59854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":781,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:54:45.583473Z","caller":"traceutil/trace.go:171","msg":"trace[1353588386] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"776.896217ms","start":"2026-03-29T01:54:44.806564Z","end":"2026-03-29T01:54:45.58346Z","steps":["trace[1353588386] 'process raft request' (duration: 776.710184ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:45.583367Z","caller":"traceutil/trace.go:171","msg":"trace[726512810] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"776.964148ms","start":"2026-03-29T01:54:44.806376Z","end":"2026-03-29T01:54:45.583341Z","steps":["trace[726512810] 'process raft request' (duration: 776.844406ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:45.583729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:44.806551Z","time spent":"777.109251ms","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:54:45.58399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:44.806353Z","time spent":"777.581938ms","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:54:45.780852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.302225ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:54:45.780958Z","caller":"traceutil/trace.go:171","msg":"trace[608569631] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:649; }","duration":"252.117708ms","start":"2026-03-29T01:54:45.528823Z","end":"2026-03-29T01:54:45.780941Z","steps":["trace[608569631] 'read index received' (duration: 54.177835ms)","trace[608569631] 'applied index is now lower than readState.Index' (duration: 197.938503ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:54:45.781024Z","caller":"traceutil/trace.go:171","msg":"trace[370952072] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"264.916575ms","start":"2026-03-29T01:54:45.516089Z","end":"2026-03-29T01:54:45.781006Z","steps":["trace[370952072] 'process raft request' (duration: 100.378017ms)","trace[370952072] 'compare' (duration: 164.177373ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:54:45.781029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.212229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:54:45.781064Z","caller":"traceutil/trace.go:171","msg":"trace[166221163] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"252.26012ms","start":"2026-03-29T01:54:45.528795Z","end":"2026-03-29T01:54:45.781055Z","steps":["trace[166221163] 'agreement among raft nodes before linearized reading' (duration: 252.191499ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:46.759116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.377726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:54:46.759224Z","caller":"traceutil/trace.go:171","msg":"trace[1078510690] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:634; }","duration":"242.518868ms","start":"2026-03-29T01:54:46.516683Z","end":"2026-03-29T01:54:46.759202Z","steps":["trace[1078510690] 'range keys from in-memory index tree' (duration: 242.302805ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:46.759121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.823388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2kvtv\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-03-29T01:54:46.759549Z","caller":"traceutil/trace.go:171","msg":"trace[1581657856] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2kvtv; range_end:; response_count:1; response_revision:634; }","duration":"215.261815ms","start":"2026-03-29T01:54:46.544273Z","end":"2026-03-29T01:54:46.759534Z","steps":["trace[1581657856] 'range keys from in-memory index tree' (duration: 214.715366ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:48.018011Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620625497567831402,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-29T01:54:48.371811Z","caller":"traceutil/trace.go:171","msg":"trace[600158613] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"854.716128ms","start":"2026-03-29T01:54:47.517075Z","end":"2026-03-29T01:54:48.371791Z","steps":["trace[600158613] 'read index received' (duration: 854.572146ms)","trace[600158613] 'applied index is now lower than readState.Index' (duration: 143.052µs)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:54:48.371897Z","caller":"traceutil/trace.go:171","msg":"trace[332885865] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"1.596730269s","start":"2026-03-29T01:54:46.775157Z","end":"2026-03-29T01:54:48.371887Z","steps":["trace[332885865] 'process raft request' (duration: 1.596503925s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:48.371989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:46.775128Z","time spent":"1.596792089s","remote":"127.0.0.1:59964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:54:48.37213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.060113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:54:48.372165Z","caller":"traceutil/trace.go:171","msg":"trace[252840175] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"855.130504ms","start":"2026-03-29T01:54:47.517026Z","end":"2026-03-29T01:54:48.372156Z","steps":["trace[252840175] 'agreement among raft nodes before linearized reading' (duration: 855.075623ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:48.372191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:47.517007Z","time spent":"855.177575ms","remote":"127.0.0.1:59782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-29T01:54:48.872438Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620625497567831405,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T01:54:49.373685Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620625497567831405,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T01:54:49.874265Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620625497567831405,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T01:54:50.373719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999373594s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-29T01:54:50.373805Z","caller":"traceutil/trace.go:171","msg":"trace[1005466107] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999551776s","start":"2026-03-29T01:54:48.374234Z","end":"2026-03-29T01:54:50.373785Z","steps":["trace[1005466107] 'agreement among raft nodes before linearized reading' (duration: 1.999412844s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:50.37386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:48.374202Z","time spent":"1.999642288s","remote":"127.0.0.1:59786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/29 01:54:50 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-29T01:54:50.374782Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620625497567831405,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T01:54:50.874928Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620625497567831405,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T01:54:51.375506Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620625497567831405,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T01:54:51.876648Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620625497567831405,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T01:54:52.319951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.495000273s","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-03-29T01:54:52.32112Z","caller":"traceutil/trace.go:171","msg":"trace[1989560905] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:635; }","duration":"3.496180011s","start":"2026-03-29T01:54:48.82492Z","end":"2026-03-29T01:54:52.3211Z","steps":["trace[1989560905] 'range keys from in-memory index tree' (duration: 3.494986233s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:52.377302Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620625497567831405,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T01:54:52.378433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999976948s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-29T01:54:52.37849Z","caller":"traceutil/trace.go:171","msg":"trace[2102382056] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00007296s","start":"2026-03-29T01:54:50.378403Z","end":"2026-03-29T01:54:52.378476Z","steps":["trace[2102382056] 'agreement among raft nodes before linearized reading' (duration: 1.999998279s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:52.378596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:50.378391Z","time spent":"2.000183472s","remote":"127.0.0.1:59782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-29T01:54:52.385632Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.014130056s","expected-duration":"1s"} {"level":"info","ts":"2026-03-29T01:54:52.386005Z","caller":"traceutil/trace.go:171","msg":"trace[1193557579] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"4.058409138s","start":"2026-03-29T01:54:48.327572Z","end":"2026-03-29T01:54:52.385982Z","steps":["trace[1193557579] 'process raft request' (duration: 4.058238875s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:52.386189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:48.327532Z","time spent":"4.05855875s","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:54:52.390922Z","caller":"traceutil/trace.go:171","msg":"trace[1637473792] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:656; }","duration":"4.019032783s","start":"2026-03-29T01:54:48.371864Z","end":"2026-03-29T01:54:52.390896Z","steps":["trace[1637473792] 'read index received' (duration: 4.013936511s)","trace[1637473792] 'applied index is now lower than readState.Index' (duration: 5.094902ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:54:52.391082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.606356269s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-29T01:54:52.391111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.803590319s","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-03-29T01:54:52.391125Z","caller":"traceutil/trace.go:171","msg":"trace[343146555] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:637; }","duration":"4.606457821s","start":"2026-03-29T01:54:47.784655Z","end":"2026-03-29T01:54:52.391113Z","steps":["trace[343146555] 'agreement among raft nodes before linearized reading' (duration: 4.606279628s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:52.391119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.802879188s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-03-29T01:54:52.391153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:47.784629Z","time spent":"4.606517582s","remote":"127.0.0.1:60046","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-03-29T01:54:52.391148Z","caller":"traceutil/trace.go:171","msg":"trace[1150159468] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:637; }","duration":"4.803667729s","start":"2026-03-29T01:54:47.587468Z","end":"2026-03-29T01:54:52.391136Z","steps":["trace[1150159468] 'agreement among raft nodes before linearized reading' (duration: 4.803556078s)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:52.391171Z","caller":"traceutil/trace.go:171","msg":"trace[27065956] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:637; }","duration":"4.80298542s","start":"2026-03-29T01:54:47.588172Z","end":"2026-03-29T01:54:52.391158Z","steps":["trace[27065956] 'agreement among raft nodes before linearized reading' (duration: 4.802799727s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:52.391188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:47.587446Z","time spent":"4.803732221s","remote":"127.0.0.1:60046","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-03-29T01:54:52.391203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:47.588151Z","time spent":"4.80304558s","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-29T01:54:52.391331Z","caller":"traceutil/trace.go:171","msg":"trace[368607733] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"2.014287476s","start":"2026-03-29T01:54:50.377029Z","end":"2026-03-29T01:54:52.391316Z","steps":["trace[368607733] 'process raft request' (duration: 2.013733178s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:52.391476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.350007328s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.65\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-29T01:54:52.391546Z","caller":"traceutil/trace.go:171","msg":"trace[1693610337] range","detail":"{range_begin:/registry/masterleases/199.204.45.65; range_end:; response_count:1; response_revision:637; }","duration":"4.35010959s","start":"2026-03-29T01:54:48.041419Z","end":"2026-03-29T01:54:52.391528Z","steps":["trace[1693610337] 'agreement among raft nodes before linearized reading' (duration: 4.349869076s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:52.39159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:48.041397Z","time spent":"4.350181431s","remote":"127.0.0.1:59806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.65\" "} {"level":"warn","ts":"2026-03-29T01:54:52.391684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.874196239s","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-03-29T01:54:52.391803Z","caller":"traceutil/trace.go:171","msg":"trace[1469968118] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:637; }","duration":"4.874354791s","start":"2026-03-29T01:54:47.51743Z","end":"2026-03-29T01:54:52.391785Z","steps":["trace[1469968118] 'agreement among raft nodes before linearized reading' (duration: 4.874135846s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:52.391851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:47.517408Z","time spent":"4.874428942s","remote":"127.0.0.1:60046","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-03-29T01:54:52.392158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:50.377005Z","time spent":"2.014371967s","remote":"127.0.0.1:59854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:54:52.718826Z","caller":"traceutil/trace.go:171","msg":"trace[561061420] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"152.930692ms","start":"2026-03-29T01:54:52.565873Z","end":"2026-03-29T01:54:52.718803Z","steps":["trace[561061420] 'process raft request' (duration: 152.80874ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:52.946948Z","caller":"traceutil/trace.go:171","msg":"trace[1139356345] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"367.369632ms","start":"2026-03-29T01:54:52.579555Z","end":"2026-03-29T01:54:52.946925Z","steps":["trace[1139356345] 'process raft request' (duration: 367.22945ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:54:52.94711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:54:52.579527Z","time spent":"367.490325ms","remote":"127.0.0.1:59964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17228,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:54:55.163458Z","caller":"traceutil/trace.go:171","msg":"trace[746753738] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"202.290844ms","start":"2026-03-29T01:54:54.961147Z","end":"2026-03-29T01:54:55.163438Z","steps":["trace[746753738] 'process raft request' (duration: 201.826307ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:56.993597Z","caller":"traceutil/trace.go:171","msg":"trace[1114937684] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"124.577923ms","start":"2026-03-29T01:54:56.868988Z","end":"2026-03-29T01:54:56.993566Z","steps":["trace[1114937684] 'process raft request' (duration: 124.433901ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:55:03.639355Z","caller":"traceutil/trace.go:171","msg":"trace[1945988339] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"168.935367ms","start":"2026-03-29T01:55:03.470393Z","end":"2026-03-29T01:55:03.639329Z","steps":["trace[1945988339] 'process raft request' (duration: 168.723513ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:55:03.654657Z","caller":"traceutil/trace.go:171","msg":"trace[249317823] linearizableReadLoop","detail":"{readStateIndex:782; appliedIndex:780; }","duration":"137.101598ms","start":"2026-03-29T01:55:03.51754Z","end":"2026-03-29T01:55:03.654642Z","steps":["trace[249317823] 'read index received' (duration: 121.720323ms)","trace[249317823] 'applied index is now lower than readState.Index' (duration: 15.380665ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:55:03.654735Z","caller":"traceutil/trace.go:171","msg":"trace[571136703] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"181.502785ms","start":"2026-03-29T01:55:03.473225Z","end":"2026-03-29T01:55:03.654728Z","steps":["trace[571136703] 'process raft request' (duration: 181.350132ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:55:03.654901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.325171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:55:03.654947Z","caller":"traceutil/trace.go:171","msg":"trace[1356112330] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:756; }","duration":"137.440922ms","start":"2026-03-29T01:55:03.517496Z","end":"2026-03-29T01:55:03.654937Z","steps":["trace[1356112330] 'agreement among raft nodes before linearized reading' (duration: 137.336181ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:55:03.804642Z","caller":"traceutil/trace.go:171","msg":"trace[1874694379] linearizableReadLoop","detail":"{readStateIndex:783; appliedIndex:782; }","duration":"140.674987ms","start":"2026-03-29T01:55:03.663944Z","end":"2026-03-29T01:55:03.804619Z","steps":["trace[1874694379] 'read index received' (duration: 79.766425ms)","trace[1874694379] 'applied index is now lower than readState.Index' (duration: 60.907652ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:55:03.804903Z","caller":"traceutil/trace.go:171","msg":"trace[1074027551] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"144.213586ms","start":"2026-03-29T01:55:03.660637Z","end":"2026-03-29T01:55:03.804851Z","steps":["trace[1074027551] 'process raft request' (duration: 82.998679ms)","trace[1074027551] 'compare' (duration: 60.868911ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:55:03.80498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.012433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:55:03.805148Z","caller":"traceutil/trace.go:171","msg":"trace[2012331722] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:757; }","duration":"141.230107ms","start":"2026-03-29T01:55:03.663902Z","end":"2026-03-29T01:55:03.805133Z","steps":["trace[2012331722] 'agreement among raft nodes before linearized reading' (duration: 140.890971ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:55:03.827137Z","caller":"traceutil/trace.go:171","msg":"trace[1048957209] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"158.046246ms","start":"2026-03-29T01:55:03.66907Z","end":"2026-03-29T01:55:03.827116Z","steps":["trace[1048957209] 'process raft request' (duration: 157.848143ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:55:08.376274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:55:08.044888Z","time spent":"331.380465ms","remote":"127.0.0.1:59806","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-29T01:55:08.77607Z","caller":"traceutil/trace.go:171","msg":"trace[366353698] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"187.803366ms","start":"2026-03-29T01:55:08.588239Z","end":"2026-03-29T01:55:08.776043Z","steps":["trace[366353698] 'process raft request' (duration: 110.888755ms)","trace[366353698] 'compare' (duration: 76.724508ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:55:08.776367Z","caller":"traceutil/trace.go:171","msg":"trace[1690457270] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"188.02563ms","start":"2026-03-29T01:55:08.588316Z","end":"2026-03-29T01:55:08.776342Z","steps":["trace[1690457270] 'process raft request' (duration: 187.678574ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:55:08.776382Z","caller":"traceutil/trace.go:171","msg":"trace[421417217] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"187.993929ms","start":"2026-03-29T01:55:08.588365Z","end":"2026-03-29T01:55:08.776359Z","steps":["trace[421417217] 'process raft request' (duration: 187.896077ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:55:12.559214Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620625497567831769,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T01:55:13.059457Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620625497567831769,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T01:55:13.560298Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620625497567831769,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T01:55:13.619797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.100927345s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:55:13.619857Z","caller":"traceutil/trace.go:171","msg":"trace[566448750] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:807; }","duration":"2.101046157s","start":"2026-03-29T01:55:11.518797Z","end":"2026-03-29T01:55:13.619843Z","steps":["trace[566448750] 'range keys from in-memory index tree' (duration: 2.100762023s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:55:13.619886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:55:11.518768Z","time spent":"2.101111699s","remote":"127.0.0.1:59782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-29T01:55:13.627777Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.942775244s","expected-duration":"1s"} {"level":"info","ts":"2026-03-29T01:55:13.628179Z","caller":"traceutil/trace.go:171","msg":"trace[1134544144] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"1.943288032s","start":"2026-03-29T01:55:11.684873Z","end":"2026-03-29T01:55:13.628161Z","steps":["trace[1134544144] 'process raft request' (duration: 1.943095749s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:55:13.628947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:55:11.684848Z","time spent":"1.943457085s","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:55:13.638899Z","caller":"traceutil/trace.go:171","msg":"trace[385114754] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"1.103319792s","start":"2026-03-29T01:55:12.535559Z","end":"2026-03-29T01:55:13.638878Z","steps":["trace[385114754] 'process raft request' (duration: 1.103112168s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:55:13.639137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:55:12.535535Z","time spent":"1.103465345s","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:55:13.654753Z","caller":"traceutil/trace.go:171","msg":"trace[2066665363] linearizableReadLoop","detail":"{readStateIndex:838; appliedIndex:835; }","duration":"1.596381526s","start":"2026-03-29T01:55:12.058345Z","end":"2026-03-29T01:55:13.654726Z","steps":["trace[2066665363] 'read index received' (duration: 1.569609455s)","trace[2066665363] 'applied index is now lower than readState.Index' (duration: 26.770571ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:55:13.654994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.59664877s","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-03-29T01:55:13.654992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.350287297s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:55:13.65504Z","caller":"traceutil/trace.go:171","msg":"trace[1925908260] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:809; }","duration":"1.596700841s","start":"2026-03-29T01:55:12.058328Z","end":"2026-03-29T01:55:13.655029Z","steps":["trace[1925908260] 'agreement among raft nodes before linearized reading' (duration: 1.596549358s)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:55:13.655062Z","caller":"traceutil/trace.go:171","msg":"trace[97110596] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:809; }","duration":"1.350411429s","start":"2026-03-29T01:55:12.304637Z","end":"2026-03-29T01:55:13.655048Z","steps":["trace[97110596] 'agreement among raft nodes before linearized reading' (duration: 1.350294007s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:55:13.655087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:55:12.058302Z","time spent":"1.596772152s","remote":"127.0.0.1:60046","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-03-29T01:55:13.6551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:55:12.304607Z","time spent":"1.35048342s","remote":"127.0.0.1:59998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-03-29T01:55:13.655119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.922129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-29T01:55:13.655143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.942221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-29T01:55:13.65515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.412483ms","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-03-29T01:55:13.655338Z","caller":"traceutil/trace.go:171","msg":"trace[1864919834] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:809; }","duration":"970.629008ms","start":"2026-03-29T01:55:12.684687Z","end":"2026-03-29T01:55:13.655316Z","steps":["trace[1864919834] 'agreement among raft nodes before linearized reading' (duration: 970.321372ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:55:13.655219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.491673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:55:13.655396Z","caller":"traceutil/trace.go:171","msg":"trace[935589191] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:809; }","duration":"673.698837ms","start":"2026-03-29T01:55:12.981685Z","end":"2026-03-29T01:55:13.655384Z","steps":["trace[935589191] 'agreement among raft nodes before linearized reading' (duration: 673.510023ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:55:13.655396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:55:12.684674Z","time spent":"970.709949ms","remote":"127.0.0.1:60046","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-03-29T01:55:13.655473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:55:12.981664Z","time spent":"673.797958ms","remote":"127.0.0.1:59782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-29T01:55:13.655227Z","caller":"traceutil/trace.go:171","msg":"trace[1938068845] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:809; }","duration":"136.07016ms","start":"2026-03-29T01:55:13.519121Z","end":"2026-03-29T01:55:13.655191Z","steps":["trace[1938068845] 'agreement among raft nodes before linearized reading' (duration: 135.914848ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:55:13.655221Z","caller":"traceutil/trace.go:171","msg":"trace[1058411530] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:809; }","duration":"853.051683ms","start":"2026-03-29T01:55:12.802151Z","end":"2026-03-29T01:55:13.655203Z","steps":["trace[1058411530] 'agreement among raft nodes before linearized reading' (duration: 852.847239ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:55:13.655756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:55:12.802135Z","time spent":"853.612092ms","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-29T01:55:13.65515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.558713ms","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-03-29T01:55:13.655898Z","caller":"traceutil/trace.go:171","msg":"trace[1800021645] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:809; }","duration":"853.349536ms","start":"2026-03-29T01:55:12.802537Z","end":"2026-03-29T01:55:13.655886Z","steps":["trace[1800021645] 'agreement among raft nodes before linearized reading' (duration: 852.426311ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:55:13.656326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.082551ms","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-03-29T01:55:13.656375Z","caller":"traceutil/trace.go:171","msg":"trace[1819067335] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:809; }","duration":"857.170993ms","start":"2026-03-29T01:55:12.799193Z","end":"2026-03-29T01:55:13.656364Z","steps":["trace[1819067335] 'agreement among raft nodes before linearized reading' (duration: 857.049171ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:55:13.656408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:55:12.799166Z","time spent":"857.234784ms","remote":"127.0.0.1:60046","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-03-29T01:55:13.656876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:55:12.802526Z","time spent":"853.395228ms","remote":"127.0.0.1:60046","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-03-29T01:55:20.060042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.912442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-29T01:55:20.060145Z","caller":"traceutil/trace.go:171","msg":"trace[1089874597] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:880; }","duration":"194.060525ms","start":"2026-03-29T01:55:19.866062Z","end":"2026-03-29T01:55:20.060123Z","steps":["trace[1089874597] 'count revisions from in-memory index tree' (duration: 193.824631ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:55:27.390306Z","caller":"traceutil/trace.go:171","msg":"trace[709833210] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"244.138004ms","start":"2026-03-29T01:55:27.146152Z","end":"2026-03-29T01:55:27.39029Z","steps":["trace[709833210] 'process raft request' (duration: 244.018812ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:56:20.285303Z","caller":"traceutil/trace.go:171","msg":"trace[1744994553] linearizableReadLoop","detail":"{readStateIndex:1204; appliedIndex:1203; }","duration":"108.022738ms","start":"2026-03-29T01:56:20.177264Z","end":"2026-03-29T01:56:20.285287Z","steps":["trace[1744994553] 'read index received' (duration: 107.795494ms)","trace[1744994553] 'applied index is now lower than readState.Index' (duration: 226.574µs)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:56:20.285398Z","caller":"traceutil/trace.go:171","msg":"trace[863438465] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"141.442302ms","start":"2026-03-29T01:56:20.143941Z","end":"2026-03-29T01:56:20.285383Z","steps":["trace[863438465] 'process raft request' (duration: 141.187107ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:56:20.285464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.192402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-29T01:56:20.285488Z","caller":"traceutil/trace.go:171","msg":"trace[2030789170] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1160; }","duration":"108.262003ms","start":"2026-03-29T01:56:20.177217Z","end":"2026-03-29T01:56:20.285479Z","steps":["trace[2030789170] 'agreement among raft nodes before linearized reading' (duration: 108.170031ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:56:23.709158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.490361ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-29T01:56:24.24954Z","caller":"traceutil/trace.go:171","msg":"trace[1868336614] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"127.542738ms","start":"2026-03-29T01:56:24.121972Z","end":"2026-03-29T01:56:24.249515Z","steps":["trace[1868336614] 'process raft request' (duration: 127.403235ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:56:25.171918Z","caller":"traceutil/trace.go:171","msg":"trace[1495063985] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"152.096102ms","start":"2026-03-29T01:56:25.0198Z","end":"2026-03-29T01:56:25.171896Z","steps":["trace[1495063985] 'process raft request' (duration: 151.956009ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:56:26.680848Z","caller":"traceutil/trace.go:171","msg":"trace[752431410] linearizableReadLoop","detail":"{readStateIndex:1224; appliedIndex:1223; }","duration":"163.647012ms","start":"2026-03-29T01:56:26.517177Z","end":"2026-03-29T01:56:26.680824Z","steps":["trace[752431410] 'read index received' (duration: 91.373691ms)","trace[752431410] 'applied index is now lower than readState.Index' (duration: 72.271941ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:56:26.680925Z","caller":"traceutil/trace.go:171","msg":"trace[550876150] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"341.531003ms","start":"2026-03-29T01:56:26.339366Z","end":"2026-03-29T01:56:26.680896Z","steps":["trace[550876150] 'process raft request' (duration: 269.258041ms)","trace[550876150] 'compare' (duration: 72.014967ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:56:26.681027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.819276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-29T01:56:26.681078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:56:26.339344Z","time spent":"341.651095ms","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:56:26.681145Z","caller":"traceutil/trace.go:171","msg":"trace[1377376679] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1179; }","duration":"163.981548ms","start":"2026-03-29T01:56:26.517135Z","end":"2026-03-29T01:56:26.681117Z","steps":["trace[1377376679] 'agreement among raft nodes before linearized reading' (duration: 163.781474ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:56:26.681244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.094739ms","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-03-29T01:56:26.68131Z","caller":"traceutil/trace.go:171","msg":"trace[1055655625] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1179; }","duration":"159.174021ms","start":"2026-03-29T01:56:26.522123Z","end":"2026-03-29T01:56:26.681297Z","steps":["trace[1055655625] 'agreement among raft nodes before linearized reading' (duration: 158.930676ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:56:26.681409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.393205ms","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-03-29T01:56:26.681481Z","caller":"traceutil/trace.go:171","msg":"trace[681441900] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1179; }","duration":"104.498867ms","start":"2026-03-29T01:56:26.57696Z","end":"2026-03-29T01:56:26.681459Z","steps":["trace[681441900] 'agreement among raft nodes before linearized reading' (duration: 104.331134ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:56:59.611194Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620625497567832964,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-29T01:56:59.678634Z","caller":"traceutil/trace.go:171","msg":"trace[1297615538] linearizableReadLoop","detail":"{readStateIndex:1372; appliedIndex:1371; }","duration":"567.855449ms","start":"2026-03-29T01:56:59.110755Z","end":"2026-03-29T01:56:59.678611Z","steps":["trace[1297615538] 'read index received' (duration: 567.640964ms)","trace[1297615538] 'applied index is now lower than readState.Index' (duration: 211.915µs)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:56:59.678699Z","caller":"traceutil/trace.go:171","msg":"trace[632159716] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"658.60173ms","start":"2026-03-29T01:56:59.020067Z","end":"2026-03-29T01:56:59.678669Z","steps":["trace[632159716] 'process raft request' (duration: 658.393786ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:56:59.678831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:56:59.020043Z","time spent":"658.721403ms","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:56:59.678896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.434674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-29T01:56:59.67895Z","caller":"traceutil/trace.go:171","msg":"trace[1643285285] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1318; }","duration":"562.544926ms","start":"2026-03-29T01:56:59.11639Z","end":"2026-03-29T01:56:59.678935Z","steps":["trace[1643285285] 'agreement among raft nodes before linearized reading' (duration: 562.361862ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:56:59.678996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:56:59.116361Z","time spent":"562.623008ms","remote":"127.0.0.1:60046","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-03-29T01:56:59.678979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.228731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-03-29T01:56:59.679017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.199427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-29T01:56:59.679069Z","caller":"traceutil/trace.go:171","msg":"trace[407982428] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1318; }","duration":"556.364074ms","start":"2026-03-29T01:56:59.122686Z","end":"2026-03-29T01:56:59.67905Z","steps":["trace[407982428] 'agreement among raft nodes before linearized reading' (duration: 556.16465ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:56:59.67914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:56:59.122674Z","time spent":"556.454576ms","remote":"127.0.0.1:60046","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-03-29T01:56:59.679107Z","caller":"traceutil/trace.go:171","msg":"trace[1673568106] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1318; }","duration":"568.37687ms","start":"2026-03-29T01:56:59.110713Z","end":"2026-03-29T01:56:59.67909Z","steps":["trace[1673568106] 'agreement among raft nodes before linearized reading' (duration: 568.009502ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:56:59.679215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:56:59.110699Z","time spent":"568.500433ms","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-29T01:56:59.679535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.593459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-29T01:56:59.679599Z","caller":"traceutil/trace.go:171","msg":"trace[258567582] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1318; }","duration":"556.708771ms","start":"2026-03-29T01:56:59.122877Z","end":"2026-03-29T01:56:59.679586Z","steps":["trace[258567582] 'agreement among raft nodes before linearized reading' (duration: 555.913545ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:56:59.679632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:56:59.122851Z","time spent":"556.772922ms","remote":"127.0.0.1:60046","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-03-29T01:57:03.942318Z","caller":"traceutil/trace.go:171","msg":"trace[318366114] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"177.6864ms","start":"2026-03-29T01:57:03.764608Z","end":"2026-03-29T01:57:03.942295Z","steps":["trace[318366114] 'process raft request' (duration: 177.646639ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:03.942381Z","caller":"traceutil/trace.go:171","msg":"trace[672017744] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"179.03495ms","start":"2026-03-29T01:57:03.763299Z","end":"2026-03-29T01:57:03.942347Z","steps":["trace[672017744] 'process raft request' (duration: 178.744334ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:03.94251Z","caller":"traceutil/trace.go:171","msg":"trace[402980252] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"179.187592ms","start":"2026-03-29T01:57:03.763313Z","end":"2026-03-29T01:57:03.942501Z","steps":["trace[402980252] 'process raft request' (duration: 178.904236ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:04.308167Z","caller":"traceutil/trace.go:171","msg":"trace[377470745] linearizableReadLoop","detail":"{readStateIndex:1393; appliedIndex:1392; }","duration":"178.133624ms","start":"2026-03-29T01:57:04.130015Z","end":"2026-03-29T01:57:04.308149Z","steps":["trace[377470745] 'read index received' (duration: 177.92559ms)","trace[377470745] 'applied index is now lower than readState.Index' (duration: 207.394µs)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:57:04.308289Z","caller":"traceutil/trace.go:171","msg":"trace[757901508] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"178.698136ms","start":"2026-03-29T01:57:04.129555Z","end":"2026-03-29T01:57:04.308253Z","steps":["trace[757901508] 'process raft request' (duration: 178.443531ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:04.308481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.44714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-03-29T01:57:04.308526Z","caller":"traceutil/trace.go:171","msg":"trace[2049930118] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1338; }","duration":"178.535143ms","start":"2026-03-29T01:57:04.12998Z","end":"2026-03-29T01:57:04.308515Z","steps":["trace[2049930118] 'agreement among raft nodes before linearized reading' (duration: 178.236966ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:06.802664Z","caller":"traceutil/trace.go:171","msg":"trace[722580343] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"128.280272ms","start":"2026-03-29T01:57:06.674354Z","end":"2026-03-29T01:57:06.802634Z","steps":["trace[722580343] 'process raft request' (duration: 128.078218ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:07.798129Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620625497567833037,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-29T01:57:07.865962Z","caller":"traceutil/trace.go:171","msg":"trace[1344710411] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"639.362504ms","start":"2026-03-29T01:57:07.226575Z","end":"2026-03-29T01:57:07.865938Z","steps":["trace[1344710411] 'process raft request' (duration: 639.18355ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:07.86619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:07.226546Z","time spent":"639.482557ms","remote":"127.0.0.1:59958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5718,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:57:08.377015Z","caller":"traceutil/trace.go:171","msg":"trace[1823622627] linearizableReadLoop","detail":"{readStateIndex:1405; appliedIndex:1403; }","duration":"1.079095277s","start":"2026-03-29T01:57:07.297902Z","end":"2026-03-29T01:57:08.376997Z","steps":["trace[1823622627] 'read index received' (duration: 567.998551ms)","trace[1823622627] 'applied index is now lower than readState.Index' (duration: 511.095666ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:57:08.377087Z","caller":"traceutil/trace.go:171","msg":"trace[845921832] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"594.514988ms","start":"2026-03-29T01:57:07.782565Z","end":"2026-03-29T01:57:08.37708Z","steps":["trace[845921832] 'process raft request' (duration: 594.319494ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:08.377231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:07.782534Z","time spent":"594.56902ms","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:57:08.377208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.079292881s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:57:08.377339Z","caller":"traceutil/trace.go:171","msg":"trace[1735314740] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1350; }","duration":"1.079437115s","start":"2026-03-29T01:57:07.297852Z","end":"2026-03-29T01:57:08.377289Z","steps":["trace[1735314740] 'agreement among raft nodes before linearized reading' (duration: 1.079247101s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:08.377475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:07.297832Z","time spent":"1.079600908s","remote":"127.0.0.1:59930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"info","ts":"2026-03-29T01:57:08.411054Z","caller":"traceutil/trace.go:171","msg":"trace[1618010223] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"484.834563ms","start":"2026-03-29T01:57:07.926192Z","end":"2026-03-29T01:57:08.411026Z","steps":["trace[1618010223] 'process raft request' (duration: 484.569157ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:08.411211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.711852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-29T01:57:08.411298Z","caller":"traceutil/trace.go:171","msg":"trace[240037921] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1351; }","duration":"485.912826ms","start":"2026-03-29T01:57:07.925362Z","end":"2026-03-29T01:57:08.411275Z","steps":["trace[240037921] 'agreement among raft nodes before linearized reading' (duration: 485.691212ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:08.411484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:07.925339Z","time spent":"486.12182ms","remote":"127.0.0.1:60286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":31,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-03-29T01:57:08.411454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.421545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-29T01:57:08.411567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.338771ms","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-03-29T01:57:08.411658Z","caller":"traceutil/trace.go:171","msg":"trace[852115423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1351; }","duration":"362.476404ms","start":"2026-03-29T01:57:08.049161Z","end":"2026-03-29T01:57:08.411637Z","steps":["trace[852115423] 'agreement among raft nodes before linearized reading' (duration: 362.221439ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:08.411701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:08.049137Z","time spent":"362.553545ms","remote":"127.0.0.1:60046","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-03-29T01:57:08.411747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.553086ms","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-03-29T01:57:08.411808Z","caller":"traceutil/trace.go:171","msg":"trace[230486069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1351; }","duration":"362.620898ms","start":"2026-03-29T01:57:08.049172Z","end":"2026-03-29T01:57:08.411793Z","steps":["trace[230486069] 'agreement among raft nodes before linearized reading' (duration: 362.468444ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:08.411834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:08.04911Z","time spent":"362.71889ms","remote":"127.0.0.1:60046","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-03-29T01:57:08.411665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:07.926165Z","time spent":"484.959076ms","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:57:08.411554Z","caller":"traceutil/trace.go:171","msg":"trace[856298549] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1351; }","duration":"607.61475ms","start":"2026-03-29T01:57:07.80392Z","end":"2026-03-29T01:57:08.411535Z","steps":["trace[856298549] 'agreement among raft nodes before linearized reading' (duration: 607.346984ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:08.411929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:07.803906Z","time spent":"608.004059ms","remote":"127.0.0.1:60106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":16,"response size":31,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-03-29T01:57:08.411902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.007761ms","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-03-29T01:57:08.412019Z","caller":"traceutil/trace.go:171","msg":"trace[556835589] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1351; }","duration":"426.168305ms","start":"2026-03-29T01:57:07.985834Z","end":"2026-03-29T01:57:08.412002Z","steps":["trace[556835589] 'agreement among raft nodes before linearized reading' (duration: 425.828877ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:08.412133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:07.985811Z","time spent":"426.303547ms","remote":"127.0.0.1:60046","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-03-29T01:57:08.412295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.053397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.65\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-29T01:57:08.412347Z","caller":"traceutil/trace.go:171","msg":"trace[760355267] range","detail":"{range_begin:/registry/masterleases/199.204.45.65; range_end:; response_count:1; response_revision:1351; }","duration":"363.105488ms","start":"2026-03-29T01:57:08.049229Z","end":"2026-03-29T01:57:08.412335Z","steps":["trace[760355267] 'agreement among raft nodes before linearized reading' (duration: 362.404872ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:08.412381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:08.049137Z","time spent":"363.236841ms","remote":"127.0.0.1:59806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.65\" "} {"level":"warn","ts":"2026-03-29T01:57:08.412348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.133487ms","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-03-29T01:57:08.412872Z","caller":"traceutil/trace.go:171","msg":"trace[163252731] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1351; }","duration":"618.667808ms","start":"2026-03-29T01:57:07.794162Z","end":"2026-03-29T01:57:08.412829Z","steps":["trace[163252731] 'agreement among raft nodes before linearized reading' (duration: 616.952571ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:08.413577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:07.794138Z","time spent":"618.772841ms","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-29T01:57:08.744277Z","caller":"traceutil/trace.go:171","msg":"trace[744904596] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"327.304448ms","start":"2026-03-29T01:57:08.41695Z","end":"2026-03-29T01:57:08.744255Z","steps":["trace[744904596] 'process raft request' (duration: 327.117594ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:08.744415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:08.416929Z","time spent":"327.41794ms","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:57:08.744697Z","caller":"traceutil/trace.go:171","msg":"trace[564961788] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"327.635305ms","start":"2026-03-29T01:57:08.417031Z","end":"2026-03-29T01:57:08.744666Z","steps":["trace[564961788] 'process raft request' (duration: 327.186986ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:08.74544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:08.417023Z","time spent":"328.31677ms","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:57:08.744757Z","caller":"traceutil/trace.go:171","msg":"trace[1353737272] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"326.196143ms","start":"2026-03-29T01:57:08.418538Z","end":"2026-03-29T01:57:08.744734Z","steps":["trace[1353737272] 'process raft request' (duration: 325.975489ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:08.745957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:08.41853Z","time spent":"327.267367ms","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:57:08.744776Z","caller":"traceutil/trace.go:171","msg":"trace[272509331] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"325.797905ms","start":"2026-03-29T01:57:08.418966Z","end":"2026-03-29T01:57:08.744764Z","steps":["trace[272509331] 'process raft request' (duration: 325.674172ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:08.746123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:08.418959Z","time spent":"327.115944ms","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:57:08.744978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.130264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:57:08.746242Z","caller":"traceutil/trace.go:171","msg":"trace[2099951683] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1355; }","duration":"228.411942ms","start":"2026-03-29T01:57:08.517817Z","end":"2026-03-29T01:57:08.746229Z","steps":["trace[2099951683] 'agreement among raft nodes before linearized reading' (duration: 227.133244ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:08.7447Z","caller":"traceutil/trace.go:171","msg":"trace[97991288] linearizableReadLoop","detail":"{readStateIndex:1411; appliedIndex:1406; }","duration":"226.837117ms","start":"2026-03-29T01:57:08.517845Z","end":"2026-03-29T01:57:08.744682Z","steps":["trace[97991288] 'read index received' (duration: 123.126888ms)","trace[97991288] 'applied index is now lower than readState.Index' (duration: 103.709349ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:57:09.080669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.817933ms","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-03-29T01:57:09.080812Z","caller":"traceutil/trace.go:171","msg":"trace[2007762092] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1355; }","duration":"256.978756ms","start":"2026-03-29T01:57:08.823812Z","end":"2026-03-29T01:57:09.080791Z","steps":["trace[2007762092] 'range keys from in-memory index tree' (duration: 256.802363ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:09.080735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.576154ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:57:09.081112Z","caller":"traceutil/trace.go:171","msg":"trace[408195192] linearizableReadLoop","detail":"{readStateIndex:1412; appliedIndex:1411; }","duration":"333.82847ms","start":"2026-03-29T01:57:08.74727Z","end":"2026-03-29T01:57:09.081098Z","steps":["trace[408195192] 'read index received' (duration: 112.798497ms)","trace[408195192] 'applied index is now lower than readState.Index' (duration: 221.028223ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:57:09.081214Z","caller":"traceutil/trace.go:171","msg":"trace[801658629] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"410.166761ms","start":"2026-03-29T01:57:08.671033Z","end":"2026-03-29T01:57:09.0812Z","steps":["trace[801658629] 'process raft request' (duration: 189.059446ms)","trace[801658629] 'compare' (duration: 220.437621ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:57:09.081328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:08.671002Z","time spent":"410.251753ms","remote":"127.0.0.1:59806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:57:09.081351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.077736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:57:09.081386Z","caller":"traceutil/trace.go:171","msg":"trace[1709734020] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1356; }","duration":"334.149048ms","start":"2026-03-29T01:57:08.747223Z","end":"2026-03-29T01:57:09.081372Z","steps":["trace[1709734020] 'agreement among raft nodes before linearized reading' (duration: 334.028814ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:09.081415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:08.747203Z","time spent":"334.206649ms","remote":"127.0.0.1:59786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-29T01:57:09.081544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.948085ms","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-03-29T01:57:09.081619Z","caller":"traceutil/trace.go:171","msg":"trace[1970797456] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1356; }","duration":"275.072188ms","start":"2026-03-29T01:57:08.806529Z","end":"2026-03-29T01:57:09.081601Z","steps":["trace[1970797456] 'agreement among raft nodes before linearized reading' (duration: 274.726239ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:09.585677Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620625497567833054,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T01:57:10.086054Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620625497567833054,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T01:57:10.169569Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.08455137s","expected-duration":"1s"} {"level":"info","ts":"2026-03-29T01:57:10.170082Z","caller":"traceutil/trace.go:171","msg":"trace[1285998753] linearizableReadLoop","detail":"{readStateIndex:1413; appliedIndex:1412; }","duration":"1.084474929s","start":"2026-03-29T01:57:09.085555Z","end":"2026-03-29T01:57:10.17003Z","steps":["trace[1285998753] 'read index received' (duration: 1.084222464s)","trace[1285998753] 'applied index is now lower than readState.Index' (duration: 250.975µs)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:57:10.170223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.084647133s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-03-29T01:57:10.170252Z","caller":"traceutil/trace.go:171","msg":"trace[981458173] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1357; }","duration":"1.084730555s","start":"2026-03-29T01:57:09.085515Z","end":"2026-03-29T01:57:10.170245Z","steps":["trace[981458173] 'agreement among raft nodes before linearized reading' (duration: 1.084632712s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:10.170281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:09.085494Z","time spent":"1.084781045s","remote":"127.0.0.1:59954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-03-29T01:57:10.170379Z","caller":"traceutil/trace.go:171","msg":"trace[515050792] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"1.085342587s","start":"2026-03-29T01:57:09.084954Z","end":"2026-03-29T01:57:10.170296Z","steps":["trace[515050792] 'process raft request' (duration: 1.084931438s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:10.170593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:09.084941Z","time spent":"1.085528521s","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:57:11.351029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.58354ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:57:11.351147Z","caller":"traceutil/trace.go:171","msg":"trace[2039188393] linearizableReadLoop","detail":"{readStateIndex:1414; appliedIndex:1413; }","duration":"833.940619ms","start":"2026-03-29T01:57:10.517183Z","end":"2026-03-29T01:57:11.351124Z","steps":["trace[2039188393] 'read index received' (duration: 285.094834ms)","trace[2039188393] 'applied index is now lower than readState.Index' (duration: 548.843865ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:57:11.351727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.550981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:57:11.351767Z","caller":"traceutil/trace.go:171","msg":"trace[1745016770] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1358; }","duration":"834.643563ms","start":"2026-03-29T01:57:10.517112Z","end":"2026-03-29T01:57:11.351755Z","steps":["trace[1745016770] 'agreement among raft nodes before linearized reading' (duration: 834.571432ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:11.351801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:10.517082Z","time spent":"834.711775ms","remote":"127.0.0.1:59782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-29T01:57:11.35179Z","caller":"traceutil/trace.go:171","msg":"trace[1969901868] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"968.723683ms","start":"2026-03-29T01:57:10.383014Z","end":"2026-03-29T01:57:11.351738Z","steps":["trace[1969901868] 'process raft request' (duration: 419.341427ms)","trace[1969901868] 'compare' (duration: 548.222711ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:57:11.351964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:10.382981Z","time spent":"968.888777ms","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:57:11.352057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.779875ms","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-03-29T01:57:11.352104Z","caller":"traceutil/trace.go:171","msg":"trace[428678498] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1358; }","duration":"601.870207ms","start":"2026-03-29T01:57:10.750223Z","end":"2026-03-29T01:57:11.352093Z","steps":["trace[428678498] 'agreement among raft nodes before linearized reading' (duration: 601.739974ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:11.352089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.047339ms","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-03-29T01:57:11.352141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:10.7502Z","time spent":"601.932619ms","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-29T01:57:11.352139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.621302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-29T01:57:11.352139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.825416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-29T01:57:11.352147Z","caller":"traceutil/trace.go:171","msg":"trace[1597836612] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1358; }","duration":"601.134261ms","start":"2026-03-29T01:57:10.751003Z","end":"2026-03-29T01:57:11.352137Z","steps":["trace[1597836612] 'agreement among raft nodes before linearized reading' (duration: 600.914797ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:11.352269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:10.750987Z","time spent":"601.270804ms","remote":"127.0.0.1:60046","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-03-29T01:57:11.352273Z","caller":"traceutil/trace.go:171","msg":"trace[644464110] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1358; }","duration":"601.730735ms","start":"2026-03-29T01:57:10.75047Z","end":"2026-03-29T01:57:11.352201Z","steps":["trace[644464110] 'agreement among raft nodes before linearized reading' (duration: 601.437588ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:11.352247Z","caller":"traceutil/trace.go:171","msg":"trace[1109831244] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1358; }","duration":"601.97227ms","start":"2026-03-29T01:57:10.750237Z","end":"2026-03-29T01:57:11.352209Z","steps":["trace[1109831244] 'agreement among raft nodes before linearized reading' (duration: 601.668593ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:11.352325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:10.750225Z","time spent":"602.088052ms","remote":"127.0.0.1:60046","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-03-29T01:57:11.352383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:10.750371Z","time spent":"601.99536ms","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-29T01:57:12.014423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.913189ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:57:12.014655Z","caller":"traceutil/trace.go:171","msg":"trace[691359441] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"656.954653ms","start":"2026-03-29T01:57:11.357677Z","end":"2026-03-29T01:57:12.014631Z","steps":["trace[691359441] 'process raft request' (duration: 102.728926ms)","trace[691359441] 'compare' (duration: 552.894917ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:57:12.014776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:11.357655Z","time spent":"657.058975ms","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:57:12.014814Z","caller":"traceutil/trace.go:171","msg":"trace[1749520776] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"656.920802ms","start":"2026-03-29T01:57:11.357873Z","end":"2026-03-29T01:57:12.014794Z","steps":["trace[1749520776] 'process raft request' (duration: 656.666947ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:12.014919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:11.357848Z","time spent":"657.022485ms","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:57:12.014941Z","caller":"traceutil/trace.go:171","msg":"trace[802876652] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"656.919523ms","start":"2026-03-29T01:57:11.357995Z","end":"2026-03-29T01:57:12.014914Z","steps":["trace[802876652] 'process raft request' (duration: 656.654147ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:12.014991Z","caller":"traceutil/trace.go:171","msg":"trace[332917957] linearizableReadLoop","detail":"{readStateIndex:1418; appliedIndex:1414; }","duration":"497.664057ms","start":"2026-03-29T01:57:11.517307Z","end":"2026-03-29T01:57:12.014971Z","steps":["trace[332917957] 'read index received' (duration: 315.6495ms)","trace[332917957] 'applied index is now lower than readState.Index' (duration: 182.012897ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:57:12.015052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:11.357989Z","time spent":"657.007504ms","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:57:12.015091Z","caller":"traceutil/trace.go:171","msg":"trace[1795596561] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"656.970343ms","start":"2026-03-29T01:57:11.358099Z","end":"2026-03-29T01:57:12.01507Z","steps":["trace[1795596561] 'process raft request' (duration: 656.681017ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:12.015139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.82128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:57:12.015181Z","caller":"traceutil/trace.go:171","msg":"trace[722098095] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1362; }","duration":"497.902392ms","start":"2026-03-29T01:57:11.517266Z","end":"2026-03-29T01:57:12.015168Z","steps":["trace[722098095] 'agreement among raft nodes before linearized reading' (duration: 497.81969ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:12.015213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:11.358092Z","time spent":"657.065475ms","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:57:12.015233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:57:11.517245Z","time spent":"497.977053ms","remote":"127.0.0.1:59782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-29T01:57:12.015233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.764847ms","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-03-29T01:57:12.015275Z","caller":"traceutil/trace.go:171","msg":"trace[1127495393] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1362; }","duration":"159.809798ms","start":"2026-03-29T01:57:11.855454Z","end":"2026-03-29T01:57:12.015264Z","steps":["trace[1127495393] 'agreement among raft nodes before linearized reading' (duration: 159.715146ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:12.306637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.124363ms","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-03-29T01:57:12.306713Z","caller":"traceutil/trace.go:171","msg":"trace[97004454] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1362; }","duration":"132.244885ms","start":"2026-03-29T01:57:12.17445Z","end":"2026-03-29T01:57:12.306695Z","steps":["trace[97004454] 'range keys from in-memory index tree' (duration: 131.960779ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:12.425287Z","caller":"traceutil/trace.go:171","msg":"trace[1415683611] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"115.136387ms","start":"2026-03-29T01:57:12.310107Z","end":"2026-03-29T01:57:12.425243Z","steps":["trace[1415683611] 'process raft request' (duration: 114.866271ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:18.188635Z","caller":"traceutil/trace.go:171","msg":"trace[2147470193] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"106.992535ms","start":"2026-03-29T01:57:18.081608Z","end":"2026-03-29T01:57:18.1886Z","steps":["trace[2147470193] 'process raft request' (duration: 81.90262ms)","trace[2147470193] 'compare' (duration: 24.944281ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:57:18.706447Z","caller":"traceutil/trace.go:171","msg":"trace[492519456] linearizableReadLoop","detail":"{readStateIndex:1445; appliedIndex:1444; }","duration":"192.518712ms","start":"2026-03-29T01:57:18.513906Z","end":"2026-03-29T01:57:18.706425Z","steps":["trace[492519456] 'read index received' (duration: 192.353258ms)","trace[492519456] 'applied index is now lower than readState.Index' (duration: 164.604µs)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:57:18.706485Z","caller":"traceutil/trace.go:171","msg":"trace[653140512] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"200.096386ms","start":"2026-03-29T01:57:18.506368Z","end":"2026-03-29T01:57:18.706465Z","steps":["trace[653140512] 'process raft request' (duration: 199.931513ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:18.706587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.655284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:57:18.706624Z","caller":"traceutil/trace.go:171","msg":"trace[72017371] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1387; }","duration":"192.750907ms","start":"2026-03-29T01:57:18.513865Z","end":"2026-03-29T01:57:18.706616Z","steps":["trace[72017371] 'agreement among raft nodes before linearized reading' (duration: 192.666385ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:18.706702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.797355ms","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-03-29T01:57:18.706753Z","caller":"traceutil/trace.go:171","msg":"trace[1170568316] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1387; }","duration":"179.848987ms","start":"2026-03-29T01:57:18.526892Z","end":"2026-03-29T01:57:18.706741Z","steps":["trace[1170568316] 'agreement among raft nodes before linearized reading' (duration: 179.717605ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:18.70685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.03879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:endpoint-controller\" ","response":"range_response_count:1 size:754"} {"level":"info","ts":"2026-03-29T01:57:18.706884Z","caller":"traceutil/trace.go:171","msg":"trace[137305844] range","detail":"{range_begin:/registry/clusterroles/system:controller:endpoint-controller; range_end:; response_count:1; response_revision:1387; }","duration":"156.092951ms","start":"2026-03-29T01:57:18.550781Z","end":"2026-03-29T01:57:18.706874Z","steps":["trace[137305844] 'agreement among raft nodes before linearized reading' (duration: 156.04126ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:18.706976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.93761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:disruption-controller\" ","response":"range_response_count:1 size:972"} {"level":"info","ts":"2026-03-29T01:57:18.707018Z","caller":"traceutil/trace.go:171","msg":"trace[516099762] range","detail":"{range_begin:/registry/clusterroles/system:controller:disruption-controller; range_end:; response_count:1; response_revision:1387; }","duration":"162.998292ms","start":"2026-03-29T01:57:18.544009Z","end":"2026-03-29T01:57:18.707007Z","steps":["trace[516099762] 'agreement among raft nodes before linearized reading' (duration: 162.90359ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:20.389628Z","caller":"traceutil/trace.go:171","msg":"trace[824414121] transaction","detail":"{read_only:false; response_revision:1392; number_of_response:1; }","duration":"150.995597ms","start":"2026-03-29T01:57:20.238608Z","end":"2026-03-29T01:57:20.389604Z","steps":["trace[824414121] 'process raft request' (duration: 150.398434ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:20.391827Z","caller":"traceutil/trace.go:171","msg":"trace[661537847] transaction","detail":"{read_only:false; response_revision:1393; number_of_response:1; }","duration":"149.164127ms","start":"2026-03-29T01:57:20.242641Z","end":"2026-03-29T01:57:20.391805Z","steps":["trace[661537847] 'process raft request' (duration: 148.980053ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:22.528986Z","caller":"traceutil/trace.go:171","msg":"trace[1562895905] transaction","detail":"{read_only:false; response_revision:1398; number_of_response:1; }","duration":"130.460295ms","start":"2026-03-29T01:57:22.398505Z","end":"2026-03-29T01:57:22.528965Z","steps":["trace[1562895905] 'process raft request' (duration: 130.334502ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:22.531807Z","caller":"traceutil/trace.go:171","msg":"trace[1353790270] linearizableReadLoop","detail":"{readStateIndex:1457; appliedIndex:1455; }","duration":"131.265352ms","start":"2026-03-29T01:57:22.400517Z","end":"2026-03-29T01:57:22.531783Z","steps":["trace[1353790270] 'read index received' (duration: 128.322218ms)","trace[1353790270] 'applied index is now lower than readState.Index' (duration: 2.942064ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:57:22.531919Z","caller":"traceutil/trace.go:171","msg":"trace[28863239] transaction","detail":"{read_only:false; response_revision:1399; number_of_response:1; }","duration":"132.800025ms","start":"2026-03-29T01:57:22.399108Z","end":"2026-03-29T01:57:22.531908Z","steps":["trace[28863239] 'process raft request' (duration: 132.53803ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:22.532049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.223997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:disruption-controller\" ","response":"range_response_count:1 size:759"} {"level":"warn","ts":"2026-03-29T01:57:22.532138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.60404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:ttl-controller\" ","response":"range_response_count:1 size:663"} {"level":"info","ts":"2026-03-29T01:57:22.532188Z","caller":"traceutil/trace.go:171","msg":"trace[1881434670] range","detail":"{range_begin:/registry/clusterroles/system:controller:ttl-controller; range_end:; response_count:1; response_revision:1399; }","duration":"131.661111ms","start":"2026-03-29T01:57:22.400513Z","end":"2026-03-29T01:57:22.532174Z","steps":["trace[1881434670] 'agreement among raft nodes before linearized reading' (duration: 131.548168ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:22.532161Z","caller":"traceutil/trace.go:171","msg":"trace[1121460563] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:disruption-controller; range_end:; response_count:1; response_revision:1399; }","duration":"117.374789ms","start":"2026-03-29T01:57:22.414761Z","end":"2026-03-29T01:57:22.532136Z","steps":["trace[1121460563] 'agreement among raft nodes before linearized reading' (duration: 117.189615ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:22.972233Z","caller":"traceutil/trace.go:171","msg":"trace[1408337601] linearizableReadLoop","detail":"{readStateIndex:1458; appliedIndex:1457; }","duration":"152.488935ms","start":"2026-03-29T01:57:22.819721Z","end":"2026-03-29T01:57:22.972209Z","steps":["trace[1408337601] 'read index received' (duration: 152.359923ms)","trace[1408337601] 'applied index is now lower than readState.Index' (duration: 127.812µs)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:57:22.972347Z","caller":"traceutil/trace.go:171","msg":"trace[970229217] transaction","detail":"{read_only:false; response_revision:1400; number_of_response:1; }","duration":"162.31751ms","start":"2026-03-29T01:57:22.809959Z","end":"2026-03-29T01:57:22.972277Z","steps":["trace[970229217] 'process raft request' (duration: 162.065334ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:22.972462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.181415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:coredns\" ","response":"range_response_count:1 size:388"} {"level":"warn","ts":"2026-03-29T01:57:22.972502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.768356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:endpointslicemirroring-controller\" ","response":"range_response_count:1 size:807"} {"level":"info","ts":"2026-03-29T01:57:22.972595Z","caller":"traceutil/trace.go:171","msg":"trace[1512955112] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:endpointslicemirroring-controller; range_end:; response_count:1; response_revision:1400; }","duration":"138.904488ms","start":"2026-03-29T01:57:22.833672Z","end":"2026-03-29T01:57:22.972576Z","steps":["trace[1512955112] 'agreement among raft nodes before linearized reading' (duration: 138.701944ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:22.972533Z","caller":"traceutil/trace.go:171","msg":"trace[197700044] range","detail":"{range_begin:/registry/clusterroles/system:coredns; range_end:; response_count:1; response_revision:1400; }","duration":"117.295288ms","start":"2026-03-29T01:57:22.855226Z","end":"2026-03-29T01:57:22.972521Z","steps":["trace[197700044] 'agreement among raft nodes before linearized reading' (duration: 117.141665ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:22.973168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.432866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-29T01:57:22.973226Z","caller":"traceutil/trace.go:171","msg":"trace[2122336800] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1400; }","duration":"153.496477ms","start":"2026-03-29T01:57:22.819715Z","end":"2026-03-29T01:57:22.973212Z","steps":["trace[2122336800] 'agreement among raft nodes before linearized reading' (duration: 153.325453ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:23.211868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.809111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:discovery\" ","response":"range_response_count:1 size:647"} {"level":"info","ts":"2026-03-29T01:57:23.211959Z","caller":"traceutil/trace.go:171","msg":"trace[847579252] range","detail":"{range_begin:/registry/clusterroles/system:discovery; range_end:; response_count:1; response_revision:1400; }","duration":"138.952534ms","start":"2026-03-29T01:57:23.072984Z","end":"2026-03-29T01:57:23.211937Z","steps":["trace[847579252] 'range keys from in-memory index tree' (duration: 138.709879ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:24.643131Z","caller":"traceutil/trace.go:171","msg":"trace[1435654178] linearizableReadLoop","detail":"{readStateIndex:1463; appliedIndex:1462; }","duration":"106.61622ms","start":"2026-03-29T01:57:24.536488Z","end":"2026-03-29T01:57:24.643105Z","steps":["trace[1435654178] 'read index received' (duration: 106.361013ms)","trace[1435654178] 'applied index is now lower than readState.Index' (duration: 254.277µs)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:57:24.643249Z","caller":"traceutil/trace.go:171","msg":"trace[543746436] transaction","detail":"{read_only:false; response_revision:1404; number_of_response:1; }","duration":"108.722306ms","start":"2026-03-29T01:57:24.534488Z","end":"2026-03-29T01:57:24.64321Z","steps":["trace[543746436] 'process raft request' (duration: 108.468471ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:24.643378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.868145ms","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-03-29T01:57:24.643462Z","caller":"traceutil/trace.go:171","msg":"trace[1382947450] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1404; }","duration":"107.003517ms","start":"2026-03-29T01:57:24.536446Z","end":"2026-03-29T01:57:24.64345Z","steps":["trace[1382947450] 'agreement among raft nodes before linearized reading' (duration: 106.800943ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:28.20203Z","caller":"traceutil/trace.go:171","msg":"trace[54679771] transaction","detail":"{read_only:false; response_revision:1415; number_of_response:1; }","duration":"117.629329ms","start":"2026-03-29T01:57:28.084365Z","end":"2026-03-29T01:57:28.201994Z","steps":["trace[54679771] 'process raft request' (duration: 93.047ms)","trace[54679771] 'compare' (duration: 24.327504ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:57:29.275188Z","caller":"traceutil/trace.go:171","msg":"trace[948700853] transaction","detail":"{read_only:false; response_revision:1421; number_of_response:1; }","duration":"166.182857ms","start":"2026-03-29T01:57:29.108985Z","end":"2026-03-29T01:57:29.275168Z","steps":["trace[948700853] 'process raft request' (duration: 165.767428ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:30.665144Z","caller":"traceutil/trace.go:171","msg":"trace[746003108] linearizableReadLoop","detail":"{readStateIndex:1484; appliedIndex:1483; }","duration":"149.410942ms","start":"2026-03-29T01:57:30.515711Z","end":"2026-03-29T01:57:30.665122Z","steps":["trace[746003108] 'read index received' (duration: 131.148361ms)","trace[746003108] 'applied index is now lower than readState.Index' (duration: 18.261641ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:57:30.665284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.555775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:57:30.665322Z","caller":"traceutil/trace.go:171","msg":"trace[529088281] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1424; }","duration":"149.642287ms","start":"2026-03-29T01:57:30.515669Z","end":"2026-03-29T01:57:30.665312Z","steps":["trace[529088281] 'agreement among raft nodes before linearized reading' (duration: 149.569426ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:30.665382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.539583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:1 size:912"} {"level":"warn","ts":"2026-03-29T01:57:30.665383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.250444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-leader-election-rolebinding\" ","response":"range_response_count:1 size:1146"} {"level":"info","ts":"2026-03-29T01:57:30.665438Z","caller":"traceutil/trace.go:171","msg":"trace[396058937] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:1; response_revision:1424; }","duration":"123.616125ms","start":"2026-03-29T01:57:30.541806Z","end":"2026-03-29T01:57:30.665423Z","steps":["trace[396058937] 'agreement among raft nodes before linearized reading' (duration: 123.493883ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:30.665443Z","caller":"traceutil/trace.go:171","msg":"trace[695371456] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-leader-election-rolebinding; range_end:; response_count:1; response_revision:1424; }","duration":"147.328426ms","start":"2026-03-29T01:57:30.518101Z","end":"2026-03-29T01:57:30.665429Z","steps":["trace[695371456] 'agreement among raft nodes before linearized reading' (duration: 147.167572ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:30.665538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.323972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/\" range_end:\"/registry/persistentvolumeclaims/default0\" limit:500 ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-03-29T01:57:30.665626Z","caller":"traceutil/trace.go:171","msg":"trace[1367442261] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/; range_end:/registry/persistentvolumeclaims/default0; response_count:1; response_revision:1424; }","duration":"125.479096ms","start":"2026-03-29T01:57:30.540129Z","end":"2026-03-29T01:57:30.665608Z","steps":["trace[1367442261] 'agreement among raft nodes before linearized reading' (duration: 125.197229ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:30.665242Z","caller":"traceutil/trace.go:171","msg":"trace[1753453173] transaction","detail":"{read_only:false; response_revision:1424; number_of_response:1; }","duration":"181.180449ms","start":"2026-03-29T01:57:30.483999Z","end":"2026-03-29T01:57:30.66518Z","steps":["trace[1753453173] 'process raft request' (duration: 162.837327ms)","trace[1753453173] 'compare' (duration: 18.191509ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:57:31.455933Z","caller":"traceutil/trace.go:171","msg":"trace[1974749063] transaction","detail":"{read_only:false; response_revision:1427; number_of_response:1; }","duration":"175.761425ms","start":"2026-03-29T01:57:31.280153Z","end":"2026-03-29T01:57:31.455915Z","steps":["trace[1974749063] 'process raft request' (duration: 175.628021ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:31.45608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.507268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/kube-node-lease/\" range_end:\"/registry/cronjobs/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:57:31.456121Z","caller":"traceutil/trace.go:171","msg":"trace[1502974725] range","detail":"{range_begin:/registry/cronjobs/kube-node-lease/; range_end:/registry/cronjobs/kube-node-lease0; response_count:0; response_revision:1427; }","duration":"134.589959ms","start":"2026-03-29T01:57:31.321521Z","end":"2026-03-29T01:57:31.456111Z","steps":["trace[1502974725] 'agreement among raft nodes before linearized reading' (duration: 134.488437ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:31.455947Z","caller":"traceutil/trace.go:171","msg":"trace[1996389397] linearizableReadLoop","detail":"{readStateIndex:1487; appliedIndex:1486; }","duration":"134.349525ms","start":"2026-03-29T01:57:31.321551Z","end":"2026-03-29T01:57:31.4559Z","steps":["trace[1996389397] 'read index received' (duration: 134.19179ms)","trace[1996389397] 'applied index is now lower than readState.Index' (duration: 156.985µs)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:57:31.456484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.923896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-node-lease/\" range_end:\"/registry/daemonsets/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:57:31.456526Z","caller":"traceutil/trace.go:171","msg":"trace[2107228889] range","detail":"{range_begin:/registry/daemonsets/kube-node-lease/; range_end:/registry/daemonsets/kube-node-lease0; response_count:0; response_revision:1427; }","duration":"119.067749ms","start":"2026-03-29T01:57:31.337446Z","end":"2026-03-29T01:57:31.456514Z","steps":["trace[2107228889] 'agreement among raft nodes before linearized reading' (duration: 118.942676ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:35.04536Z","caller":"traceutil/trace.go:171","msg":"trace[292854034] transaction","detail":"{read_only:false; response_revision:1438; number_of_response:1; }","duration":"105.761753ms","start":"2026-03-29T01:57:34.939574Z","end":"2026-03-29T01:57:35.045336Z","steps":["trace[292854034] 'process raft request' (duration: 105.602239ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:35.270827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.083788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6751"} {"level":"info","ts":"2026-03-29T01:57:35.27104Z","caller":"traceutil/trace.go:171","msg":"trace[2013381067] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:1438; }","duration":"129.328334ms","start":"2026-03-29T01:57:35.141686Z","end":"2026-03-29T01:57:35.271014Z","steps":["trace[2013381067] 'range keys from in-memory index tree' (duration: 128.996786ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:35.7095Z","caller":"traceutil/trace.go:171","msg":"trace[704448207] linearizableReadLoop","detail":"{readStateIndex:1500; appliedIndex:1499; }","duration":"207.304954ms","start":"2026-03-29T01:57:35.502172Z","end":"2026-03-29T01:57:35.709477Z","steps":["trace[704448207] 'read index received' (duration: 207.023298ms)","trace[704448207] 'applied index is now lower than readState.Index' (duration: 280.406µs)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:57:35.709615Z","caller":"traceutil/trace.go:171","msg":"trace[1492822800] transaction","detail":"{read_only:false; response_revision:1439; number_of_response:1; }","duration":"209.630676ms","start":"2026-03-29T01:57:35.499958Z","end":"2026-03-29T01:57:35.709588Z","steps":["trace[1492822800] 'process raft request' (duration: 209.36221ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:35.709663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.468978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4130"} {"level":"info","ts":"2026-03-29T01:57:35.709698Z","caller":"traceutil/trace.go:171","msg":"trace[1726308525] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:1439; }","duration":"207.538099ms","start":"2026-03-29T01:57:35.50215Z","end":"2026-03-29T01:57:35.709688Z","steps":["trace[1726308525] 'agreement among raft nodes before linearized reading' (duration: 207.451807ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:57:35.709876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.438252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4660"} {"level":"info","ts":"2026-03-29T01:57:35.709943Z","caller":"traceutil/trace.go:171","msg":"trace[1122833531] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:1439; }","duration":"195.520694ms","start":"2026-03-29T01:57:35.514406Z","end":"2026-03-29T01:57:35.709927Z","steps":["trace[1122833531] 'agreement among raft nodes before linearized reading' (duration: 195.377591ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:43.371973Z","caller":"traceutil/trace.go:171","msg":"trace[1188014858] transaction","detail":"{read_only:false; response_revision:1466; number_of_response:1; }","duration":"112.0655ms","start":"2026-03-29T01:57:43.259873Z","end":"2026-03-29T01:57:43.371939Z","steps":["trace[1188014858] 'process raft request' (duration: 111.59803ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:47.242299Z","caller":"traceutil/trace.go:171","msg":"trace[382994163] linearizableReadLoop","detail":"{readStateIndex:1541; appliedIndex:1540; }","duration":"101.324117ms","start":"2026-03-29T01:57:47.140949Z","end":"2026-03-29T01:57:47.242273Z","steps":["trace[382994163] 'read index received' (duration: 95.474636ms)","trace[382994163] 'applied index is now lower than readState.Index' (duration: 5.848481ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:57:47.242524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.554762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2kvtv\" ","response":"range_response_count:1 size:18521"} {"level":"info","ts":"2026-03-29T01:57:47.242485Z","caller":"traceutil/trace.go:171","msg":"trace[776260334] transaction","detail":"{read_only:false; response_revision:1478; number_of_response:1; }","duration":"104.252062ms","start":"2026-03-29T01:57:47.13819Z","end":"2026-03-29T01:57:47.242442Z","steps":["trace[776260334] 'process raft request' (duration: 98.203698ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:57:47.242567Z","caller":"traceutil/trace.go:171","msg":"trace[1515155331] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2kvtv; range_end:; response_count:1; response_revision:1478; }","duration":"101.610603ms","start":"2026-03-29T01:57:47.140943Z","end":"2026-03-29T01:57:47.242554Z","steps":["trace[1515155331] 'agreement among raft nodes before linearized reading' (duration: 101.424149ms)"],"step_count":1}