{"level":"warn","ts":"2026-03-26T09:38:23.198924Z","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-26T09:38:23.19904Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.162: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.162:2380","--initial-cluster=instance=https://199.204.45.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.162: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-26T09:38:23.199129Z","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-26T09:38:23.199142Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-03-26T09:38:23.199166Z","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-26T09:38:23.200748Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"]} {"level":"info","ts":"2026-03-26T09:38:23.200898Z","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.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.162: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-26T09:38:23.210601Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.177882ms"} {"level":"info","ts":"2026-03-26T09:38:23.221796Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2d372260bb4c37a","cluster-id":"52bb6ec15e8722ff"} {"level":"info","ts":"2026-03-26T09:38:23.221916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=()"} {"level":"info","ts":"2026-03-26T09:38:23.221945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 0"} {"level":"info","ts":"2026-03-26T09:38:23.221971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2d372260bb4c37a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-26T09:38:23.22198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 1"} {"level":"info","ts":"2026-03-26T09:38:23.222032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"warn","ts":"2026-03-26T09:38:23.229502Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-26T09:38:23.230833Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-26T09:38:23.232909Z","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-26T09:38:23.235418Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2d372260bb4c37a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-26T09:38:23.236203Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2d372260bb4c37a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-26T09:38:23.236497Z","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-26T09:38:23.237862Z","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-26T09:38:23.237894Z","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-26T09:38:23.238214Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"info","ts":"2026-03-26T09:38:23.238392Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","added-peer-id":"2d372260bb4c37a","added-peer-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-03-26T09:38:23.239883Z","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-26T09:38:23.240043Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-03-26T09:38:23.240089Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-03-26T09:38:23.24016Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2d372260bb4c37a","initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-26T09:38:23.240215Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-26T09:38:24.222718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a is starting a new election at term 1"} {"level":"info","ts":"2026-03-26T09:38:24.222851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became pre-candidate at term 1"} {"level":"info","ts":"2026-03-26T09:38:24.222905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgPreVoteResp from 2d372260bb4c37a at term 1"} {"level":"info","ts":"2026-03-26T09:38:24.22293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became candidate at term 2"} {"level":"info","ts":"2026-03-26T09:38:24.222944Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgVoteResp from 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-03-26T09:38:24.222965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became leader at term 2"} {"level":"info","ts":"2026-03-26T09:38:24.222981Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2d372260bb4c37a elected leader 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-03-26T09:38:24.235785Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2d372260bb4c37a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.162:2379]}","request-path":"/0/members/2d372260bb4c37a/attributes","cluster-id":"52bb6ec15e8722ff","publish-timeout":"7s"} {"level":"info","ts":"2026-03-26T09:38:24.2358Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-26T09:38:24.235862Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-26T09:38:24.235801Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-26T09:38:24.236182Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-26T09:38:24.236229Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-26T09:38:24.237354Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","cluster-version":"3.5"} {"level":"info","ts":"2026-03-26T09:38:24.237537Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-26T09:38:24.237603Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-26T09:38:24.239939Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-26T09:38:24.246522Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.162:2379"} {"level":"info","ts":"2026-03-26T09:38:48.647521Z","caller":"traceutil/trace.go:171","msg":"trace[1792851910] linearizableReadLoop","detail":"{readStateIndex:438; appliedIndex:437; }","duration":"370.973824ms","start":"2026-03-26T09:38:48.276495Z","end":"2026-03-26T09:38:48.647469Z","steps":["trace[1792851910] 'read index received' (duration: 370.669736ms)","trace[1792851910] 'applied index is now lower than readState.Index' (duration: 302.608µs)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:38:48.64773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.180938ms","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-26T09:38:48.648137Z","caller":"traceutil/trace.go:171","msg":"trace[2067875249] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"389.142385ms","start":"2026-03-26T09:38:48.258451Z","end":"2026-03-26T09:38:48.647594Z","steps":["trace[2067875249] 'process raft request' (duration: 388.820457ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:38:48.64897Z","caller":"traceutil/trace.go:171","msg":"trace[1285769433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:422; }","duration":"371.326921ms","start":"2026-03-26T09:38:48.276447Z","end":"2026-03-26T09:38:48.647774Z","steps":["trace[1285769433] 'agreement among raft nodes before linearized reading' (duration: 371.189058ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:38:48.649105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:38:48.276424Z","time spent":"372.630981ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-26T09:38:48.649792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:38:48.258428Z","time spent":"390.388032ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:38:48.938697Z","caller":"traceutil/trace.go:171","msg":"trace[1869513322] linearizableReadLoop","detail":"{readStateIndex:439; appliedIndex:438; }","duration":"164.465184ms","start":"2026-03-26T09:38:48.774207Z","end":"2026-03-26T09:38:48.938672Z","steps":["trace[1869513322] 'read index received' (duration: 164.182547ms)","trace[1869513322] 'applied index is now lower than readState.Index' (duration: 281.557µs)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:38:48.938798Z","caller":"traceutil/trace.go:171","msg":"trace[1491869192] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"282.905283ms","start":"2026-03-26T09:38:48.655841Z","end":"2026-03-26T09:38:48.938746Z","steps":["trace[1491869192] 'process raft request' (duration: 282.608276ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:38:48.938852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.624887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-26T09:38:48.93891Z","caller":"traceutil/trace.go:171","msg":"trace[1445063411] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:423; }","duration":"164.74223ms","start":"2026-03-26T09:38:48.774152Z","end":"2026-03-26T09:38:48.938894Z","steps":["trace[1445063411] 'agreement among raft nodes before linearized reading' (duration: 164.626738ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:38:55.114695Z","caller":"traceutil/trace.go:171","msg":"trace[2129509117] linearizableReadLoop","detail":"{readStateIndex:453; appliedIndex:452; }","duration":"147.48929ms","start":"2026-03-26T09:38:54.967174Z","end":"2026-03-26T09:38:55.114663Z","steps":["trace[2129509117] 'read index received' (duration: 147.252684ms)","trace[2129509117] 'applied index is now lower than readState.Index' (duration: 235.226µs)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:38:55.114822Z","caller":"traceutil/trace.go:171","msg":"trace[284197027] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"149.690459ms","start":"2026-03-26T09:38:54.965085Z","end":"2026-03-26T09:38:55.114776Z","steps":["trace[284197027] 'process raft request' (duration: 149.395471ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:38:55.114934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.733825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-26T09:38:55.115017Z","caller":"traceutil/trace.go:171","msg":"trace[1493039793] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:436; }","duration":"147.858688ms","start":"2026-03-26T09:38:54.967137Z","end":"2026-03-26T09:38:55.114995Z","steps":["trace[1493039793] 'agreement among raft nodes before linearized reading' (duration: 147.646244ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:38:56.686129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.213563729s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T09:38:56.686379Z","caller":"traceutil/trace.go:171","msg":"trace[382468248] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"437.268384ms","start":"2026-03-26T09:38:56.249094Z","end":"2026-03-26T09:38:56.686362Z","steps":["trace[382468248] 'process raft request' (duration: 437.195232ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:38:56.686452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:38:56.249065Z","time spent":"437.347805ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:38:56.686457Z","caller":"traceutil/trace.go:171","msg":"trace[698998398] linearizableReadLoop","detail":"{readStateIndex:454; appliedIndex:453; }","duration":"1.565138053s","start":"2026-03-26T09:38:55.121302Z","end":"2026-03-26T09:38:56.68644Z","steps":["trace[698998398] 'read index received' (duration: 350.903861ms)","trace[698998398] 'applied index is now lower than readState.Index' (duration: 1.214231472s)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:38:56.686575Z","caller":"traceutil/trace.go:171","msg":"trace[1289915538] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"1.567382452s","start":"2026-03-26T09:38:55.119181Z","end":"2026-03-26T09:38:56.686564Z","steps":["trace[1289915538] 'process raft request' (duration: 353.151079ms)","trace[1289915538] 'compare' (duration: 1.213322374s)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:38:56.686648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:38:55.119153Z","time spent":"1.567456504s","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:38:56.686797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.565499022s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-26T09:38:56.686793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.175633071s","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-26T09:38:56.686838Z","caller":"traceutil/trace.go:171","msg":"trace[1704091694] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:0; response_revision:438; }","duration":"1.565580263s","start":"2026-03-26T09:38:55.121247Z","end":"2026-03-26T09:38:56.686827Z","steps":["trace[1704091694] 'agreement among raft nodes before linearized reading' (duration: 1.565506211s)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:38:56.686855Z","caller":"traceutil/trace.go:171","msg":"trace[644053708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:438; }","duration":"1.175733044s","start":"2026-03-26T09:38:55.511105Z","end":"2026-03-26T09:38:56.686838Z","steps":["trace[644053708] 'agreement among raft nodes before linearized reading' (duration: 1.175613701s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:38:56.686874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:38:55.121225Z","time spent":"1.565641165s","remote":"127.0.0.1:42552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/configmaps/kube-system/cilium-config\" "} {"level":"warn","ts":"2026-03-26T09:38:56.686885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:38:55.51108Z","time spent":"1.175798195s","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-26T09:38:57.155731Z","caller":"traceutil/trace.go:171","msg":"trace[1150791198] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"232.187875ms","start":"2026-03-26T09:38:56.923504Z","end":"2026-03-26T09:38:57.155692Z","steps":["trace[1150791198] 'process raft request' (duration: 232.116693ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:38:57.156759Z","caller":"traceutil/trace.go:171","msg":"trace[1474504225] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"234.332662ms","start":"2026-03-26T09:38:56.922377Z","end":"2026-03-26T09:38:57.15671Z","steps":["trace[1474504225] 'process raft request' (duration: 221.345166ms)","trace[1474504225] 'compare' (duration: 11.509573ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:38:59.661473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.816352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-26T09:38:59.661502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.596894ms","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-26T09:38:59.661562Z","caller":"traceutil/trace.go:171","msg":"trace[1840485979] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:478; }","duration":"853.999736ms","start":"2026-03-26T09:38:58.807539Z","end":"2026-03-26T09:38:59.661539Z","steps":["trace[1840485979] 'range keys from in-memory index tree' (duration: 853.68442ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:38:59.661579Z","caller":"traceutil/trace.go:171","msg":"trace[478645885] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:478; }","duration":"836.698895ms","start":"2026-03-26T09:38:58.824857Z","end":"2026-03-26T09:38:59.661555Z","steps":["trace[478645885] 'range keys from in-memory index tree' (duration: 836.403079ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:38:59.661603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:38:58.807509Z","time spent":"854.085388ms","remote":"127.0.0.1:42452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-26T09:38:59.661636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:38:58.824837Z","time spent":"836.787668ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-26T09:38:59.670338Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085743586161162819,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-26T09:38:59.923738Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.087500661s","expected-duration":"1s"} {"level":"info","ts":"2026-03-26T09:38:59.924147Z","caller":"traceutil/trace.go:171","msg":"trace[109313270] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"1.088006784s","start":"2026-03-26T09:38:58.836113Z","end":"2026-03-26T09:38:59.924119Z","steps":["trace[109313270] 'process raft request' (duration: 1.08787325s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:38:59.92432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:38:58.836077Z","time spent":"1.088154847s","remote":"127.0.0.1:42504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T09:38:59.935484Z","caller":"traceutil/trace.go:171","msg":"trace[826197865] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"1.09148983s","start":"2026-03-26T09:38:58.843971Z","end":"2026-03-26T09:38:59.935461Z","steps":["trace[826197865] 'process raft request' (duration: 1.091312655s)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:38:59.935702Z","caller":"traceutil/trace.go:171","msg":"trace[845051331] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"1.062109972s","start":"2026-03-26T09:38:58.873551Z","end":"2026-03-26T09:38:59.935661Z","steps":["trace[845051331] 'process raft request' (duration: 1.061895168s)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:38:59.935815Z","caller":"traceutil/trace.go:171","msg":"trace[964854219] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"1.043908631s","start":"2026-03-26T09:38:58.89189Z","end":"2026-03-26T09:38:59.935799Z","steps":["trace[964854219] 'process raft request' (duration: 1.043752238s)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:38:59.935783Z","caller":"traceutil/trace.go:171","msg":"trace[988530574] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"1.057375407s","start":"2026-03-26T09:38:58.878383Z","end":"2026-03-26T09:38:59.935759Z","steps":["trace[988530574] 'process raft request' (duration: 1.057088391s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:38:59.935837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:38:58.873526Z","time spent":"1.062246606s","remote":"127.0.0.1:42504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-26T09:38:59.935874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:38:58.891826Z","time spent":"1.044021273s","remote":"127.0.0.1:42504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-26T09:38:59.935891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:38:58.878359Z","time spent":"1.057503301s","remote":"127.0.0.1:42504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T09:38:59.935884Z","caller":"traceutil/trace.go:171","msg":"trace[1515092059] linearizableReadLoop","detail":"{readStateIndex:508; appliedIndex:496; }","duration":"765.798024ms","start":"2026-03-26T09:38:59.170073Z","end":"2026-03-26T09:38:59.935871Z","steps":["trace[1515092059] 'read index received' (duration: 754.008144ms)","trace[1515092059] 'applied index is now lower than readState.Index' (duration: 11.78804ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:38:59.935958Z","caller":"traceutil/trace.go:171","msg":"trace[1824676921] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"1.038808209s","start":"2026-03-26T09:38:58.897145Z","end":"2026-03-26T09:38:59.935953Z","steps":["trace[1824676921] 'process raft request' (duration: 1.038531643s)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:38:59.935963Z","caller":"traceutil/trace.go:171","msg":"trace[982596918] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"1.0488645s","start":"2026-03-26T09:38:58.88709Z","end":"2026-03-26T09:38:59.935954Z","steps":["trace[982596918] 'process raft request' (duration: 1.048491582s)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:38:59.935986Z","caller":"traceutil/trace.go:171","msg":"trace[1182415898] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"265.45159ms","start":"2026-03-26T09:38:59.670524Z","end":"2026-03-26T09:38:59.935976Z","steps":["trace[1182415898] 'process raft request' (duration: 265.304967ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:38:59.93601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:38:58.887065Z","time spent":"1.048922121s","remote":"127.0.0.1:42504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-26T09:38:59.936033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:38:58.897113Z","time spent":"1.03886648s","remote":"127.0.0.1:42504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T09:38:59.936105Z","caller":"traceutil/trace.go:171","msg":"trace[1807223665] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"1.072148734s","start":"2026-03-26T09:38:58.863947Z","end":"2026-03-26T09:38:59.936096Z","steps":["trace[1807223665] 'process raft request' (duration: 1.071473889s)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:38:59.936121Z","caller":"traceutil/trace.go:171","msg":"trace[870990904] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"1.033777088s","start":"2026-03-26T09:38:58.902335Z","end":"2026-03-26T09:38:59.936113Z","steps":["trace[870990904] 'process raft request' (duration: 1.03338612s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:38:59.936145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.080919ms","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-26T09:38:59.936155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:38:58.863938Z","time spent":"1.072193794s","remote":"127.0.0.1:42504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T09:38:59.936169Z","caller":"traceutil/trace.go:171","msg":"trace[1692510957] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:491; }","duration":"766.150981ms","start":"2026-03-26T09:38:59.170013Z","end":"2026-03-26T09:38:59.936164Z","steps":["trace[1692510957] 'agreement among raft nodes before linearized reading' (duration: 766.10231ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:38:59.936187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:38:59.169985Z","time spent":"766.198253ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-26T09:38:59.93618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:38:58.902301Z","time spent":"1.033841079s","remote":"127.0.0.1:42504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-26T09:38:59.935628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:38:58.843953Z","time spent":"1.091605942s","remote":"127.0.0.1:42504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T09:38:59.936233Z","caller":"traceutil/trace.go:171","msg":"trace[1819820871] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"934.550451ms","start":"2026-03-26T09:38:59.001672Z","end":"2026-03-26T09:38:59.936223Z","steps":["trace[1819820871] 'process raft request' (duration: 934.111342ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:38:59.935507Z","caller":"traceutil/trace.go:171","msg":"trace[241839809] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"1.071662193s","start":"2026-03-26T09:38:58.863829Z","end":"2026-03-26T09:38:59.935491Z","steps":["trace[241839809] 'process raft request' (duration: 1.071561851s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:38:59.936337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:38:59.001638Z","time spent":"934.675933ms","remote":"127.0.0.1:42504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-26T09:38:59.93637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:38:58.863799Z","time spent":"1.072536672s","remote":"127.0.0.1:42504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T09:38:59.935737Z","caller":"traceutil/trace.go:171","msg":"trace[700949955] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"1.057029921s","start":"2026-03-26T09:38:58.878685Z","end":"2026-03-26T09:38:59.935715Z","steps":["trace[700949955] 'process raft request' (duration: 1.056866387s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:38:59.936474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:38:58.878668Z","time spent":"1.057754546s","remote":"127.0.0.1:42504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-26T09:38:59.943879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.686399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:38:59.943947Z","caller":"traceutil/trace.go:171","msg":"trace[134733229] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:493; }","duration":"134.810892ms","start":"2026-03-26T09:38:59.809121Z","end":"2026-03-26T09:38:59.943932Z","steps":["trace[134733229] 'agreement among raft nodes before linearized reading' (duration: 134.691089ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:39:07.683315Z","caller":"traceutil/trace.go:171","msg":"trace[127680669] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"744.946304ms","start":"2026-03-26T09:39:06.938333Z","end":"2026-03-26T09:39:07.683279Z","steps":["trace[127680669] 'process raft request' (duration: 744.76967ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:39:07.683499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:39:06.938306Z","time spent":"745.105987ms","remote":"127.0.0.1:42676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T09:39:07.701108Z","caller":"traceutil/trace.go:171","msg":"trace[306211317] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:613; }","duration":"190.938046ms","start":"2026-03-26T09:39:07.510149Z","end":"2026-03-26T09:39:07.701087Z","steps":["trace[306211317] 'read index received' (duration: 173.004121ms)","trace[306211317] 'applied index is now lower than readState.Index' (duration: 17.933095ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:39:07.701219Z","caller":"traceutil/trace.go:171","msg":"trace[1397550483] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"685.919644ms","start":"2026-03-26T09:39:07.015267Z","end":"2026-03-26T09:39:07.701187Z","steps":["trace[1397550483] 'process raft request' (duration: 685.697979ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:39:07.701269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.077279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-03-26T09:39:07.701363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:39:07.015236Z","time spent":"686.044296ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:39:07.701315Z","caller":"traceutil/trace.go:171","msg":"trace[2035221004] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:1; response_revision:592; }","duration":"191.183622ms","start":"2026-03-26T09:39:07.510105Z","end":"2026-03-26T09:39:07.701288Z","steps":["trace[2035221004] 'agreement among raft nodes before linearized reading' (duration: 191.08935ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:39:07.701581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.578546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-lrqsr\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-26T09:39:07.701665Z","caller":"traceutil/trace.go:171","msg":"trace[2025790526] range","detail":"{range_begin:/registry/pods/kube-system/cilium-lrqsr; range_end:; response_count:1; response_revision:592; }","duration":"105.7961ms","start":"2026-03-26T09:39:07.59585Z","end":"2026-03-26T09:39:07.701646Z","steps":["trace[2025790526] 'agreement among raft nodes before linearized reading' (duration: 105.478694ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:39:08.912776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.512625ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T09:39:08.912949Z","caller":"traceutil/trace.go:171","msg":"trace[1553842048] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:616; }","duration":"1.175703944s","start":"2026-03-26T09:39:07.737232Z","end":"2026-03-26T09:39:08.912936Z","steps":["trace[1553842048] 'read index received' (duration: 287.983155ms)","trace[1553842048] 'applied index is now lower than readState.Index' (duration: 887.719949ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:39:08.913165Z","caller":"traceutil/trace.go:171","msg":"trace[207489341] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"1.195363067s","start":"2026-03-26T09:39:07.717793Z","end":"2026-03-26T09:39:08.913156Z","steps":["trace[207489341] 'process raft request' (duration: 307.406152ms)","trace[207489341] 'compare' (duration: 886.910482ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:39:08.913224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:39:07.717772Z","time spent":"1.195420569s","remote":"127.0.0.1:42620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:39:08.913443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.102147933s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:39:08.913563Z","caller":"traceutil/trace.go:171","msg":"trace[45025245] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:594; }","duration":"1.102322338s","start":"2026-03-26T09:39:07.811215Z","end":"2026-03-26T09:39:08.913538Z","steps":["trace[45025245] 'agreement among raft nodes before linearized reading' (duration: 1.102119593s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:39:08.913642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:39:07.811195Z","time spent":"1.1024327s","remote":"127.0.0.1:42452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-26T09:39:08.913917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.229298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:39:08.913964Z","caller":"traceutil/trace.go:171","msg":"trace[733334846] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:594; }","duration":"774.335501ms","start":"2026-03-26T09:39:08.139615Z","end":"2026-03-26T09:39:08.91395Z","steps":["trace[733334846] 'agreement among raft nodes before linearized reading' (duration: 774.26239ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:39:08.914002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:39:08.139574Z","time spent":"774.417923ms","remote":"127.0.0.1:42440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-26T09:39:08.914147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.17690204s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-26T09:39:08.914152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"865.849177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-26T09:39:08.914199Z","caller":"traceutil/trace.go:171","msg":"trace[1547525972] range","detail":"{range_begin:/registry/limitranges/envoy-gateway-system/; range_end:/registry/limitranges/envoy-gateway-system0; response_count:0; response_revision:594; }","duration":"1.176980161s","start":"2026-03-26T09:39:07.737202Z","end":"2026-03-26T09:39:08.914182Z","steps":["trace[1547525972] 'agreement among raft nodes before linearized reading' (duration: 1.17689744s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:39:08.914237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:39:07.737187Z","time spent":"1.177041853s","remote":"127.0.0.1:42574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" "} {"level":"info","ts":"2026-03-26T09:39:08.914237Z","caller":"traceutil/trace.go:171","msg":"trace[239667072] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:594; }","duration":"865.98851ms","start":"2026-03-26T09:39:08.048228Z","end":"2026-03-26T09:39:08.914216Z","steps":["trace[239667072] 'agreement among raft nodes before linearized reading' (duration: 865.727695ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:39:08.914278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.263514ms","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-26T09:39:08.91429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:39:08.048201Z","time spent":"866.078991ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-26T09:39:08.91429Z","caller":"traceutil/trace.go:171","msg":"trace[1805582836] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"1.187700858s","start":"2026-03-26T09:39:07.726563Z","end":"2026-03-26T09:39:08.914264Z","steps":["trace[1805582836] 'process raft request' (duration: 1.186323828s)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:39:08.914326Z","caller":"traceutil/trace.go:171","msg":"trace[587422576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:594; }","duration":"845.387406ms","start":"2026-03-26T09:39:08.068926Z","end":"2026-03-26T09:39:08.914314Z","steps":["trace[587422576] 'agreement among raft nodes before linearized reading' (duration: 845.170112ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:39:08.914361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:39:08.068886Z","time spent":"845.466808ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-26T09:39:08.914515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:39:07.726553Z","time spent":"1.187785701s","remote":"127.0.0.1:42482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:39:09.185185Z","caller":"traceutil/trace.go:171","msg":"trace[916708967] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"266.610006ms","start":"2026-03-26T09:39:08.918542Z","end":"2026-03-26T09:39:09.185152Z","steps":["trace[916708967] 'read index received' (duration: 266.486743ms)","trace[916708967] 'applied index is now lower than readState.Index' (duration: 122.163µs)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:39:09.185271Z","caller":"traceutil/trace.go:171","msg":"trace[2103040672] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"268.391655ms","start":"2026-03-26T09:39:08.916854Z","end":"2026-03-26T09:39:09.185246Z","steps":["trace[2103040672] 'process raft request' (duration: 268.1603ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:39:09.185711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.140947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-26T09:39:09.185765Z","caller":"traceutil/trace.go:171","msg":"trace[1053461847] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:595; }","duration":"267.264359ms","start":"2026-03-26T09:39:08.918487Z","end":"2026-03-26T09:39:09.185751Z","steps":["trace[1053461847] 'agreement among raft nodes before linearized reading' (duration: 266.83748ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:39:09.685868Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085743586161163088,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-26T09:39:10.186106Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085743586161163088,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-26T09:39:10.61543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.863305ms","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-26T09:39:10.615579Z","caller":"traceutil/trace.go:171","msg":"trace[1728410332] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:595; }","duration":"142.03312ms","start":"2026-03-26T09:39:10.473524Z","end":"2026-03-26T09:39:10.615557Z","steps":["trace[1728410332] 'range keys from in-memory index tree' (duration: 141.841845ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:39:10.68729Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085743586161163088,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-26T09:39:10.924638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000705393s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-26T09:39:10.924742Z","caller":"traceutil/trace.go:171","msg":"trace[662694671] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000873756s","start":"2026-03-26T09:39:08.923846Z","end":"2026-03-26T09:39:10.92472Z","steps":["trace[662694671] 'agreement among raft nodes before linearized reading' (duration: 2.000737863s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:39:10.924809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:39:08.923823Z","time spent":"2.000970818s","remote":"127.0.0.1:42452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/26 09:39:10 WARNING: [core] [Server #5] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-26T09:39:11.187671Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085743586161163088,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-26T09:39:11.503732Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.318670929s","expected-duration":"1s"} {"level":"info","ts":"2026-03-26T09:39:11.504701Z","caller":"traceutil/trace.go:171","msg":"trace[1046132798] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"2.584609568s","start":"2026-03-26T09:39:08.920059Z","end":"2026-03-26T09:39:11.504669Z","steps":["trace[1046132798] 'process raft request' (duration: 2.584542126s)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:39:11.504743Z","caller":"traceutil/trace.go:171","msg":"trace[562868180] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"2.585607211s","start":"2026-03-26T09:39:08.919103Z","end":"2026-03-26T09:39:11.50471Z","steps":["trace[562868180] 'process raft request' (duration: 2.585343494s)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:39:11.504755Z","caller":"traceutil/trace.go:171","msg":"trace[642797914] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"2.585352135s","start":"2026-03-26T09:39:08.919375Z","end":"2026-03-26T09:39:11.504728Z","steps":["trace[642797914] 'process raft request' (duration: 2.585178761s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:39:11.504901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:39:08.919093Z","time spent":"2.585706953s","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:39:11.504901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:39:08.92004Z","time spent":"2.584751402s","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:39:11.504966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:39:08.919364Z","time spent":"2.585495848s","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:39:11.505517Z","caller":"traceutil/trace.go:171","msg":"trace[1807969190] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"2.585654592s","start":"2026-03-26T09:39:08.918996Z","end":"2026-03-26T09:39:11.504651Z","steps":["trace[1807969190] 'process raft request' (duration: 2.585109939s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:39:11.505665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:39:08.918975Z","time spent":"2.586638852s","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:39:11.540059Z","caller":"traceutil/trace.go:171","msg":"trace[28397480] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"2.350144342s","start":"2026-03-26T09:39:09.189878Z","end":"2026-03-26T09:39:11.540022Z","steps":["trace[28397480] 'process raft request' (duration: 2.349951658s)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:39:11.540127Z","caller":"traceutil/trace.go:171","msg":"trace[886462077] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"2.348805603s","start":"2026-03-26T09:39:09.191301Z","end":"2026-03-26T09:39:11.540107Z","steps":["trace[886462077] 'process raft request' (duration: 2.348677991s)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:39:11.540287Z","caller":"traceutil/trace.go:171","msg":"trace[1374671855] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"2.350165343s","start":"2026-03-26T09:39:09.190098Z","end":"2026-03-26T09:39:11.540264Z","steps":["trace[1374671855] 'process raft request' (duration: 2.349856256s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:39:11.540293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:39:09.189858Z","time spent":"2.350279195s","remote":"127.0.0.1:42524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-26T09:39:11.540354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:39:09.19129Z","time spent":"2.348892285s","remote":"127.0.0.1:42620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:39:11.540406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:39:09.190088Z","time spent":"2.350240554s","remote":"127.0.0.1:42524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T09:39:11.540476Z","caller":"traceutil/trace.go:171","msg":"trace[1117714150] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"895.980191ms","start":"2026-03-26T09:39:10.644489Z","end":"2026-03-26T09:39:11.540469Z","steps":["trace[1117714150] 'process raft request' (duration: 895.588353ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:39:11.540514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:39:10.644453Z","time spent":"896.034182ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:39:11.540677Z","caller":"traceutil/trace.go:171","msg":"trace[1500405703] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:619; }","duration":"2.355301615s","start":"2026-03-26T09:39:09.185366Z","end":"2026-03-26T09:39:11.540668Z","steps":["trace[1500405703] 'read index received' (duration: 2.318689349s)","trace[1500405703] 'applied index is now lower than readState.Index' (duration: 36.611606ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:39:11.540684Z","caller":"traceutil/trace.go:171","msg":"trace[517986733] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"612.27261ms","start":"2026-03-26T09:39:10.928398Z","end":"2026-03-26T09:39:11.540671Z","steps":["trace[517986733] 'process raft request' (duration: 612.047465ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:39:11.540782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:39:10.92837Z","time spent":"612.344071ms","remote":"127.0.0.1:42524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T09:39:11.540447Z","caller":"traceutil/trace.go:171","msg":"trace[1700448748] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"2.347940604s","start":"2026-03-26T09:39:09.192473Z","end":"2026-03-26T09:39:11.540414Z","steps":["trace[1700448748] 'process raft request' (duration: 2.347570416s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:39:11.540787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.351863139s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-03-26T09:39:11.540845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.241523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:39:11.540932Z","caller":"traceutil/trace.go:171","msg":"trace[1705225076] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:605; }","duration":"609.378346ms","start":"2026-03-26T09:39:10.931535Z","end":"2026-03-26T09:39:11.540913Z","steps":["trace[1705225076] 'agreement among raft nodes before linearized reading' (duration: 609.242114ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:39:11.540983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:39:10.93151Z","time spent":"609.466648ms","remote":"127.0.0.1:42440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-26T09:39:11.541025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:39:09.192459Z","time spent":"2.348449774s","remote":"127.0.0.1:42676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:39:11.540893Z","caller":"traceutil/trace.go:171","msg":"trace[1503049991] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:605; }","duration":"2.352075193s","start":"2026-03-26T09:39:09.188812Z","end":"2026-03-26T09:39:11.540887Z","steps":["trace[1503049991] 'agreement among raft nodes before linearized reading' (duration: 2.351917099s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:39:11.541164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:39:09.188783Z","time spent":"2.352368632s","remote":"127.0.0.1:42704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-03-26T09:39:13.31005Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085743586161163103,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-26T09:39:13.402442Z","caller":"traceutil/trace.go:171","msg":"trace[820548008] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"593.073758ms","start":"2026-03-26T09:39:12.809324Z","end":"2026-03-26T09:39:13.402398Z","steps":["trace[820548008] 'read index received' (duration: 502.953303ms)","trace[820548008] 'applied index is now lower than readState.Index' (duration: 90.118115ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:39:13.402586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.242532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:39:13.402648Z","caller":"traceutil/trace.go:171","msg":"trace[480641571] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:616; }","duration":"593.374704ms","start":"2026-03-26T09:39:12.809258Z","end":"2026-03-26T09:39:13.402632Z","steps":["trace[480641571] 'agreement among raft nodes before linearized reading' (duration: 593.227051ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:39:13.402684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:39:12.809226Z","time spent":"593.453276ms","remote":"127.0.0.1:42440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-26T09:39:13.402821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.041885ms","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-26T09:39:13.402876Z","caller":"traceutil/trace.go:171","msg":"trace[21025657] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:616; }","duration":"105.159017ms","start":"2026-03-26T09:39:13.297702Z","end":"2026-03-26T09:39:13.402861Z","steps":["trace[21025657] 'agreement among raft nodes before linearized reading' (duration: 104.979764ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:39:13.545007Z","caller":"traceutil/trace.go:171","msg":"trace[1336245732] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"113.018721ms","start":"2026-03-26T09:39:13.431941Z","end":"2026-03-26T09:39:13.544959Z","steps":["trace[1336245732] 'process raft request' (duration: 112.718045ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:39:23.932941Z","caller":"traceutil/trace.go:171","msg":"trace[898851146] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"258.812024ms","start":"2026-03-26T09:39:23.67409Z","end":"2026-03-26T09:39:23.932902Z","steps":["trace[898851146] 'process raft request' (duration: 253.379136ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:39:23.933591Z","caller":"traceutil/trace.go:171","msg":"trace[375094946] linearizableReadLoop","detail":"{readStateIndex:765; appliedIndex:761; }","duration":"122.98176ms","start":"2026-03-26T09:39:23.810581Z","end":"2026-03-26T09:39:23.933563Z","steps":["trace[375094946] 'read index received' (duration: 116.90959ms)","trace[375094946] 'applied index is now lower than readState.Index' (duration: 6.07081ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:39:23.933575Z","caller":"traceutil/trace.go:171","msg":"trace[889018114] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"259.101509ms","start":"2026-03-26T09:39:23.674443Z","end":"2026-03-26T09:39:23.933545Z","steps":["trace[889018114] 'process raft request' (duration: 258.344035ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:39:23.933742Z","caller":"traceutil/trace.go:171","msg":"trace[343830393] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"259.002428ms","start":"2026-03-26T09:39:23.674722Z","end":"2026-03-26T09:39:23.933724Z","steps":["trace[343830393] 'process raft request' (duration: 258.792483ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:39:23.933748Z","caller":"traceutil/trace.go:171","msg":"trace[910078134] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"258.953586ms","start":"2026-03-26T09:39:23.674695Z","end":"2026-03-26T09:39:23.933649Z","steps":["trace[910078134] 'process raft request' (duration: 258.725401ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:39:23.93402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.275506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:39:23.934098Z","caller":"traceutil/trace.go:171","msg":"trace[1096317340] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:737; }","duration":"123.557262ms","start":"2026-03-26T09:39:23.810524Z","end":"2026-03-26T09:39:23.934081Z","steps":["trace[1096317340] 'agreement among raft nodes before linearized reading' (duration: 123.154724ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:39:27.309892Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085743586161163379,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-26T09:39:27.790962Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.056103908s","expected-duration":"1s"} {"level":"info","ts":"2026-03-26T09:39:27.791532Z","caller":"traceutil/trace.go:171","msg":"trace[718670110] linearizableReadLoop","detail":"{readStateIndex:780; appliedIndex:779; }","duration":"981.668997ms","start":"2026-03-26T09:39:26.809816Z","end":"2026-03-26T09:39:27.791485Z","steps":["trace[718670110] 'read index received' (duration: 981.400332ms)","trace[718670110] 'applied index is now lower than readState.Index' (duration: 267.235µs)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:39:27.791742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"981.897611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:39:27.791812Z","caller":"traceutil/trace.go:171","msg":"trace[1533240920] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:752; }","duration":"982.038364ms","start":"2026-03-26T09:39:26.809762Z","end":"2026-03-26T09:39:27.7918Z","steps":["trace[1533240920] 'agreement among raft nodes before linearized reading' (duration: 981.909251ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:39:27.791876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:39:26.809734Z","time spent":"982.129335ms","remote":"127.0.0.1:42440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-26T09:39:27.791971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.256867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-26T09:39:27.792037Z","caller":"traceutil/trace.go:171","msg":"trace[437678899] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:1; response_revision:752; }","duration":"281.38745ms","start":"2026-03-26T09:39:27.510636Z","end":"2026-03-26T09:39:27.792024Z","steps":["trace[437678899] 'agreement among raft nodes before linearized reading' (duration: 281.173826ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:39:27.792041Z","caller":"traceutil/trace.go:171","msg":"trace[1104838487] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:752; }","duration":"100.020406ms","start":"2026-03-26T09:39:27.692005Z","end":"2026-03-26T09:39:27.792025Z","steps":["trace[1104838487] 'agreement among raft nodes before linearized reading' (duration: 99.955805ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:39:27.791723Z","caller":"traceutil/trace.go:171","msg":"trace[312264518] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"1.056996805s","start":"2026-03-26T09:39:26.734673Z","end":"2026-03-26T09:39:27.79167Z","steps":["trace[312264518] 'process raft request' (duration: 1.056624098s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:39:27.792336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:39:26.734639Z","time spent":"1.057625228s","remote":"127.0.0.1:42676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:40:03.584032Z","caller":"traceutil/trace.go:171","msg":"trace[1329781873] linearizableReadLoop","detail":"{readStateIndex:1013; appliedIndex:1012; }","duration":"498.891138ms","start":"2026-03-26T09:40:03.085097Z","end":"2026-03-26T09:40:03.583988Z","steps":["trace[1329781873] 'read index received' (duration: 497.988931ms)","trace[1329781873] 'applied index is now lower than readState.Index' (duration: 900.857µs)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:40:03.584129Z","caller":"traceutil/trace.go:171","msg":"trace[4514140] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"572.082564ms","start":"2026-03-26T09:40:03.012018Z","end":"2026-03-26T09:40:03.5841Z","steps":["trace[4514140] 'process raft request' (duration: 571.136295ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:40:03.584295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.088832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-26T09:40:03.584321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:40:03.011994Z","time spent":"572.252138ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:40:03.58435Z","caller":"traceutil/trace.go:171","msg":"trace[2053699670] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:976; }","duration":"499.251525ms","start":"2026-03-26T09:40:03.085084Z","end":"2026-03-26T09:40:03.584336Z","steps":["trace[2053699670] 'agreement among raft nodes before linearized reading' (duration: 499.048982ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:40:03.584449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:40:03.085043Z","time spent":"499.390448ms","remote":"127.0.0.1:42880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-03-26T09:40:04.644271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.054265ms","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-26T09:40:04.644365Z","caller":"traceutil/trace.go:171","msg":"trace[901530446] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:976; }","duration":"484.179138ms","start":"2026-03-26T09:40:04.160146Z","end":"2026-03-26T09:40:04.644325Z","steps":["trace[901530446] 'range keys from in-memory index tree' (duration: 483.885592ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:40:04.644402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:40:04.160124Z","time spent":"484.271679ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-26T09:40:04.644723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.979458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:40:04.644752Z","caller":"traceutil/trace.go:171","msg":"trace[901808433] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:976; }","duration":"835.04235ms","start":"2026-03-26T09:40:03.809702Z","end":"2026-03-26T09:40:04.644744Z","steps":["trace[901808433] 'range keys from in-memory index tree' (duration: 834.911187ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:40:04.644771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:40:03.809682Z","time spent":"835.08486ms","remote":"127.0.0.1:42440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-26T09:40:04.644747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.163638ms","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-26T09:40:04.644849Z","caller":"traceutil/trace.go:171","msg":"trace[994876923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:976; }","duration":"481.29408ms","start":"2026-03-26T09:40:04.163533Z","end":"2026-03-26T09:40:04.644827Z","steps":["trace[994876923] 'range keys from in-memory index tree' (duration: 481.015145ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:40:04.644894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:40:04.163527Z","time spent":"481.358921ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-26T09:40:04.645103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.683459ms","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-26T09:40:04.645128Z","caller":"traceutil/trace.go:171","msg":"trace[1025324889] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:976; }","duration":"639.753471ms","start":"2026-03-26T09:40:04.005368Z","end":"2026-03-26T09:40:04.645121Z","steps":["trace[1025324889] 'range keys from in-memory index tree' (duration: 639.531346ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:40:04.64514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.647527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-03-26T09:40:04.645146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:40:04.005342Z","time spent":"639.800102ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-26T09:40:04.645164Z","caller":"traceutil/trace.go:171","msg":"trace[584385333] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:976; }","duration":"481.689357ms","start":"2026-03-26T09:40:04.163467Z","end":"2026-03-26T09:40:04.645157Z","steps":["trace[584385333] 'range keys from in-memory index tree' (duration: 481.506014ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:40:04.645186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:40:04.163456Z","time spent":"481.724669ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-26T09:40:04.645324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.574185ms","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-26T09:40:04.645343Z","caller":"traceutil/trace.go:171","msg":"trace[1193319210] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:976; }","duration":"481.635977ms","start":"2026-03-26T09:40:04.163701Z","end":"2026-03-26T09:40:04.645337Z","steps":["trace[1193319210] 'range keys from in-memory index tree' (duration: 481.438502ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:40:04.645361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:40:04.163676Z","time spent":"481.680758ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-26T09:40:05.311023Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085743586161163864,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-26T09:40:05.811819Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085743586161163864,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-26T09:40:05.895144Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.244623003s","expected-duration":"1s"} {"level":"info","ts":"2026-03-26T09:40:05.895636Z","caller":"traceutil/trace.go:171","msg":"trace[1570639388] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"1.245193734s","start":"2026-03-26T09:40:04.650414Z","end":"2026-03-26T09:40:05.895608Z","steps":["trace[1570639388] 'process raft request' (duration: 1.24498788s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:40:05.895805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:40:04.650388Z","time spent":"1.245336037s","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:40:06.045901Z","caller":"traceutil/trace.go:171","msg":"trace[1020402306] linearizableReadLoop","detail":"{readStateIndex:1019; appliedIndex:1013; }","duration":"1.235481152s","start":"2026-03-26T09:40:04.810399Z","end":"2026-03-26T09:40:06.04588Z","steps":["trace[1020402306] 'read index received' (duration: 1.08509539s)","trace[1020402306] 'applied index is now lower than readState.Index' (duration: 150.384542ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:40:06.046161Z","caller":"traceutil/trace.go:171","msg":"trace[1469299467] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"455.397668ms","start":"2026-03-26T09:40:05.590727Z","end":"2026-03-26T09:40:06.046125Z","steps":["trace[1469299467] 'process raft request' (duration: 455.120712ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:40:06.046233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.523501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:40:06.046278Z","caller":"traceutil/trace.go:171","msg":"trace[818580807] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"1.395499794s","start":"2026-03-26T09:40:04.650759Z","end":"2026-03-26T09:40:06.046259Z","steps":["trace[818580807] 'process raft request' (duration: 1.395006365s)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:40:06.046305Z","caller":"traceutil/trace.go:171","msg":"trace[312600244] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"1.395474304s","start":"2026-03-26T09:40:04.65082Z","end":"2026-03-26T09:40:06.046294Z","steps":["trace[312600244] 'process raft request' (duration: 1.394974724s)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:40:06.046298Z","caller":"traceutil/trace.go:171","msg":"trace[180936973] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:982; }","duration":"954.643283ms","start":"2026-03-26T09:40:05.091639Z","end":"2026-03-26T09:40:06.046282Z","steps":["trace[180936973] 'agreement among raft nodes before linearized reading' (duration: 954.51386ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:40:06.046327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:40:05.590698Z","time spent":"455.554301ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:40:06.046316Z","caller":"traceutil/trace.go:171","msg":"trace[1792404019] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"1.395577346s","start":"2026-03-26T09:40:04.65071Z","end":"2026-03-26T09:40:06.046287Z","steps":["trace[1792404019] 'process raft request' (duration: 1.394859742s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:40:06.04635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:40:05.091621Z","time spent":"954.719115ms","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-03-26T09:40:06.0464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:40:04.650741Z","time spent":"1.395590725s","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:40:06.046442Z","caller":"traceutil/trace.go:171","msg":"trace[1585039900] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"1.395397971s","start":"2026-03-26T09:40:04.651037Z","end":"2026-03-26T09:40:06.046435Z","steps":["trace[1585039900] 'process raft request' (duration: 1.3947878s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:40:06.0464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:40:04.650814Z","time spent":"1.395517084s","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:40:06.046461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:40:04.650693Z","time spent":"1.395695478s","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:40:06.046485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:40:04.65101Z","time spent":"1.395451682s","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:40:06.046694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.23583449s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-26T09:40:06.046619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.84448ms","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:7"} {"level":"info","ts":"2026-03-26T09:40:06.046885Z","caller":"traceutil/trace.go:171","msg":"trace[387983084] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:982; }","duration":"1.236512283s","start":"2026-03-26T09:40:04.81036Z","end":"2026-03-26T09:40:06.046872Z","steps":["trace[387983084] 'agreement among raft nodes before linearized reading' (duration: 1.235810388s)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:40:06.0469Z","caller":"traceutil/trace.go:171","msg":"trace[1686377101] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:982; }","duration":"234.169278ms","start":"2026-03-26T09:40:05.812711Z","end":"2026-03-26T09:40:06.046881Z","steps":["trace[1686377101] 'agreement among raft nodes before linearized reading' (duration: 233.496274ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:40:06.046922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:40:04.810341Z","time spent":"1.236572555s","remote":"127.0.0.1:42440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-26T09:40:28.287377Z","caller":"traceutil/trace.go:171","msg":"trace[381090595] linearizableReadLoop","detail":"{readStateIndex:1093; appliedIndex:1092; }","duration":"141.949319ms","start":"2026-03-26T09:40:28.145405Z","end":"2026-03-26T09:40:28.287354Z","steps":["trace[381090595] 'read index received' (duration: 141.776646ms)","trace[381090595] 'applied index is now lower than readState.Index' (duration: 171.793µs)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:40:28.287634Z","caller":"traceutil/trace.go:171","msg":"trace[1826165618] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"158.338528ms","start":"2026-03-26T09:40:28.129281Z","end":"2026-03-26T09:40:28.28762Z","steps":["trace[1826165618] 'process raft request' (duration: 157.956821ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:40:28.288029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.612472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-03-26T09:40:28.288033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.714125ms","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-26T09:40:28.288075Z","caller":"traceutil/trace.go:171","msg":"trace[1839832651] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1051; }","duration":"142.713844ms","start":"2026-03-26T09:40:28.145351Z","end":"2026-03-26T09:40:28.288065Z","steps":["trace[1839832651] 'agreement among raft nodes before linearized reading' (duration: 142.55632ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:40:28.288114Z","caller":"traceutil/trace.go:171","msg":"trace[1182704473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1051; }","duration":"124.828788ms","start":"2026-03-26T09:40:28.163263Z","end":"2026-03-26T09:40:28.288091Z","steps":["trace[1182704473] 'agreement among raft nodes before linearized reading' (duration: 124.582182ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:40:28.288124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.699713ms","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-26T09:40:28.288178Z","caller":"traceutil/trace.go:171","msg":"trace[1716370612] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1051; }","duration":"126.770765ms","start":"2026-03-26T09:40:28.161391Z","end":"2026-03-26T09:40:28.288161Z","steps":["trace[1716370612] 'agreement among raft nodes before linearized reading' (duration: 126.634023ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:40:28.288252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.332485ms","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-26T09:40:28.288472Z","caller":"traceutil/trace.go:171","msg":"trace[1998517188] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1051; }","duration":"130.741482ms","start":"2026-03-26T09:40:28.157688Z","end":"2026-03-26T09:40:28.28843Z","steps":["trace[1998517188] 'agreement among raft nodes before linearized reading' (duration: 130.12415ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:40:28.522282Z","caller":"traceutil/trace.go:171","msg":"trace[251719126] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"229.045013ms","start":"2026-03-26T09:40:28.293209Z","end":"2026-03-26T09:40:28.522254Z","steps":["trace[251719126] 'process raft request' (duration: 228.595844ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:40:28.527791Z","caller":"traceutil/trace.go:171","msg":"trace[1007175417] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"234.04323ms","start":"2026-03-26T09:40:28.293722Z","end":"2026-03-26T09:40:28.527765Z","steps":["trace[1007175417] 'process raft request' (duration: 233.949499ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:40:28.527833Z","caller":"traceutil/trace.go:171","msg":"trace[1053412812] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"234.166092ms","start":"2026-03-26T09:40:28.293647Z","end":"2026-03-26T09:40:28.527813Z","steps":["trace[1053412812] 'process raft request' (duration: 233.927078ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:40:28.527861Z","caller":"traceutil/trace.go:171","msg":"trace[1390251846] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"234.04084ms","start":"2026-03-26T09:40:28.293803Z","end":"2026-03-26T09:40:28.527844Z","steps":["trace[1390251846] 'process raft request' (duration: 233.917248ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:40:29.974951Z","caller":"traceutil/trace.go:171","msg":"trace[283160509] linearizableReadLoop","detail":"{readStateIndex:1098; appliedIndex:1097; }","duration":"480.035263ms","start":"2026-03-26T09:40:29.494896Z","end":"2026-03-26T09:40:29.974931Z","steps":["trace[283160509] 'read index received' (duration: 479.820219ms)","trace[283160509] 'applied index is now lower than readState.Index' (duration: 214.444µs)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:40:29.975043Z","caller":"traceutil/trace.go:171","msg":"trace[1204152429] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"502.711733ms","start":"2026-03-26T09:40:29.472324Z","end":"2026-03-26T09:40:29.975035Z","steps":["trace[1204152429] 'process raft request' (duration: 502.490958ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:40:29.975137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:40:29.472286Z","time spent":"502.777514ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:40:29.975265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.36678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-26T09:40:29.975295Z","caller":"traceutil/trace.go:171","msg":"trace[1194050166] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1056; }","duration":"480.441041ms","start":"2026-03-26T09:40:29.494844Z","end":"2026-03-26T09:40:29.975285Z","steps":["trace[1194050166] 'agreement among raft nodes before linearized reading' (duration: 480.38877ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:40:29.975315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:40:29.494817Z","time spent":"480.492942ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":3,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-03-26T09:40:29.975644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.296613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:40:29.9758Z","caller":"traceutil/trace.go:171","msg":"trace[329922374] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1056; }","duration":"165.510237ms","start":"2026-03-26T09:40:29.810267Z","end":"2026-03-26T09:40:29.975777Z","steps":["trace[329922374] 'agreement among raft nodes before linearized reading' (duration: 165.297094ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:40:30.456385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.612123ms","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-26T09:40:30.456479Z","caller":"traceutil/trace.go:171","msg":"trace[538026397] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1056; }","duration":"447.808676ms","start":"2026-03-26T09:40:30.00865Z","end":"2026-03-26T09:40:30.456459Z","steps":["trace[538026397] 'range keys from in-memory index tree' (duration: 447.383917ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:40:30.45653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:40:30.008619Z","time spent":"447.900127ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-26T09:40:30.456535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.726733ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T09:40:30.456918Z","caller":"traceutil/trace.go:171","msg":"trace[1834191800] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"162.646993ms","start":"2026-03-26T09:40:30.29423Z","end":"2026-03-26T09:40:30.456877Z","steps":["trace[1834191800] 'process raft request' (duration: 50.368049ms)","trace[1834191800] 'compare' (duration: 111.505529ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:40:30.705259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.559383ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T09:40:30.705504Z","caller":"traceutil/trace.go:171","msg":"trace[1474312084] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"173.595465ms","start":"2026-03-26T09:40:30.53188Z","end":"2026-03-26T09:40:30.705476Z","steps":["trace[1474312084] 'process raft request' (duration: 52.488362ms)","trace[1474312084] 'compare' (duration: 120.217236ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:40:30.705762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.31931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-26T09:40:30.705845Z","caller":"traceutil/trace.go:171","msg":"trace[77554245] linearizableReadLoop","detail":"{readStateIndex:1101; appliedIndex:1100; }","duration":"173.020574ms","start":"2026-03-26T09:40:30.532407Z","end":"2026-03-26T09:40:30.705427Z","steps":["trace[77554245] 'read index received' (duration: 52.014243ms)","trace[77554245] 'applied index is now lower than readState.Index' (duration: 121.003931ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:40:30.70588Z","caller":"traceutil/trace.go:171","msg":"trace[1801989826] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1059; }","duration":"173.485512ms","start":"2026-03-26T09:40:30.532369Z","end":"2026-03-26T09:40:30.705854Z","steps":["trace[1801989826] 'agreement among raft nodes before linearized reading' (duration: 173.198217ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:40:30.706121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.084885ms","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-26T09:40:30.706158Z","caller":"traceutil/trace.go:171","msg":"trace[741328684] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1059; }","duration":"173.149627ms","start":"2026-03-26T09:40:30.533Z","end":"2026-03-26T09:40:30.706149Z","steps":["trace[741328684] 'agreement among raft nodes before linearized reading' (duration: 173.051765ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:40:30.70616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.383161ms","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-26T09:40:30.706199Z","caller":"traceutil/trace.go:171","msg":"trace[633224625] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1059; }","duration":"173.434801ms","start":"2026-03-26T09:40:30.532749Z","end":"2026-03-26T09:40:30.706184Z","steps":["trace[633224625] 'agreement among raft nodes before linearized reading' (duration: 173.233687ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:40:30.944108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.256351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:40:30.944236Z","caller":"traceutil/trace.go:171","msg":"trace[788434312] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1062; }","duration":"133.421054ms","start":"2026-03-26T09:40:30.810792Z","end":"2026-03-26T09:40:30.944213Z","steps":["trace[788434312] 'range keys from in-memory index tree' (duration: 133.130088ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:40:33.024267Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085743586161164097,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-26T09:40:33.399081Z","caller":"traceutil/trace.go:171","msg":"trace[1788563935] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"936.214731ms","start":"2026-03-26T09:40:32.462832Z","end":"2026-03-26T09:40:33.399046Z","steps":["trace[1788563935] 'process raft request' (duration: 936.021347ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:40:33.399283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:40:32.462805Z","time spent":"936.379675ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:40:33.649441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.020036ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-03-26T09:40:33.649756Z","caller":"traceutil/trace.go:171","msg":"trace[124481841] linearizableReadLoop","detail":"{readStateIndex:1107; appliedIndex:1104; }","duration":"1.126058722s","start":"2026-03-26T09:40:32.523677Z","end":"2026-03-26T09:40:33.649736Z","steps":["trace[124481841] 'read index received' (duration: 875.250106ms)","trace[124481841] 'applied index is now lower than readState.Index' (duration: 250.807066ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:40:33.64991Z","caller":"traceutil/trace.go:171","msg":"trace[394896050] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"743.27695ms","start":"2026-03-26T09:40:32.906596Z","end":"2026-03-26T09:40:33.649873Z","steps":["trace[394896050] 'process raft request' (duration: 742.956934ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:40:33.649988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"939.621187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-26T09:40:33.650028Z","caller":"traceutil/trace.go:171","msg":"trace[1087122221] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1064; }","duration":"939.707359ms","start":"2026-03-26T09:40:32.71031Z","end":"2026-03-26T09:40:33.650017Z","steps":["trace[1087122221] 'agreement among raft nodes before linearized reading' (duration: 939.586287ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:40:33.650054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:40:32.710286Z","time spent":"939.763039ms","remote":"127.0.0.1:42700","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-26T09:40:33.650031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:40:32.906543Z","time spent":"743.407203ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:40:33.650189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.09053ms","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-26T09:40:33.65031Z","caller":"traceutil/trace.go:171","msg":"trace[1241488834] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1064; }","duration":"861.232683ms","start":"2026-03-26T09:40:32.78905Z","end":"2026-03-26T09:40:33.650282Z","steps":["trace[1241488834] 'agreement among raft nodes before linearized reading' (duration: 860.897047ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:40:33.65034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.113383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-26T09:40:33.650422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.703922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-26T09:40:33.650421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:40:32.78902Z","time spent":"861.370136ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-26T09:40:33.65044Z","caller":"traceutil/trace.go:171","msg":"trace[757407547] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1064; }","duration":"839.277796ms","start":"2026-03-26T09:40:32.811138Z","end":"2026-03-26T09:40:33.650416Z","steps":["trace[757407547] 'agreement among raft nodes before linearized reading' (duration: 839.078483ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:40:33.65052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:40:32.810999Z","time spent":"839.511191ms","remote":"127.0.0.1:42440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-26T09:40:33.650679Z","caller":"traceutil/trace.go:171","msg":"trace[2145125581] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1064; }","duration":"861.840604ms","start":"2026-03-26T09:40:32.788622Z","end":"2026-03-26T09:40:33.650462Z","steps":["trace[2145125581] 'agreement among raft nodes before linearized reading' (duration: 861.64418ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:40:33.65071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.01715ms","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-26T09:40:33.650745Z","caller":"traceutil/trace.go:171","msg":"trace[925165767] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1064; }","duration":"913.090741ms","start":"2026-03-26T09:40:32.737641Z","end":"2026-03-26T09:40:33.650732Z","steps":["trace[925165767] 'agreement among raft nodes before linearized reading' (duration: 912.986059ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:40:33.650782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:40:32.737619Z","time spent":"913.156042ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-26T09:40:33.650724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:40:32.788597Z","time spent":"862.116981ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-26T09:40:33.651053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.127367537s","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-26T09:40:33.651109Z","caller":"traceutil/trace.go:171","msg":"trace[1471440324] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1064; }","duration":"1.127422698s","start":"2026-03-26T09:40:32.523671Z","end":"2026-03-26T09:40:33.651093Z","steps":["trace[1471440324] 'agreement among raft nodes before linearized reading' (duration: 1.12650181s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:40:33.651162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:40:32.523625Z","time spent":"1.12752922s","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-26T09:40:33.998449Z","caller":"traceutil/trace.go:171","msg":"trace[159355342] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"343.949387ms","start":"2026-03-26T09:40:33.654463Z","end":"2026-03-26T09:40:33.998413Z","steps":["trace[159355342] 'process raft request' (duration: 271.009089ms)","trace[159355342] 'compare' (duration: 72.448339ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:40:33.998845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:40:33.654427Z","time spent":"344.281374ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:40:34.162892Z","caller":"traceutil/trace.go:171","msg":"trace[886356831] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"507.028067ms","start":"2026-03-26T09:40:33.655815Z","end":"2026-03-26T09:40:34.162843Z","steps":["trace[886356831] 'process raft request' (duration: 449.252944ms)","trace[886356831] 'compare' (duration: 57.493488ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:40:34.162962Z","caller":"traceutil/trace.go:171","msg":"trace[948130101] linearizableReadLoop","detail":"{readStateIndex:1112; appliedIndex:1107; }","duration":"481.193124ms","start":"2026-03-26T09:40:33.681746Z","end":"2026-03-26T09:40:34.162939Z","steps":["trace[948130101] 'read index received' (duration: 243.746168ms)","trace[948130101] 'applied index is now lower than readState.Index' (duration: 237.445566ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:40:34.162953Z","caller":"traceutil/trace.go:171","msg":"trace[1127647686] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"507.093658ms","start":"2026-03-26T09:40:33.655828Z","end":"2026-03-26T09:40:34.162922Z","steps":["trace[1127647686] 'process raft request' (duration: 506.924775ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:40:34.1631Z","caller":"traceutil/trace.go:171","msg":"trace[891474520] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"506.960836ms","start":"2026-03-26T09:40:33.656124Z","end":"2026-03-26T09:40:34.163084Z","steps":["trace[891474520] 'process raft request' (duration: 506.69324ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:40:34.163138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:40:33.655793Z","time spent":"507.211121ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:40:34.163192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:40:33.656113Z","time spent":"507.030397ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:40:34.163337Z","caller":"traceutil/trace.go:171","msg":"trace[1085372526] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"506.15076ms","start":"2026-03-26T09:40:33.657172Z","end":"2026-03-26T09:40:34.163322Z","steps":["trace[1085372526] 'process raft request' (duration: 505.700091ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:40:34.163345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:40:33.655811Z","time spent":"507.213872ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:40:34.163416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:40:33.657146Z","time spent":"506.225372ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:40:34.163488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.211399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:40:34.163544Z","caller":"traceutil/trace.go:171","msg":"trace[1013161671] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1069; }","duration":"260.323671ms","start":"2026-03-26T09:40:33.903203Z","end":"2026-03-26T09:40:34.163526Z","steps":["trace[1013161671] 'agreement among raft nodes before linearized reading' (duration: 260.135088ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:40:34.163539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.758686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-26T09:40:34.163611Z","caller":"traceutil/trace.go:171","msg":"trace[1234394530] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1069; }","duration":"481.905848ms","start":"2026-03-26T09:40:33.681687Z","end":"2026-03-26T09:40:34.163593Z","steps":["trace[1234394530] 'agreement among raft nodes before linearized reading' (duration: 481.713065ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:40:34.163656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:40:33.681663Z","time spent":"481.984479ms","remote":"127.0.0.1:42800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":29,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"info","ts":"2026-03-26T09:40:36.218196Z","caller":"traceutil/trace.go:171","msg":"trace[2143510161] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"209.733726ms","start":"2026-03-26T09:40:36.00842Z","end":"2026-03-26T09:40:36.218154Z","steps":["trace[2143510161] 'process raft request' (duration: 209.451271ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:40:36.331885Z","caller":"traceutil/trace.go:171","msg":"trace[589033456] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"108.108152ms","start":"2026-03-26T09:40:36.223734Z","end":"2026-03-26T09:40:36.331842Z","steps":["trace[589033456] 'process raft request' (duration: 69.074893ms)","trace[589033456] 'compare' (duration: 38.848314ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:40:36.704252Z","caller":"traceutil/trace.go:171","msg":"trace[1180145485] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"480.38896ms","start":"2026-03-26T09:40:36.22384Z","end":"2026-03-26T09:40:36.704229Z","steps":["trace[1180145485] 'process raft request' (duration: 385.571706ms)","trace[1180145485] 'compare' (duration: 94.535957ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:40:36.704832Z","caller":"traceutil/trace.go:171","msg":"trace[1696100737] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"480.785846ms","start":"2026-03-26T09:40:36.224001Z","end":"2026-03-26T09:40:36.704787Z","steps":["trace[1696100737] 'process raft request' (duration: 480.161603ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:40:36.705051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:40:36.223795Z","time spent":"480.513892ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:40:36.705052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:40:36.22392Z","time spent":"481.002372ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:40:36.705124Z","caller":"traceutil/trace.go:171","msg":"trace[907192647] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"481.073122ms","start":"2026-03-26T09:40:36.224015Z","end":"2026-03-26T09:40:36.705088Z","steps":["trace[907192647] 'process raft request' (duration: 480.45079ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:40:36.705489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:40:36.224001Z","time spent":"481.222454ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:40:36.969869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.498151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:40:36.969982Z","caller":"traceutil/trace.go:171","msg":"trace[1105731269] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1075; }","duration":"159.697165ms","start":"2026-03-26T09:40:36.810258Z","end":"2026-03-26T09:40:36.969956Z","steps":["trace[1105731269] 'range keys from in-memory index tree' (duration: 159.276016ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:40:48.595084Z","caller":"traceutil/trace.go:171","msg":"trace[44756117] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"200.504487ms","start":"2026-03-26T09:40:48.394554Z","end":"2026-03-26T09:40:48.595058Z","steps":["trace[44756117] 'process raft request' (duration: 114.73603ms)","trace[44756117] 'compare' (duration: 85.685555ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:40:51.101295Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085743586161164330,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-26T09:40:51.435749Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.095419105s","expected-duration":"1s"} {"level":"info","ts":"2026-03-26T09:40:51.436162Z","caller":"traceutil/trace.go:171","msg":"trace[1130635332] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"1.095913445s","start":"2026-03-26T09:40:50.34022Z","end":"2026-03-26T09:40:51.436133Z","steps":["trace[1130635332] 'process raft request' (duration: 1.095710711s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:40:51.436384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:40:50.340195Z","time spent":"1.096059907s","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:40:52.367333Z","caller":"traceutil/trace.go:171","msg":"trace[2111708184] linearizableReadLoop","detail":"{readStateIndex:1217; appliedIndex:1215; }","duration":"1.766322178s","start":"2026-03-26T09:40:50.60097Z","end":"2026-03-26T09:40:52.367292Z","steps":["trace[2111708184] 'read index received' (duration: 834.961312ms)","trace[2111708184] 'applied index is now lower than readState.Index' (duration: 931.359396ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:40:52.367426Z","caller":"traceutil/trace.go:171","msg":"trace[584533967] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"1.758051918s","start":"2026-03-26T09:40:50.609325Z","end":"2026-03-26T09:40:52.367376Z","steps":["trace[584533967] 'process raft request' (duration: 1.724343312s)","trace[584533967] 'compare' (duration: 32.935221ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:40:52.367596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.766613084s","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-26T09:40:52.367641Z","caller":"traceutil/trace.go:171","msg":"trace[1007442638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1169; }","duration":"1.766716986s","start":"2026-03-26T09:40:50.600908Z","end":"2026-03-26T09:40:52.367625Z","steps":["trace[1007442638] 'agreement among raft nodes before linearized reading' (duration: 1.766525362s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:40:52.367652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:40:50.609289Z","time spent":"1.758241972s","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:40:52.367697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:40:50.600883Z","time spent":"1.766802379s","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-26T09:40:52.384439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.573006811s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:40:52.384443Z","caller":"traceutil/trace.go:171","msg":"trace[106897783] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"887.571655ms","start":"2026-03-26T09:40:51.496832Z","end":"2026-03-26T09:40:52.384404Z","steps":["trace[106897783] 'process raft request' (duration: 887.351829ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:40:52.384523Z","caller":"traceutil/trace.go:171","msg":"trace[1180137561] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1170; }","duration":"1.573192034s","start":"2026-03-26T09:40:50.811314Z","end":"2026-03-26T09:40:52.384506Z","steps":["trace[1180137561] 'agreement among raft nodes before linearized reading' (duration: 1.572998631s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:40:52.384565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:40:50.811295Z","time spent":"1.573261815s","remote":"127.0.0.1:42440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-26T09:40:52.384641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:40:51.496793Z","time spent":"887.738588ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:40:52.384626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.009302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:40:52.384737Z","caller":"traceutil/trace.go:171","msg":"trace[1440679167] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1170; }","duration":"962.171225ms","start":"2026-03-26T09:40:51.42253Z","end":"2026-03-26T09:40:52.384702Z","steps":["trace[1440679167] 'agreement among raft nodes before linearized reading' (duration: 961.979581ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:40:52.384802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:40:51.422507Z","time spent":"962.283767ms","remote":"127.0.0.1:44564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-03-26T09:40:52.384923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.517498572s","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-26T09:40:52.385033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.514777378s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-26T09:40:52.384929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.511173358s","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-26T09:40:52.385087Z","caller":"traceutil/trace.go:171","msg":"trace[1397036245] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1170; }","duration":"1.51485482s","start":"2026-03-26T09:40:50.87022Z","end":"2026-03-26T09:40:52.385075Z","steps":["trace[1397036245] 'agreement among raft nodes before linearized reading' (duration: 1.514690777s)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:40:52.385026Z","caller":"traceutil/trace.go:171","msg":"trace[544623430] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1170; }","duration":"1.517697105s","start":"2026-03-26T09:40:50.867306Z","end":"2026-03-26T09:40:52.385003Z","steps":["trace[544623430] 'agreement among raft nodes before linearized reading' (duration: 1.517412349s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:40:52.385125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:40:50.870209Z","time spent":"1.514909471s","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-26T09:40:52.385126Z","caller":"traceutil/trace.go:171","msg":"trace[1595621785] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1170; }","duration":"1.511400413s","start":"2026-03-26T09:40:50.873711Z","end":"2026-03-26T09:40:52.385112Z","steps":["trace[1595621785] 'agreement among raft nodes before linearized reading' (duration: 1.510651149s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:40:52.385162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:40:50.867278Z","time spent":"1.517866648s","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-26T09:40:52.385177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:40:50.873697Z","time spent":"1.511465844s","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-26T09:41:07.72425Z","caller":"traceutil/trace.go:171","msg":"trace[1798776182] linearizableReadLoop","detail":"{readStateIndex:1281; appliedIndex:1280; }","duration":"124.528811ms","start":"2026-03-26T09:41:07.59969Z","end":"2026-03-26T09:41:07.724219Z","steps":["trace[1798776182] 'read index received' (duration: 46.560325ms)","trace[1798776182] 'applied index is now lower than readState.Index' (duration: 77.967116ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:41:07.724296Z","caller":"traceutil/trace.go:171","msg":"trace[835967114] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"161.906808ms","start":"2026-03-26T09:41:07.562359Z","end":"2026-03-26T09:41:07.724266Z","steps":["trace[835967114] 'process raft request' (duration: 83.879261ms)","trace[835967114] 'compare' (duration: 77.822302ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:41:07.724566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.842907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-26T09:41:07.724643Z","caller":"traceutil/trace.go:171","msg":"trace[1370878803] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1229; }","duration":"124.97671ms","start":"2026-03-26T09:41:07.599645Z","end":"2026-03-26T09:41:07.724621Z","steps":["trace[1370878803] 'agreement among raft nodes before linearized reading' (duration: 124.662514ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:41:08.909843Z","caller":"traceutil/trace.go:171","msg":"trace[352639215] linearizableReadLoop","detail":"{readStateIndex:1283; appliedIndex:1282; }","duration":"443.307488ms","start":"2026-03-26T09:41:08.466505Z","end":"2026-03-26T09:41:08.909813Z","steps":["trace[352639215] 'read index received' (duration: 443.124854ms)","trace[352639215] 'applied index is now lower than readState.Index' (duration: 180.754µs)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:41:08.910233Z","caller":"traceutil/trace.go:171","msg":"trace[491817606] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"455.801772ms","start":"2026-03-26T09:41:08.454415Z","end":"2026-03-26T09:41:08.910217Z","steps":["trace[491817606] 'process raft request' (duration: 455.236641ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:08.910358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:08.454392Z","time spent":"455.885553ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:41:08.910635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.133945ms","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-26T09:41:08.910676Z","caller":"traceutil/trace.go:171","msg":"trace[2073443662] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1231; }","duration":"444.214016ms","start":"2026-03-26T09:41:08.466452Z","end":"2026-03-26T09:41:08.910666Z","steps":["trace[2073443662] 'agreement among raft nodes before linearized reading' (duration: 444.086293ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:08.910707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:08.466428Z","time spent":"444.270507ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-26T09:41:08.910905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.839672ms","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-26T09:41:08.910941Z","caller":"traceutil/trace.go:171","msg":"trace[1046687148] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1231; }","duration":"402.898474ms","start":"2026-03-26T09:41:08.508034Z","end":"2026-03-26T09:41:08.910933Z","steps":["trace[1046687148] 'agreement among raft nodes before linearized reading' (duration: 402.808892ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:08.910968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:08.50802Z","time spent":"402.941714ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-26T09:41:08.911189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.716807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:41:08.911226Z","caller":"traceutil/trace.go:171","msg":"trace[550379988] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1231; }","duration":"101.791749ms","start":"2026-03-26T09:41:08.809423Z","end":"2026-03-26T09:41:08.911215Z","steps":["trace[550379988] 'agreement among raft nodes before linearized reading' (duration: 101.739808ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:08.911412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.06199ms","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-26T09:41:08.911446Z","caller":"traceutil/trace.go:171","msg":"trace[1914354647] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1231; }","duration":"377.117051ms","start":"2026-03-26T09:41:08.53432Z","end":"2026-03-26T09:41:08.911437Z","steps":["trace[1914354647] 'agreement among raft nodes before linearized reading' (duration: 377.027899ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:08.911472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:08.534309Z","time spent":"377.155192ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-26T09:41:08.911645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.770024ms","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-26T09:41:08.911679Z","caller":"traceutil/trace.go:171","msg":"trace[1096429594] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1231; }","duration":"378.825185ms","start":"2026-03-26T09:41:08.532846Z","end":"2026-03-26T09:41:08.911671Z","steps":["trace[1096429594] 'agreement among raft nodes before linearized reading' (duration: 378.743044ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:08.911705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:08.532835Z","time spent":"378.864676ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-26T09:41:09.649123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.221446ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T09:41:09.649282Z","caller":"traceutil/trace.go:171","msg":"trace[1402717474] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"729.468752ms","start":"2026-03-26T09:41:08.919783Z","end":"2026-03-26T09:41:09.649252Z","steps":["trace[1402717474] 'process raft request' (duration: 284.983931ms)","trace[1402717474] 'compare' (duration: 443.744567ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:41:09.649357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:08.919761Z","time spent":"729.564703ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:41:10.278977Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085743586161164492,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-26T09:41:10.299652Z","caller":"traceutil/trace.go:171","msg":"trace[1607265405] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"1.378382027s","start":"2026-03-26T09:41:08.921243Z","end":"2026-03-26T09:41:10.299625Z","steps":["trace[1607265405] 'process raft request' (duration: 1.378296385s)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:41:10.299697Z","caller":"traceutil/trace.go:171","msg":"trace[361818337] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"1.379424387s","start":"2026-03-26T09:41:08.920209Z","end":"2026-03-26T09:41:10.299634Z","steps":["trace[361818337] 'process raft request' (duration: 1.379146762s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:10.299807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:08.921207Z","time spent":"1.3785261s","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:41:10.299889Z","caller":"traceutil/trace.go:171","msg":"trace[1569558475] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"1.377088672s","start":"2026-03-26T09:41:08.922783Z","end":"2026-03-26T09:41:10.299872Z","steps":["trace[1569558475] 'process raft request' (duration: 1.376806436s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:10.299894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:08.920179Z","time spent":"1.37960037s","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:41:10.300005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:08.922766Z","time spent":"1.377171553s","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:41:10.30022Z","caller":"traceutil/trace.go:171","msg":"trace[1945312331] linearizableReadLoop","detail":"{readStateIndex:1287; appliedIndex:1284; }","duration":"521.303794ms","start":"2026-03-26T09:41:09.778513Z","end":"2026-03-26T09:41:10.299817Z","steps":["trace[1945312331] 'read index received' (duration: 520.839256ms)","trace[1945312331] 'applied index is now lower than readState.Index' (duration: 462.828µs)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:41:10.300267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.734192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-26T09:41:10.300331Z","caller":"traceutil/trace.go:171","msg":"trace[1331106675] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1235; }","duration":"521.875435ms","start":"2026-03-26T09:41:09.778437Z","end":"2026-03-26T09:41:10.300313Z","steps":["trace[1331106675] 'agreement among raft nodes before linearized reading' (duration: 521.61625ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:10.300383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:09.778408Z","time spent":"521.967297ms","remote":"127.0.0.1:42524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":109,"response size":29,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-03-26T09:41:10.300574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.0638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-26T09:41:10.300635Z","caller":"traceutil/trace.go:171","msg":"trace[2022646310] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1235; }","duration":"486.164923ms","start":"2026-03-26T09:41:09.814455Z","end":"2026-03-26T09:41:10.30062Z","steps":["trace[2022646310] 'agreement among raft nodes before linearized reading' (duration: 485.879016ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:10.300695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:09.814435Z","time spent":"486.242754ms","remote":"127.0.0.1:42700","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-26T09:41:10.546078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.312514ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T09:41:10.546212Z","caller":"traceutil/trace.go:171","msg":"trace[410964015] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"241.434303ms","start":"2026-03-26T09:41:10.304757Z","end":"2026-03-26T09:41:10.546191Z","steps":["trace[410964015] 'process raft request' (duration: 111.928966ms)","trace[410964015] 'compare' (duration: 129.198352ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:41:11.116309Z","caller":"traceutil/trace.go:171","msg":"trace[1937189102] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"194.794477ms","start":"2026-03-26T09:41:10.921489Z","end":"2026-03-26T09:41:11.116284Z","steps":["trace[1937189102] 'process raft request' (duration: 194.625834ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:41:12.02587Z","caller":"traceutil/trace.go:171","msg":"trace[499968884] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"368.686017ms","start":"2026-03-26T09:41:11.657155Z","end":"2026-03-26T09:41:12.025841Z","steps":["trace[499968884] 'process raft request' (duration: 368.509344ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:12.026038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:11.657131Z","time spent":"368.824421ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:41:13.203657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.811498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:41:13.203753Z","caller":"traceutil/trace.go:171","msg":"trace[896022101] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1239; }","duration":"335.952191ms","start":"2026-03-26T09:41:12.867777Z","end":"2026-03-26T09:41:13.203729Z","steps":["trace[896022101] 'count revisions from in-memory index tree' (duration: 335.714987ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:13.203807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:12.867754Z","time spent":"336.036643ms","remote":"127.0.0.1:36376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-03-26T09:41:13.203832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"898.569259ms","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-26T09:41:13.203852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.639382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:41:13.203914Z","caller":"traceutil/trace.go:171","msg":"trace[1433166698] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1239; }","duration":"898.730802ms","start":"2026-03-26T09:41:12.305163Z","end":"2026-03-26T09:41:13.203894Z","steps":["trace[1433166698] 'range keys from in-memory index tree' (duration: 898.362735ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:41:13.203916Z","caller":"traceutil/trace.go:171","msg":"trace[605432292] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1239; }","duration":"394.740794ms","start":"2026-03-26T09:41:12.809158Z","end":"2026-03-26T09:41:13.203899Z","steps":["trace[605432292] 'range keys from in-memory index tree' (duration: 394.554551ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:13.203961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:12.809138Z","time spent":"394.815026ms","remote":"127.0.0.1:42440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-26T09:41:13.203961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:12.305099Z","time spent":"898.852755ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-26T09:41:13.204061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"898.747233ms","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-26T09:41:13.204111Z","caller":"traceutil/trace.go:171","msg":"trace[1789441601] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1239; }","duration":"898.832334ms","start":"2026-03-26T09:41:12.305264Z","end":"2026-03-26T09:41:13.204097Z","steps":["trace[1789441601] 'range keys from in-memory index tree' (duration: 898.60621ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:13.204092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.534654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-26T09:41:13.204159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:12.305242Z","time spent":"898.908866ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-26T09:41:13.204191Z","caller":"traceutil/trace.go:171","msg":"trace[55907929] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1239; }","duration":"654.677777ms","start":"2026-03-26T09:41:12.549487Z","end":"2026-03-26T09:41:13.204164Z","steps":["trace[55907929] 'range keys from in-memory index tree' (duration: 654.32653ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:13.204253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:12.54946Z","time spent":"654.773278ms","remote":"127.0.0.1:42700","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-26T09:41:13.205719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"898.13356ms","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-26T09:41:13.205788Z","caller":"traceutil/trace.go:171","msg":"trace[356167209] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1239; }","duration":"900.353804ms","start":"2026-03-26T09:41:12.305416Z","end":"2026-03-26T09:41:13.20577Z","steps":["trace[356167209] 'range keys from in-memory index tree' (duration: 897.929366ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:13.205833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:12.305217Z","time spent":"900.599749ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-26T09:41:13.269858Z","caller":"traceutil/trace.go:171","msg":"trace[1980991242] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"395.338845ms","start":"2026-03-26T09:41:12.874497Z","end":"2026-03-26T09:41:13.269836Z","steps":["trace[1980991242] 'process raft request' (duration: 395.205652ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:13.270015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:12.874479Z","time spent":"395.454678ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:41:13.652521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.651907ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-03-26T09:41:13.652811Z","caller":"traceutil/trace.go:171","msg":"trace[1244496685] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"444.067913ms","start":"2026-03-26T09:41:13.208723Z","end":"2026-03-26T09:41:13.652791Z","steps":["trace[1244496685] 'process raft request' (duration: 444.000322ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:41:13.652818Z","caller":"traceutil/trace.go:171","msg":"trace[163997827] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"530.705467ms","start":"2026-03-26T09:41:13.122082Z","end":"2026-03-26T09:41:13.652787Z","steps":["trace[163997827] 'process raft request' (duration: 530.518654ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:13.652924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:13.208703Z","time spent":"444.158065ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:41:13.652948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:13.122054Z","time spent":"530.82525ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:41:13.653097Z","caller":"traceutil/trace.go:171","msg":"trace[662334436] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"444.454561ms","start":"2026-03-26T09:41:13.208629Z","end":"2026-03-26T09:41:13.653084Z","steps":["trace[662334436] 'process raft request' (duration: 444.062094ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:13.653176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:13.208614Z","time spent":"444.527022ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:41:13.653383Z","caller":"traceutil/trace.go:171","msg":"trace[467484886] linearizableReadLoop","detail":"{readStateIndex:1294; appliedIndex:1292; }","duration":"448.221084ms","start":"2026-03-26T09:41:13.205149Z","end":"2026-03-26T09:41:13.653371Z","steps":["trace[467484886] 'read index received' (duration: 64.742699ms)","trace[467484886] 'applied index is now lower than readState.Index' (duration: 383.477585ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:41:13.653488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.331386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:41:13.65353Z","caller":"traceutil/trace.go:171","msg":"trace[888062995] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1245; }","duration":"448.399277ms","start":"2026-03-26T09:41:13.20512Z","end":"2026-03-26T09:41:13.65352Z","steps":["trace[888062995] 'agreement among raft nodes before linearized reading' (duration: 448.311515ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:13.653565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:13.205104Z","time spent":"448.453279ms","remote":"127.0.0.1:42452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-26T09:41:13.653707Z","caller":"traceutil/trace.go:171","msg":"trace[1679157114] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"443.591853ms","start":"2026-03-26T09:41:13.210105Z","end":"2026-03-26T09:41:13.653697Z","steps":["trace[1679157114] 'process raft request' (duration: 442.87933ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:13.653772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:13.210098Z","time spent":"443.637565ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:41:13.65308Z","caller":"traceutil/trace.go:171","msg":"trace[1556909306] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"443.358739ms","start":"2026-03-26T09:41:13.209702Z","end":"2026-03-26T09:41:13.65306Z","steps":["trace[1556909306] 'process raft request' (duration: 443.056284ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:13.654098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:13.209692Z","time spent":"444.372ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:41:14.291729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.912602ms","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-26T09:41:14.291815Z","caller":"traceutil/trace.go:171","msg":"trace[641012828] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1245; }","duration":"261.039775ms","start":"2026-03-26T09:41:14.030755Z","end":"2026-03-26T09:41:14.291794Z","steps":["trace[641012828] 'range keys from in-memory index tree' (duration: 260.761649ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:14.291811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.052113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:41:14.291888Z","caller":"traceutil/trace.go:171","msg":"trace[630526155] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1245; }","duration":"479.171835ms","start":"2026-03-26T09:41:13.812696Z","end":"2026-03-26T09:41:14.291868Z","steps":["trace[630526155] 'range keys from in-memory index tree' (duration: 478.976361ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:14.291936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:13.812678Z","time spent":"479.249406ms","remote":"127.0.0.1:42440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-26T09:41:15.940941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.369583186s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T09:41:15.941111Z","caller":"traceutil/trace.go:171","msg":"trace[1716307179] linearizableReadLoop","detail":"{readStateIndex:1300; appliedIndex:1298; }","duration":"1.614083469s","start":"2026-03-26T09:41:14.326998Z","end":"2026-03-26T09:41:15.941082Z","steps":["trace[1716307179] 'read index received' (duration: 30.939501ms)","trace[1716307179] 'applied index is now lower than readState.Index' (duration: 1.583141818s)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:41:15.941372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.614340294s","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-26T09:41:15.941471Z","caller":"traceutil/trace.go:171","msg":"trace[1706967574] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1247; }","duration":"1.614469417s","start":"2026-03-26T09:41:14.32697Z","end":"2026-03-26T09:41:15.941439Z","steps":["trace[1706967574] 'agreement among raft nodes before linearized reading' (duration: 1.614282563s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:15.941523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:14.326962Z","time spent":"1.614550318s","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":29,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"info","ts":"2026-03-26T09:41:15.941587Z","caller":"traceutil/trace.go:171","msg":"trace[448256001] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"1.619796331s","start":"2026-03-26T09:41:14.321469Z","end":"2026-03-26T09:41:15.941266Z","steps":["trace[448256001] 'process raft request' (duration: 249.748636ms)","trace[448256001] 'compare' (duration: 1.369390922s)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:41:15.941868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:14.32145Z","time spent":"1.620293061s","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:41:16.174748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.017669ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T09:41:16.17491Z","caller":"traceutil/trace.go:171","msg":"trace[1465534396] linearizableReadLoop","detail":"{readStateIndex:1301; appliedIndex:1300; }","duration":"233.691583ms","start":"2026-03-26T09:41:15.941158Z","end":"2026-03-26T09:41:16.17485Z","steps":["trace[1465534396] 'read index received' (duration: 123.523111ms)","trace[1465534396] 'applied index is now lower than readState.Index' (duration: 110.167102ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:41:16.17495Z","caller":"traceutil/trace.go:171","msg":"trace[249449456] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"514.602094ms","start":"2026-03-26T09:41:15.660302Z","end":"2026-03-26T09:41:16.174904Z","steps":["trace[249449456] 'process raft request' (duration: 404.33224ms)","trace[249449456] 'compare' (duration: 109.873686ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:41:16.175058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.038431ms","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-26T09:41:16.175092Z","caller":"traceutil/trace.go:171","msg":"trace[616651514] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1248; }","duration":"517.112863ms","start":"2026-03-26T09:41:15.657969Z","end":"2026-03-26T09:41:16.175082Z","steps":["trace[616651514] 'agreement among raft nodes before linearized reading' (duration: 516.97847ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:16.175133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:15.657948Z","time spent":"517.176914ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-26T09:41:16.175153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:15.660287Z","time spent":"514.773077ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:41:16.175296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.571957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:41:16.175322Z","caller":"traceutil/trace.go:171","msg":"trace[1537912504] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1248; }","duration":"365.634508ms","start":"2026-03-26T09:41:15.80968Z","end":"2026-03-26T09:41:16.175314Z","steps":["trace[1537912504] 'agreement among raft nodes before linearized reading' (duration: 365.592718ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:16.175347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:15.809648Z","time spent":"365.693319ms","remote":"127.0.0.1:42440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-26T09:41:16.175502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.082733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-26T09:41:16.175553Z","caller":"traceutil/trace.go:171","msg":"trace[1316956625] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1248; }","duration":"517.160855ms","start":"2026-03-26T09:41:15.658378Z","end":"2026-03-26T09:41:16.175539Z","steps":["trace[1316956625] 'agreement among raft nodes before linearized reading' (duration: 516.98419ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:16.17558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:15.65836Z","time spent":"517.216125ms","remote":"127.0.0.1:42700","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-26T09:41:16.175616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.516781ms","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-26T09:41:16.175648Z","caller":"traceutil/trace.go:171","msg":"trace[1425898260] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1248; }","duration":"517.548441ms","start":"2026-03-26T09:41:15.658091Z","end":"2026-03-26T09:41:16.175639Z","steps":["trace[1425898260] 'agreement among raft nodes before linearized reading' (duration: 517.45948ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:16.175679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:15.658027Z","time spent":"517.645193ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-26T09:41:16.175883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.183295ms","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-26T09:41:16.175915Z","caller":"traceutil/trace.go:171","msg":"trace[1476933958] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1248; }","duration":"517.233015ms","start":"2026-03-26T09:41:15.658673Z","end":"2026-03-26T09:41:16.175906Z","steps":["trace[1476933958] 'agreement among raft nodes before linearized reading' (duration: 517.096823ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:16.175938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:15.658667Z","time spent":"517.265666ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-26T09:41:16.863217Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085743586161164529,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-26T09:41:17.064511Z","caller":"traceutil/trace.go:171","msg":"trace[169913213] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"884.910464ms","start":"2026-03-26T09:41:16.179558Z","end":"2026-03-26T09:41:17.064469Z","steps":["trace[169913213] 'process raft request' (duration: 884.378893ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:17.064776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:16.179531Z","time spent":"885.107607ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:41:17.064857Z","caller":"traceutil/trace.go:171","msg":"trace[2092348133] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"885.22956ms","start":"2026-03-26T09:41:16.179575Z","end":"2026-03-26T09:41:17.064805Z","steps":["trace[2092348133] 'process raft request' (duration: 884.805271ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:17.06508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:16.179559Z","time spent":"885.389303ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:41:17.395959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.074009ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T09:41:17.39619Z","caller":"traceutil/trace.go:171","msg":"trace[728608283] linearizableReadLoop","detail":"{readStateIndex:1305; appliedIndex:1301; }","duration":"1.033708636s","start":"2026-03-26T09:41:16.362461Z","end":"2026-03-26T09:41:17.39617Z","steps":["trace[728608283] 'read index received' (duration: 701.466167ms)","trace[728608283] 'applied index is now lower than readState.Index' (duration: 332.241549ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:41:17.396183Z","caller":"traceutil/trace.go:171","msg":"trace[193448489] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"1.216566941s","start":"2026-03-26T09:41:16.17957Z","end":"2026-03-26T09:41:17.396137Z","steps":["trace[193448489] 'process raft request' (duration: 1.035233927s)","trace[193448489] 'compare' (duration: 180.821755ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:41:17.396427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:16.179551Z","time spent":"1.216721474s","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:41:17.396422Z","caller":"traceutil/trace.go:171","msg":"trace[1957662120] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"1.214107894s","start":"2026-03-26T09:41:16.182219Z","end":"2026-03-26T09:41:17.396327Z","steps":["trace[1957662120] 'process raft request' (duration: 1.213839058s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:17.396616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:16.182204Z","time spent":"1.214304657s","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:41:17.396714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.034254267s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-26T09:41:17.396764Z","caller":"traceutil/trace.go:171","msg":"trace[280980040] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1252; }","duration":"1.034345219s","start":"2026-03-26T09:41:16.362403Z","end":"2026-03-26T09:41:17.396748Z","steps":["trace[280980040] 'agreement among raft nodes before linearized reading' (duration: 1.033819118s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:17.396801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:16.362381Z","time spent":"1.03441225s","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-26T09:41:17.739002Z","caller":"traceutil/trace.go:171","msg":"trace[2015148643] linearizableReadLoop","detail":"{readStateIndex:1306; appliedIndex:1305; }","duration":"316.826559ms","start":"2026-03-26T09:41:17.422137Z","end":"2026-03-26T09:41:17.738964Z","steps":["trace[2015148643] 'read index received' (duration: 260.759049ms)","trace[2015148643] 'applied index is now lower than readState.Index' (duration: 56.06605ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:41:17.739163Z","caller":"traceutil/trace.go:171","msg":"trace[1115360694] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"337.834777ms","start":"2026-03-26T09:41:17.401218Z","end":"2026-03-26T09:41:17.739052Z","steps":["trace[1115360694] 'process raft request' (duration: 281.792218ms)","trace[1115360694] 'compare' (duration: 55.806015ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:41:17.739448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.362743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-03-26T09:41:17.739453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:17.401187Z","time spent":"338.119443ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:41:17.739499Z","caller":"traceutil/trace.go:171","msg":"trace[533934443] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:1; response_revision:1253; }","duration":"222.458055ms","start":"2026-03-26T09:41:17.517027Z","end":"2026-03-26T09:41:17.739485Z","steps":["trace[533934443] 'agreement among raft nodes before linearized reading' (duration: 222.266661ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:17.739255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.109534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:41:17.739745Z","caller":"traceutil/trace.go:171","msg":"trace[2087797368] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1253; }","duration":"317.657936ms","start":"2026-03-26T09:41:17.422067Z","end":"2026-03-26T09:41:17.739725Z","steps":["trace[2087797368] 'agreement among raft nodes before linearized reading' (duration: 317.123185ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:17.739797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:17.422042Z","time spent":"317.739128ms","remote":"127.0.0.1:42440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-26T09:41:25.928679Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085743586161164662,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-26T09:41:26.429849Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085743586161164662,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-26T09:41:26.457979Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.259240064s","expected-duration":"1s"} {"level":"info","ts":"2026-03-26T09:41:26.458312Z","caller":"traceutil/trace.go:171","msg":"trace[48171436] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"1.259696643s","start":"2026-03-26T09:41:25.198591Z","end":"2026-03-26T09:41:26.458288Z","steps":["trace[48171436] 'process raft request' (duration: 1.25956437s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:26.458454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:25.198564Z","time spent":"1.259814194s","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:41:26.471062Z","caller":"traceutil/trace.go:171","msg":"trace[199660509] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"245.155868ms","start":"2026-03-26T09:41:26.22588Z","end":"2026-03-26T09:41:26.471036Z","steps":["trace[199660509] 'process raft request' (duration: 245.056426ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:41:26.471203Z","caller":"traceutil/trace.go:171","msg":"trace[664082155] linearizableReadLoop","detail":"{readStateIndex:1385; appliedIndex:1381; }","duration":"1.043372707s","start":"2026-03-26T09:41:25.427811Z","end":"2026-03-26T09:41:26.471184Z","steps":["trace[664082155] 'read index received' (duration: 1.030425739s)","trace[664082155] 'applied index is now lower than readState.Index' (duration: 12.945388ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:41:26.471264Z","caller":"traceutil/trace.go:171","msg":"trace[1992441994] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"186.101294ms","start":"2026-03-26T09:41:26.285137Z","end":"2026-03-26T09:41:26.471238Z","steps":["trace[1992441994] 'process raft request' (duration: 185.833609ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:26.47137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.043554771s","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-26T09:41:26.471406Z","caller":"traceutil/trace.go:171","msg":"trace[173087906] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1330; }","duration":"1.043633212s","start":"2026-03-26T09:41:25.427765Z","end":"2026-03-26T09:41:26.471398Z","steps":["trace[173087906] 'agreement among raft nodes before linearized reading' (duration: 1.04351423s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:26.471431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:25.427742Z","time spent":"1.043683054s","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-26T09:41:26.4714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.039675126s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-26T09:41:26.471588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.028282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-03-26T09:41:26.471638Z","caller":"traceutil/trace.go:171","msg":"trace[2088535363] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1330; }","duration":"891.145094ms","start":"2026-03-26T09:41:25.580481Z","end":"2026-03-26T09:41:26.471626Z","steps":["trace[2088535363] 'agreement among raft nodes before linearized reading' (duration: 891.022381ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:26.471651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.236644ms","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-26T09:41:26.471551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.163614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-03-26T09:41:26.47168Z","caller":"traceutil/trace.go:171","msg":"trace[1960672638] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1330; }","duration":"692.299665ms","start":"2026-03-26T09:41:25.779371Z","end":"2026-03-26T09:41:26.47167Z","steps":["trace[1960672638] 'agreement among raft nodes before linearized reading' (duration: 692.230024ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:26.471678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:25.580457Z","time spent":"891.212925ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-03-26T09:41:26.471718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:25.779349Z","time spent":"692.358906ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-26T09:41:26.471738Z","caller":"traceutil/trace.go:171","msg":"trace[184038091] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1330; }","duration":"816.392729ms","start":"2026-03-26T09:41:25.655285Z","end":"2026-03-26T09:41:26.471678Z","steps":["trace[184038091] 'agreement among raft nodes before linearized reading' (duration: 816.028352ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:26.471448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.363325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-03-26T09:41:26.471833Z","caller":"traceutil/trace.go:171","msg":"trace[187898751] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1330; }","duration":"832.799983ms","start":"2026-03-26T09:41:25.639024Z","end":"2026-03-26T09:41:26.471824Z","steps":["trace[187898751] 'agreement among raft nodes before linearized reading' (duration: 832.396686ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:26.47188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:25.638991Z","time spent":"832.868814ms","remote":"127.0.0.1:42514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":140,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"info","ts":"2026-03-26T09:41:26.471875Z","caller":"traceutil/trace.go:171","msg":"trace[1978218030] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"1.270978159s","start":"2026-03-26T09:41:25.200108Z","end":"2026-03-26T09:41:26.471086Z","steps":["trace[1978218030] 'process raft request' (duration: 1.270710474s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:26.471974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:25.20009Z","time spent":"1.271824195s","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:41:26.471525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.479786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:88934"} {"level":"info","ts":"2026-03-26T09:41:26.472051Z","caller":"traceutil/trace.go:171","msg":"trace[1128831152] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1330; }","duration":"835.045077ms","start":"2026-03-26T09:41:25.636987Z","end":"2026-03-26T09:41:26.472032Z","steps":["trace[1128831152] 'agreement among raft nodes before linearized reading' (duration: 834.476836ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:26.47178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:25.655258Z","time spent":"816.511921ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-26T09:41:26.472091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:25.636959Z","time spent":"835.121499ms","remote":"127.0.0.1:42508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":88956,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-03-26T09:41:26.471581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.8563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T09:41:26.471966Z","caller":"traceutil/trace.go:171","msg":"trace[1906717511] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1330; }","duration":"1.039956561s","start":"2026-03-26T09:41:25.431659Z","end":"2026-03-26T09:41:26.471616Z","steps":["trace[1906717511] 'agreement among raft nodes before linearized reading' (duration: 1.039637805s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:26.472325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:25.431623Z","time spent":"1.040669207s","remote":"127.0.0.1:42700","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-26T09:41:26.47222Z","caller":"traceutil/trace.go:171","msg":"trace[2097586260] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1330; }","duration":"663.484872ms","start":"2026-03-26T09:41:25.808691Z","end":"2026-03-26T09:41:26.472176Z","steps":["trace[2097586260] 'agreement among raft nodes before linearized reading' (duration: 662.872649ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:26.472505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:25.808677Z","time spent":"663.810129ms","remote":"127.0.0.1:42440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-26T09:41:26.90199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.812588ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T09:41:26.902178Z","caller":"traceutil/trace.go:171","msg":"trace[1261554162] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"424.804949ms","start":"2026-03-26T09:41:26.477338Z","end":"2026-03-26T09:41:26.902142Z","steps":["trace[1261554162] 'process raft request' (duration: 60.691746ms)","trace[1261554162] 'compare' (duration: 363.619803ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T09:41:26.9023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:26.477315Z","time spent":"424.932901ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:41:26.904716Z","caller":"traceutil/trace.go:171","msg":"trace[1316210594] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"426.955699ms","start":"2026-03-26T09:41:26.477737Z","end":"2026-03-26T09:41:26.904693Z","steps":["trace[1316210594] 'process raft request' (duration: 426.719594ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:26.904895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:26.477729Z","time spent":"427.078812ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:41:26.905123Z","caller":"traceutil/trace.go:171","msg":"trace[1639426800] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"427.431979ms","start":"2026-03-26T09:41:26.477664Z","end":"2026-03-26T09:41:26.905096Z","steps":["trace[1639426800] 'process raft request' (duration: 426.636133ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T09:41:26.905241Z","caller":"traceutil/trace.go:171","msg":"trace[1348047011] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"426.910719ms","start":"2026-03-26T09:41:26.478321Z","end":"2026-03-26T09:41:26.905231Z","steps":["trace[1348047011] 'process raft request' (duration: 426.316568ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:26.905289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:26.478303Z","time spent":"426.96046ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T09:41:26.905288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:26.477647Z","time spent":"427.555021ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:41:26.905428Z","caller":"traceutil/trace.go:171","msg":"trace[769797335] linearizableReadLoop","detail":"{readStateIndex:1390; appliedIndex:1385; }","duration":"425.638565ms","start":"2026-03-26T09:41:26.479779Z","end":"2026-03-26T09:41:26.905418Z","steps":["trace[769797335] 'read index received' (duration: 58.329261ms)","trace[769797335] 'applied index is now lower than readState.Index' (duration: 367.308524ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T09:41:26.90548Z","caller":"traceutil/trace.go:171","msg":"trace[613712335] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"427.114413ms","start":"2026-03-26T09:41:26.478358Z","end":"2026-03-26T09:41:26.905472Z","steps":["trace[613712335] 'process raft request' (duration: 426.819567ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:26.905581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.797308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:88934"} {"level":"info","ts":"2026-03-26T09:41:26.905613Z","caller":"traceutil/trace.go:171","msg":"trace[1209034350] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1335; }","duration":"425.841558ms","start":"2026-03-26T09:41:26.479763Z","end":"2026-03-26T09:41:26.905605Z","steps":["trace[1209034350] 'agreement among raft nodes before linearized reading' (duration: 425.705166ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T09:41:26.905634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:26.479756Z","time spent":"425.873949ms","remote":"127.0.0.1:42508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":88956,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-03-26T09:41:26.905782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T09:41:26.478344Z","time spent":"427.204334ms","remote":"127.0.0.1:42700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T09:44:55.423957Z","caller":"traceutil/trace.go:171","msg":"trace[52546957] transaction","detail":"{read_only:false; response_revision:2318; number_of_response:1; }","duration":"158.960496ms","start":"2026-03-26T09:44:55.264966Z","end":"2026-03-26T09:44:55.423927Z","steps":["trace[52546957] 'process raft request' (duration: 158.721691ms)"],"step_count":1}