{"level":"warn","ts":"2026-04-17T05:54:13.562343Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-17T05:54:13.56243Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.223: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.223:2380","--initial-cluster=instance=https://199.204.45.223:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.223:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.223:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-17T05:54:13.562793Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-17T05:54:13.562811Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-04-17T05:54:13.562847Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-17T05:54:13.563757Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"]} {"level":"info","ts":"2026-04-17T05:54:13.56383Z","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.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.223:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-17T05:54:13.569736Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.904987ms"} {"level":"info","ts":"2026-04-17T05:54:13.580725Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"11f26225a5bb9fe3","cluster-id":"75c25101f01fd4c6"} {"level":"info","ts":"2026-04-17T05:54:13.580811Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=()"} {"level":"info","ts":"2026-04-17T05:54:13.580835Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 0"} {"level":"info","ts":"2026-04-17T05:54:13.580846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 11f26225a5bb9fe3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-17T05:54:13.580857Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 1"} {"level":"info","ts":"2026-04-17T05:54:13.580889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"warn","ts":"2026-04-17T05:54:13.593439Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-17T05:54:13.595182Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-17T05:54:13.597Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-17T05:54:13.600859Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"11f26225a5bb9fe3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-17T05:54:13.60113Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-17T05:54:13.601198Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-17T05:54:13.601215Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-17T05:54:13.601354Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"11f26225a5bb9fe3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-17T05:54:13.601912Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"info","ts":"2026-04-17T05:54:13.602061Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","added-peer-id":"11f26225a5bb9fe3","added-peer-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-04-17T05:54:13.608047Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-17T05:54:13.608196Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"11f26225a5bb9fe3","initial-advertise-peer-urls":["https://199.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-17T05:54:13.608222Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-17T05:54:13.608295Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-04-17T05:54:13.608308Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-04-17T05:54:13.782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 is starting a new election at term 1"} {"level":"info","ts":"2026-04-17T05:54:13.782051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-17T05:54:13.782078Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgPreVoteResp from 11f26225a5bb9fe3 at term 1"} {"level":"info","ts":"2026-04-17T05:54:13.782095Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became candidate at term 2"} {"level":"info","ts":"2026-04-17T05:54:13.782102Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgVoteResp from 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-04-17T05:54:13.782113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became leader at term 2"} {"level":"info","ts":"2026-04-17T05:54:13.782122Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 11f26225a5bb9fe3 elected leader 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-04-17T05:54:13.783127Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"11f26225a5bb9fe3","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.223:2379]}","request-path":"/0/members/11f26225a5bb9fe3/attributes","cluster-id":"75c25101f01fd4c6","publish-timeout":"7s"} {"level":"info","ts":"2026-04-17T05:54:13.783269Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T05:54:13.783317Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T05:54:13.783592Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T05:54:13.785359Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-17T05:54:13.785399Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-17T05:54:13.785588Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T05:54:13.785826Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T05:54:13.785934Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T05:54:13.787211Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.223:2379"} {"level":"info","ts":"2026-04-17T05:54:13.787658Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-17T05:54:39.502503Z","caller":"traceutil/trace.go:171","msg":"trace[1221433993] linearizableReadLoop","detail":"{readStateIndex:484; appliedIndex:483; }","duration":"227.901353ms","start":"2026-04-17T05:54:39.274555Z","end":"2026-04-17T05:54:39.502456Z","steps":["trace[1221433993] 'read index received' (duration: 227.804271ms)","trace[1221433993] 'applied index is now lower than readState.Index' (duration: 96.142µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:54:39.502707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.120798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-17T05:54:39.502799Z","caller":"traceutil/trace.go:171","msg":"trace[836269409] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:464; }","duration":"228.254341ms","start":"2026-04-17T05:54:39.274527Z","end":"2026-04-17T05:54:39.502782Z","steps":["trace[836269409] 'agreement among raft nodes before linearized reading' (duration: 228.067266ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:54:39.503056Z","caller":"traceutil/trace.go:171","msg":"trace[1747912714] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"268.905648ms","start":"2026-04-17T05:54:39.23413Z","end":"2026-04-17T05:54:39.503036Z","steps":["trace[1747912714] 'process raft request' (duration: 268.198811ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:54:39.55942Z","caller":"traceutil/trace.go:171","msg":"trace[530947076] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"191.435604ms","start":"2026-04-17T05:54:39.367959Z","end":"2026-04-17T05:54:39.559394Z","steps":["trace[530947076] 'process raft request' (duration: 191.286641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:54:39.928781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.914586ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T05:54:39.928876Z","caller":"traceutil/trace.go:171","msg":"trace[236886815] linearizableReadLoop","detail":"{readStateIndex:486; appliedIndex:485; }","duration":"236.699904ms","start":"2026-04-17T05:54:39.692161Z","end":"2026-04-17T05:54:39.928861Z","steps":["trace[236886815] 'read index received' (duration: 41.411µs)","trace[236886815] 'applied index is now lower than readState.Index' (duration: 236.657323ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:54:39.928981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.820317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T05:54:39.928968Z","caller":"traceutil/trace.go:171","msg":"trace[1896716505] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"420.863252ms","start":"2026-04-17T05:54:39.508078Z","end":"2026-04-17T05:54:39.928942Z","steps":["trace[1896716505] 'process raft request' (duration: 97.100935ms)","trace[1896716505] 'compare' (duration: 322.728952ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:54:39.929015Z","caller":"traceutil/trace.go:171","msg":"trace[308092017] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:466; }","duration":"236.922439ms","start":"2026-04-17T05:54:39.692084Z","end":"2026-04-17T05:54:39.929006Z","steps":["trace[308092017] 'agreement among raft nodes before linearized reading' (duration: 236.858117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:54:39.929086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:54:39.508046Z","time spent":"420.992146ms","remote":"127.0.0.1:47210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T05:54:39.929183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.721891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-17T05:54:39.929277Z","caller":"traceutil/trace.go:171","msg":"trace[779118859] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:466; }","duration":"181.876665ms","start":"2026-04-17T05:54:39.747384Z","end":"2026-04-17T05:54:39.929261Z","steps":["trace[779118859] 'agreement among raft nodes before linearized reading' (duration: 181.642629ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:54:43.752927Z","caller":"traceutil/trace.go:171","msg":"trace[1897239856] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"540.536152ms","start":"2026-04-17T05:54:43.21237Z","end":"2026-04-17T05:54:43.752906Z","steps":["trace[1897239856] 'process raft request' (duration: 540.415239ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:54:43.753121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:54:43.212343Z","time spent":"540.663174ms","remote":"127.0.0.1:46984","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-04-17T05:54:44.035456Z","caller":"traceutil/trace.go:171","msg":"trace[235031459] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"810.970467ms","start":"2026-04-17T05:54:43.224445Z","end":"2026-04-17T05:54:44.035415Z","steps":["trace[235031459] 'process raft request' (duration: 810.68281ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:54:44.035531Z","caller":"traceutil/trace.go:171","msg":"trace[2081913218] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"810.959067ms","start":"2026-04-17T05:54:43.224522Z","end":"2026-04-17T05:54:44.035481Z","steps":["trace[2081913218] 'process raft request' (duration: 810.759222ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:54:44.035581Z","caller":"traceutil/trace.go:171","msg":"trace[915834243] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"805.048384ms","start":"2026-04-17T05:54:43.230488Z","end":"2026-04-17T05:54:44.035537Z","steps":["trace[915834243] 'process raft request' (duration: 804.89411ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:54:44.035681Z","caller":"traceutil/trace.go:171","msg":"trace[740228247] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"805.228048ms","start":"2026-04-17T05:54:43.230436Z","end":"2026-04-17T05:54:44.035664Z","steps":["trace[740228247] 'process raft request' (duration: 804.90428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:54:44.035728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:54:43.224515Z","time spent":"811.12498ms","remote":"127.0.0.1:46984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T05:54:44.035776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:54:43.230427Z","time spent":"805.303561ms","remote":"127.0.0.1:46984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T05:54:44.035833Z","caller":"traceutil/trace.go:171","msg":"trace[1891708347] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"805.126016ms","start":"2026-04-17T05:54:43.230531Z","end":"2026-04-17T05:54:44.035657Z","steps":["trace[1891708347] 'process raft request' (duration: 804.901081ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:54:44.035896Z","caller":"traceutil/trace.go:171","msg":"trace[2127786052] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"793.433344ms","start":"2026-04-17T05:54:43.242443Z","end":"2026-04-17T05:54:44.035877Z","steps":["trace[2127786052] 'process raft request' (duration: 793.191578ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:54:44.036002Z","caller":"traceutil/trace.go:171","msg":"trace[851719758] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"791.608651ms","start":"2026-04-17T05:54:43.244379Z","end":"2026-04-17T05:54:44.035988Z","steps":["trace[851719758] 'process raft request' (duration: 791.337474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:54:44.036015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:54:43.242426Z","time spent":"793.552916ms","remote":"127.0.0.1:46984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T05:54:44.036034Z","caller":"traceutil/trace.go:171","msg":"trace[784816681] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"791.464027ms","start":"2026-04-17T05:54:43.244554Z","end":"2026-04-17T05:54:44.036018Z","steps":["trace[784816681] 'process raft request' (duration: 791.230262ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:54:44.035997Z","caller":"traceutil/trace.go:171","msg":"trace[296135805] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"804.122231ms","start":"2026-04-17T05:54:43.231856Z","end":"2026-04-17T05:54:44.035979Z","steps":["trace[296135805] 'process raft request' (duration: 803.62004ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:54:44.036064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:54:43.244363Z","time spent":"791.675623ms","remote":"127.0.0.1:46984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T05:54:44.035858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:54:43.230483Z","time spent":"805.208528ms","remote":"127.0.0.1:46984","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-04-17T05:54:44.036146Z","caller":"traceutil/trace.go:171","msg":"trace[1094563483] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"781.377944ms","start":"2026-04-17T05:54:43.254759Z","end":"2026-04-17T05:54:44.036137Z","steps":["trace[1094563483] 'process raft request' (duration: 781.098348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:54:44.03611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:54:43.244521Z","time spent":"791.55715ms","remote":"127.0.0.1:46984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T05:54:44.035633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:54:43.22442Z","time spent":"811.13164ms","remote":"127.0.0.1:46984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T05:54:44.036208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:54:43.254733Z","time spent":"781.441826ms","remote":"127.0.0.1:46984","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-04-17T05:54:44.036174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:54:43.231849Z","time spent":"804.241204ms","remote":"127.0.0.1:46984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T05:54:44.036083Z","caller":"traceutil/trace.go:171","msg":"trace[1660412955] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:527; }","duration":"520.754605ms","start":"2026-04-17T05:54:43.515308Z","end":"2026-04-17T05:54:44.036062Z","steps":["trace[1660412955] 'read index received' (duration: 237.535604ms)","trace[1660412955] 'applied index is now lower than readState.Index' (duration: 283.218011ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:54:44.036286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:54:43.230527Z","time spent":"805.422553ms","remote":"127.0.0.1:46984","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-04-17T05:54:44.036331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.024612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-17T05:54:44.036407Z","caller":"traceutil/trace.go:171","msg":"trace[1031187634] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:518; }","duration":"521.156025ms","start":"2026-04-17T05:54:43.515231Z","end":"2026-04-17T05:54:44.036387Z","steps":["trace[1031187634] 'agreement among raft nodes before linearized reading' (duration: 520.887019ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:54:44.036403Z","caller":"traceutil/trace.go:171","msg":"trace[812676413] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"655.607359ms","start":"2026-04-17T05:54:43.380706Z","end":"2026-04-17T05:54:44.036313Z","steps":["trace[812676413] 'process raft request' (duration: 655.275062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:54:44.036478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:54:43.515184Z","time spent":"521.282358ms","remote":"127.0.0.1:47210","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-04-17T05:54:44.036554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:54:43.380679Z","time spent":"655.835274ms","remote":"127.0.0.1:46984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T05:54:44.199854Z","caller":"traceutil/trace.go:171","msg":"trace[257546259] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"440.354551ms","start":"2026-04-17T05:54:43.759467Z","end":"2026-04-17T05:54:44.199822Z","steps":["trace[257546259] 'process raft request' (duration: 437.696817ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:54:44.199885Z","caller":"traceutil/trace.go:171","msg":"trace[1695712985] linearizableReadLoop","detail":"{readStateIndex:542; appliedIndex:539; }","duration":"163.589994ms","start":"2026-04-17T05:54:44.036275Z","end":"2026-04-17T05:54:44.199865Z","steps":["trace[1695712985] 'read index received' (duration: 160.866189ms)","trace[1695712985] 'applied index is now lower than readState.Index' (duration: 2.722395ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:54:44.200038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:54:43.759445Z","time spent":"440.519565ms","remote":"127.0.0.1:47524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T05:54:44.200083Z","caller":"traceutil/trace.go:171","msg":"trace[9630727] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"438.467326ms","start":"2026-04-17T05:54:43.761606Z","end":"2026-04-17T05:54:44.200073Z","steps":["trace[9630727] 'process raft request' (duration: 438.060246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:54:44.200084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.260861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T05:54:44.200036Z","caller":"traceutil/trace.go:171","msg":"trace[1466716473] transaction","detail":"{read_only:false; number_of_response:1; response_revision:520; }","duration":"438.211199ms","start":"2026-04-17T05:54:43.761798Z","end":"2026-04-17T05:54:44.200009Z","steps":["trace[1466716473] 'process raft request' (duration: 437.998384ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:54:44.200159Z","caller":"traceutil/trace.go:171","msg":"trace[755345396] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:520; }","duration":"507.395134ms","start":"2026-04-17T05:54:43.69275Z","end":"2026-04-17T05:54:44.200145Z","steps":["trace[755345396] 'agreement among raft nodes before linearized reading' (duration: 507.218249ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:54:44.200177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:54:43.761582Z","time spent":"438.536207ms","remote":"127.0.0.1:46984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7317,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T05:54:44.200342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.307519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-17T05:54:44.200294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:54:43.692731Z","time spent":"507.537549ms","remote":"127.0.0.1:46936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T05:54:44.200415Z","caller":"traceutil/trace.go:171","msg":"trace[85824222] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:520; }","duration":"257.407041ms","start":"2026-04-17T05:54:43.942996Z","end":"2026-04-17T05:54:44.200403Z","steps":["trace[85824222] 'agreement among raft nodes before linearized reading' (duration: 257.220905ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:54:44.200641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.541283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-17T05:54:44.200743Z","caller":"traceutil/trace.go:171","msg":"trace[1339705342] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:520; }","duration":"254.663935ms","start":"2026-04-17T05:54:43.946054Z","end":"2026-04-17T05:54:44.200718Z","steps":["trace[1339705342] 'agreement among raft nodes before linearized reading' (duration: 254.005209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:54:44.200922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:54:43.761777Z","time spent":"438.567869ms","remote":"127.0.0.1:46984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7365,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T05:54:44.206414Z","caller":"traceutil/trace.go:171","msg":"trace[1327165534] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"156.882684ms","start":"2026-04-17T05:54:44.049504Z","end":"2026-04-17T05:54:44.206387Z","steps":["trace[1327165534] 'process raft request' (duration: 156.74288ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:54:44.206529Z","caller":"traceutil/trace.go:171","msg":"trace[732936394] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"157.834557ms","start":"2026-04-17T05:54:44.048622Z","end":"2026-04-17T05:54:44.206457Z","steps":["trace[732936394] 'process raft request' (duration: 157.550431ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:54:44.206677Z","caller":"traceutil/trace.go:171","msg":"trace[521670839] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"157.233292ms","start":"2026-04-17T05:54:44.049425Z","end":"2026-04-17T05:54:44.206658Z","steps":["trace[521670839] 'process raft request' (duration: 156.787532ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:54:44.206244Z","caller":"traceutil/trace.go:171","msg":"trace[537243491] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"157.994851ms","start":"2026-04-17T05:54:44.048226Z","end":"2026-04-17T05:54:44.206221Z","steps":["trace[537243491] 'process raft request' (duration: 157.833657ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:54:44.206907Z","caller":"traceutil/trace.go:171","msg":"trace[1931577972] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"145.90471ms","start":"2026-04-17T05:54:44.06098Z","end":"2026-04-17T05:54:44.206885Z","steps":["trace[1931577972] 'process raft request' (duration: 145.468669ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:54:44.206981Z","caller":"traceutil/trace.go:171","msg":"trace[980568792] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"153.913632ms","start":"2026-04-17T05:54:44.053055Z","end":"2026-04-17T05:54:44.206969Z","steps":["trace[980568792] 'process raft request' (duration: 153.264237ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:54:44.207066Z","caller":"traceutil/trace.go:171","msg":"trace[1327636445] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"154.196158ms","start":"2026-04-17T05:54:44.052848Z","end":"2026-04-17T05:54:44.207044Z","steps":["trace[1327636445] 'process raft request' (duration: 153.43066ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:54:44.207316Z","caller":"traceutil/trace.go:171","msg":"trace[841186529] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"146.3791ms","start":"2026-04-17T05:54:44.060924Z","end":"2026-04-17T05:54:44.207303Z","steps":["trace[841186529] 'process raft request' (duration: 145.458989ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:54:44.207842Z","caller":"traceutil/trace.go:171","msg":"trace[627948005] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"146.700448ms","start":"2026-04-17T05:54:44.061131Z","end":"2026-04-17T05:54:44.207832Z","steps":["trace[627948005] 'process raft request' (duration: 145.691064ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:54:44.210254Z","caller":"traceutil/trace.go:171","msg":"trace[1374195084] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"100.927328ms","start":"2026-04-17T05:54:44.109304Z","end":"2026-04-17T05:54:44.210231Z","steps":["trace[1374195084] 'process raft request' (duration: 100.747564ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:54:45.193458Z","caller":"traceutil/trace.go:171","msg":"trace[146909158] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"262.141485ms","start":"2026-04-17T05:54:44.931295Z","end":"2026-04-17T05:54:45.193436Z","steps":["trace[146909158] 'process raft request' (duration: 261.999842ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:54:45.207996Z","caller":"traceutil/trace.go:171","msg":"trace[347716780] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"195.781359ms","start":"2026-04-17T05:54:45.012171Z","end":"2026-04-17T05:54:45.207953Z","steps":["trace[347716780] 'process raft request' (duration: 194.899538ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:54:49.637977Z","caller":"traceutil/trace.go:171","msg":"trace[848547413] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"274.728648ms","start":"2026-04-17T05:54:49.363216Z","end":"2026-04-17T05:54:49.637945Z","steps":["trace[848547413] 'process raft request' (duration: 272.392822ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:54:50.418759Z","caller":"traceutil/trace.go:171","msg":"trace[857412198] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"175.717715ms","start":"2026-04-17T05:54:50.243019Z","end":"2026-04-17T05:54:50.418737Z","steps":["trace[857412198] 'read index received' (duration: 175.022209ms)","trace[857412198] 'applied index is now lower than readState.Index' (duration: 694.496µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:54:50.418868Z","caller":"traceutil/trace.go:171","msg":"trace[545959987] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"299.34876ms","start":"2026-04-17T05:54:50.119475Z","end":"2026-04-17T05:54:50.418824Z","steps":["trace[545959987] 'process raft request' (duration: 298.710405ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:54:50.418975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.371705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-17T05:54:50.41901Z","caller":"traceutil/trace.go:171","msg":"trace[1436894886] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:609; }","duration":"172.475597ms","start":"2026-04-17T05:54:50.246523Z","end":"2026-04-17T05:54:50.418999Z","steps":["trace[1436894886] 'agreement among raft nodes before linearized reading' (duration: 172.340134ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:54:50.419034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.981672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-17T05:54:50.419112Z","caller":"traceutil/trace.go:171","msg":"trace[1624004190] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:609; }","duration":"176.103195ms","start":"2026-04-17T05:54:50.242989Z","end":"2026-04-17T05:54:50.419092Z","steps":["trace[1624004190] 'agreement among raft nodes before linearized reading' (duration: 175.863089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:54:50.419895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.395687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-17T05:54:50.420055Z","caller":"traceutil/trace.go:171","msg":"trace[611458299] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:609; }","duration":"103.55264ms","start":"2026-04-17T05:54:50.316459Z","end":"2026-04-17T05:54:50.420012Z","steps":["trace[611458299] 'agreement among raft nodes before linearized reading' (duration: 102.588937ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:54:50.421677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.481052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-17T05:54:50.421892Z","caller":"traceutil/trace.go:171","msg":"trace[370403706] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:609; }","duration":"173.697318ms","start":"2026-04-17T05:54:50.248118Z","end":"2026-04-17T05:54:50.421816Z","steps":["trace[370403706] 'agreement among raft nodes before linearized reading' (duration: 170.923901ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:54:51.317763Z","caller":"traceutil/trace.go:171","msg":"trace[598659144] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"390.873391ms","start":"2026-04-17T05:54:50.926863Z","end":"2026-04-17T05:54:51.317736Z","steps":["trace[598659144] 'process raft request' (duration: 390.354349ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:54:51.317913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:54:50.92681Z","time spent":"391.026945ms","remote":"127.0.0.1:47016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T05:54:52.944556Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225556512068123,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T05:54:52.99092Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.163805222s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-17T05:54:52.993003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.299028754s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T05:54:52.993221Z","caller":"traceutil/trace.go:171","msg":"trace[676285661] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:620; }","duration":"1.299280491s","start":"2026-04-17T05:54:51.693884Z","end":"2026-04-17T05:54:52.993164Z","steps":["trace[676285661] 'range keys from in-memory index tree' (duration: 1.298138633s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:54:52.993282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:54:51.693865Z","time spent":"1.299400564s","remote":"127.0.0.1:46936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T05:54:52.994739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:54:52.27759Z","time spent":"717.143839ms","remote":"127.0.0.1:32828","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-17T05:54:53.003334Z","caller":"traceutil/trace.go:171","msg":"trace[615098944] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"1.1782545s","start":"2026-04-17T05:54:51.825058Z","end":"2026-04-17T05:54:53.003312Z","steps":["trace[615098944] 'process raft request' (duration: 1.166780935s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:54:53.003442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:54:51.825025Z","time spent":"1.178359333s","remote":"127.0.0.1:47016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T05:54:53.021214Z","caller":"traceutil/trace.go:171","msg":"trace[1178101106] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:649; }","duration":"576.931176ms","start":"2026-04-17T05:54:52.444151Z","end":"2026-04-17T05:54:53.021082Z","steps":["trace[1178101106] 'read index received' (duration: 547.09743ms)","trace[1178101106] 'applied index is now lower than readState.Index' (duration: 29.831986ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:54:53.021579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.259595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-17T05:54:53.021629Z","caller":"traceutil/trace.go:171","msg":"trace[1285511671] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:621; }","duration":"570.326107ms","start":"2026-04-17T05:54:52.451289Z","end":"2026-04-17T05:54:53.021615Z","steps":["trace[1285511671] 'agreement among raft nodes before linearized reading' (duration: 570.155563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:54:53.021653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:54:52.451255Z","time spent":"570.393239ms","remote":"127.0.0.1:47210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T05:54:53.021804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.785943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-17T05:54:53.021819Z","caller":"traceutil/trace.go:171","msg":"trace[603413451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:621; }","duration":"566.824164ms","start":"2026-04-17T05:54:52.454991Z","end":"2026-04-17T05:54:53.021815Z","steps":["trace[603413451] 'agreement among raft nodes before linearized reading' (duration: 566.779983ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:54:53.021831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:54:52.454976Z","time spent":"566.851605ms","remote":"127.0.0.1:47210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-17T05:54:53.021915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.108443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-17T05:54:53.021932Z","caller":"traceutil/trace.go:171","msg":"trace[1992776515] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:621; }","duration":"570.124133ms","start":"2026-04-17T05:54:52.451802Z","end":"2026-04-17T05:54:53.021926Z","steps":["trace[1992776515] 'agreement among raft nodes before linearized reading' (duration: 570.082702ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:54:53.021945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:54:52.451692Z","time spent":"570.248777ms","remote":"127.0.0.1:47210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T05:54:53.022157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.019844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-17T05:54:53.022177Z","caller":"traceutil/trace.go:171","msg":"trace[1730337683] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:621; }","duration":"578.071305ms","start":"2026-04-17T05:54:52.444101Z","end":"2026-04-17T05:54:53.022172Z","steps":["trace[1730337683] 'agreement among raft nodes before linearized reading' (duration: 578.020574ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:54:53.02219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:54:52.444081Z","time spent":"578.106176ms","remote":"127.0.0.1:47210","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-04-17T05:54:54.265175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.579312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T05:54:54.265255Z","caller":"traceutil/trace.go:171","msg":"trace[1209058254] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:635; }","duration":"239.702034ms","start":"2026-04-17T05:54:54.025536Z","end":"2026-04-17T05:54:54.265238Z","steps":["trace[1209058254] 'count revisions from in-memory index tree' (duration: 239.51172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:54:54.265476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.121243ms","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-04-17T05:54:54.265557Z","caller":"traceutil/trace.go:171","msg":"trace[22938256] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:635; }","duration":"168.260807ms","start":"2026-04-17T05:54:54.097279Z","end":"2026-04-17T05:54:54.26554Z","steps":["trace[22938256] 'count revisions from in-memory index tree' (duration: 168.002771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:54:54.265552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.900569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T05:54:54.265549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.07802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T05:54:54.265611Z","caller":"traceutil/trace.go:171","msg":"trace[449254666] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:635; }","duration":"240.012133ms","start":"2026-04-17T05:54:54.025586Z","end":"2026-04-17T05:54:54.265598Z","steps":["trace[449254666] 'range keys from in-memory index tree' (duration: 239.756556ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:54:54.265739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.839237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T05:54:54.265781Z","caller":"traceutil/trace.go:171","msg":"trace[1560378030] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:635; }","duration":"199.888739ms","start":"2026-04-17T05:54:54.065876Z","end":"2026-04-17T05:54:54.265765Z","steps":["trace[1560378030] 'range keys from in-memory index tree' (duration: 199.683774ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:54:54.265669Z","caller":"traceutil/trace.go:171","msg":"trace[521904161] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:635; }","duration":"239.217704ms","start":"2026-04-17T05:54:54.02642Z","end":"2026-04-17T05:54:54.265638Z","steps":["trace[521904161] 'range keys from in-memory index tree' (duration: 238.991608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:54:54.265948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.081103ms","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-04-17T05:54:54.265994Z","caller":"traceutil/trace.go:171","msg":"trace[1587704037] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:635; }","duration":"200.145635ms","start":"2026-04-17T05:54:54.065834Z","end":"2026-04-17T05:54:54.265979Z","steps":["trace[1587704037] 'count revisions from in-memory index tree' (duration: 199.92702ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:54:54.266081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.877328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T05:54:54.266111Z","caller":"traceutil/trace.go:171","msg":"trace[1742995320] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:635; }","duration":"167.928959ms","start":"2026-04-17T05:54:54.098174Z","end":"2026-04-17T05:54:54.266103Z","steps":["trace[1742995320] 'range keys from in-memory index tree' (duration: 167.818806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:54:54.266183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.848132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T05:54:54.266211Z","caller":"traceutil/trace.go:171","msg":"trace[2127890123] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:635; }","duration":"168.900483ms","start":"2026-04-17T05:54:54.097304Z","end":"2026-04-17T05:54:54.266204Z","steps":["trace[2127890123] 'range keys from in-memory index tree' (duration: 168.731509ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:54:56.952049Z","caller":"traceutil/trace.go:171","msg":"trace[493664716] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"105.936037ms","start":"2026-04-17T05:54:56.846077Z","end":"2026-04-17T05:54:56.952013Z","steps":["trace[493664716] 'process raft request' (duration: 99.276328ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:54:57.728615Z","caller":"traceutil/trace.go:171","msg":"trace[1171469456] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"139.522456ms","start":"2026-04-17T05:54:57.589054Z","end":"2026-04-17T05:54:57.728577Z","steps":["trace[1171469456] 'process raft request' (duration: 125.672452ms)","trace[1171469456] 'compare' (duration: 13.120315ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:54:58.276745Z","caller":"traceutil/trace.go:171","msg":"trace[1576636873] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"321.075942ms","start":"2026-04-17T05:54:57.955642Z","end":"2026-04-17T05:54:58.276718Z","steps":["trace[1576636873] 'process raft request' (duration: 320.750335ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:54:58.276984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:54:57.955323Z","time spent":"321.534473ms","remote":"127.0.0.1:46984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T05:54:58.799981Z","caller":"traceutil/trace.go:171","msg":"trace[83916541] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"647.141186ms","start":"2026-04-17T05:54:58.152814Z","end":"2026-04-17T05:54:58.799955Z","steps":["trace[83916541] 'process raft request' (duration: 646.938471ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:54:58.80002Z","caller":"traceutil/trace.go:171","msg":"trace[701757671] linearizableReadLoop","detail":"{readStateIndex:731; appliedIndex:731; }","duration":"476.05772ms","start":"2026-04-17T05:54:58.323918Z","end":"2026-04-17T05:54:58.799975Z","steps":["trace[701757671] 'read index received' (duration: 476.04316ms)","trace[701757671] 'applied index is now lower than readState.Index' (duration: 12.13µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:54:58.800134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:54:58.152772Z","time spent":"647.292719ms","remote":"127.0.0.1:46984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T05:54:58.800458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.511942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-04-17T05:54:58.800577Z","caller":"traceutil/trace.go:171","msg":"trace[933852279] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:700; }","duration":"476.706956ms","start":"2026-04-17T05:54:58.32384Z","end":"2026-04-17T05:54:58.800547Z","steps":["trace[933852279] 'agreement among raft nodes before linearized reading' (duration: 476.260995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:54:58.800644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:54:58.323815Z","time spent":"476.815729ms","remote":"127.0.0.1:46984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":13251,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"info","ts":"2026-04-17T05:54:58.944311Z","caller":"traceutil/trace.go:171","msg":"trace[614910985] linearizableReadLoop","detail":"{readStateIndex:732; appliedIndex:731; }","duration":"144.154228ms","start":"2026-04-17T05:54:58.800133Z","end":"2026-04-17T05:54:58.944287Z","steps":["trace[614910985] 'read index received' (duration: 143.759228ms)","trace[614910985] 'applied index is now lower than readState.Index' (duration: 394.21µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:54:58.944481Z","caller":"traceutil/trace.go:171","msg":"trace[596663613] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"595.683709ms","start":"2026-04-17T05:54:58.34872Z","end":"2026-04-17T05:54:58.944404Z","steps":["trace[596663613] 'process raft request' (duration: 595.32076ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:54:58.944735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.165747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"warn","ts":"2026-04-17T05:54:58.944762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:54:58.348675Z","time spent":"595.938025ms","remote":"127.0.0.1:46984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188471,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T05:54:58.944819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.316294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-17T05:54:58.94487Z","caller":"traceutil/trace.go:171","msg":"trace[125819654] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:701; }","duration":"384.396455ms","start":"2026-04-17T05:54:58.560461Z","end":"2026-04-17T05:54:58.944858Z","steps":["trace[125819654] 'agreement among raft nodes before linearized reading' (duration: 384.222821ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:54:58.944857Z","caller":"traceutil/trace.go:171","msg":"trace[1623011497] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:701; }","duration":"420.352781ms","start":"2026-04-17T05:54:58.524472Z","end":"2026-04-17T05:54:58.944825Z","steps":["trace[1623011497] 'agreement among raft nodes before linearized reading' (duration: 420.050673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:54:58.944904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:54:58.560451Z","time spent":"384.447627ms","remote":"127.0.0.1:47210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-17T05:54:58.944885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.955885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"warn","ts":"2026-04-17T05:54:58.944924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:54:58.524448Z","time spent":"420.461054ms","remote":"127.0.0.1:46984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10399,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"info","ts":"2026-04-17T05:54:58.944983Z","caller":"traceutil/trace.go:171","msg":"trace[374967665] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:701; }","duration":"221.129509ms","start":"2026-04-17T05:54:58.723829Z","end":"2026-04-17T05:54:58.944959Z","steps":["trace[374967665] 'agreement among raft nodes before linearized reading' (duration: 220.800651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:54:59.232047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.251537ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T05:54:59.232174Z","caller":"traceutil/trace.go:171","msg":"trace[221813550] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"281.89527ms","start":"2026-04-17T05:54:58.950258Z","end":"2026-04-17T05:54:59.232154Z","steps":["trace[221813550] 'process raft request' (duration: 140.359356ms)","trace[221813550] 'compare' (duration: 141.090843ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:54:59.876065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.534487ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T05:54:59.876548Z","caller":"traceutil/trace.go:171","msg":"trace[554664808] linearizableReadLoop","detail":"{readStateIndex:735; appliedIndex:732; }","duration":"814.46746ms","start":"2026-04-17T05:54:59.062059Z","end":"2026-04-17T05:54:59.876527Z","steps":["trace[554664808] 'read index received' (duration: 28.68199ms)","trace[554664808] 'applied index is now lower than readState.Index' (duration: 785.78435ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:54:59.876629Z","caller":"traceutil/trace.go:171","msg":"trace[81337076] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"920.721485ms","start":"2026-04-17T05:54:58.955897Z","end":"2026-04-17T05:54:59.876618Z","steps":["trace[81337076] 'process raft request' (duration: 528.496031ms)","trace[81337076] 'compare' (duration: 391.056536ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:54:59.876682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:54:58.955866Z","time spent":"920.790467ms","remote":"127.0.0.1:47008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T05:54:59.877007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.938542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-17T05:54:59.877042Z","caller":"traceutil/trace.go:171","msg":"trace[239845764] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:704; }","duration":"815.044945ms","start":"2026-04-17T05:54:59.061981Z","end":"2026-04-17T05:54:59.877026Z","steps":["trace[239845764] 'agreement among raft nodes before linearized reading' (duration: 814.897271ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:54:59.877073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:54:59.061955Z","time spent":"815.111136ms","remote":"127.0.0.1:47210","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-04-17T05:54:59.876732Z","caller":"traceutil/trace.go:171","msg":"trace[1738295571] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"825.427603ms","start":"2026-04-17T05:54:59.051256Z","end":"2026-04-17T05:54:59.876683Z","steps":["trace[1738295571] 'process raft request' (duration: 825.045433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:54:59.877317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:54:59.051227Z","time spent":"825.895795ms","remote":"127.0.0.1:46984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188228,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T05:54:59.90875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.498091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-04-17T05:54:59.908872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.365359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188243"} {"level":"info","ts":"2026-04-17T05:54:59.908953Z","caller":"traceutil/trace.go:171","msg":"trace[915613733] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:705; }","duration":"585.503683ms","start":"2026-04-17T05:54:59.323428Z","end":"2026-04-17T05:54:59.908932Z","steps":["trace[915613733] 'agreement among raft nodes before linearized reading' (duration: 585.185215ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:54:59.908921Z","caller":"traceutil/trace.go:171","msg":"trace[1859919073] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:705; }","duration":"846.685315ms","start":"2026-04-17T05:54:59.062215Z","end":"2026-04-17T05:54:59.908901Z","steps":["trace[1859919073] 'agreement among raft nodes before linearized reading' (duration: 846.369007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:54:59.909109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:54:59.062209Z","time spent":"846.861309ms","remote":"127.0.0.1:47210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T05:54:59.909193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.233911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T05:54:59.90922Z","caller":"traceutil/trace.go:171","msg":"trace[640197483] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"810.72247ms","start":"2026-04-17T05:54:59.098426Z","end":"2026-04-17T05:54:59.909148Z","steps":["trace[640197483] 'process raft request' (duration: 809.934051ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:54:59.909368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:54:59.098396Z","time spent":"810.897423ms","remote":"127.0.0.1:46984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188480,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T05:54:59.909481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"842.326701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-17T05:54:59.909568Z","caller":"traceutil/trace.go:171","msg":"trace[967892927] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:705; }","duration":"842.439523ms","start":"2026-04-17T05:54:59.067109Z","end":"2026-04-17T05:54:59.909549Z","steps":["trace[967892927] 'agreement among raft nodes before linearized reading' (duration: 842.153916ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:54:59.90959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.922527ms","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-04-17T05:54:59.909617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:54:59.067103Z","time spent":"842.505475ms","remote":"127.0.0.1:47210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-17T05:54:59.909634Z","caller":"traceutil/trace.go:171","msg":"trace[2144493006] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:705; }","duration":"845.987858ms","start":"2026-04-17T05:54:59.063635Z","end":"2026-04-17T05:54:59.909623Z","steps":["trace[2144493006] 'agreement among raft nodes before linearized reading' (duration: 845.811184ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:54:59.909667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:54:59.063624Z","time spent":"846.03654ms","remote":"127.0.0.1:47210","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-04-17T05:54:59.909996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.324929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-04-17T05:54:59.910131Z","caller":"traceutil/trace.go:171","msg":"trace[1596601155] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:705; }","duration":"600.528664ms","start":"2026-04-17T05:54:59.30958Z","end":"2026-04-17T05:54:59.910109Z","steps":["trace[1596601155] 'agreement among raft nodes before linearized reading' (duration: 600.196766ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:54:59.910192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:54:59.309555Z","time spent":"600.626286ms","remote":"127.0.0.1:46984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":1,"response size":188517,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-04-17T05:54:59.909235Z","caller":"traceutil/trace.go:171","msg":"trace[108772778] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:705; }","duration":"216.323583ms","start":"2026-04-17T05:54:59.692902Z","end":"2026-04-17T05:54:59.909225Z","steps":["trace[108772778] 'agreement among raft nodes before linearized reading' (duration: 216.241101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:54:59.909052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:54:59.323402Z","time spent":"585.636506ms","remote":"127.0.0.1:46984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":1,"response size":188265,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-04-17T05:55:00.029745Z","caller":"traceutil/trace.go:171","msg":"trace[1284235329] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"146.555875ms","start":"2026-04-17T05:54:59.883158Z","end":"2026-04-17T05:55:00.029714Z","steps":["trace[1284235329] 'process raft request' (duration: 109.289039ms)","trace[1284235329] 'compare' (duration: 37.086232ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:55:00.029929Z","caller":"traceutil/trace.go:171","msg":"trace[1144973921] linearizableReadLoop","detail":"{readStateIndex:738; appliedIndex:736; }","duration":"118.052469ms","start":"2026-04-17T05:54:59.911852Z","end":"2026-04-17T05:55:00.029904Z","steps":["trace[1144973921] 'read index received' (duration: 80.64809ms)","trace[1144973921] 'applied index is now lower than readState.Index' (duration: 37.402209ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:55:00.030061Z","caller":"traceutil/trace.go:171","msg":"trace[1933570664] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"146.671997ms","start":"2026-04-17T05:54:59.883349Z","end":"2026-04-17T05:55:00.030021Z","steps":["trace[1933570664] 'process raft request' (duration: 146.316659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:55:00.030327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.454409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T05:55:00.030375Z","caller":"traceutil/trace.go:171","msg":"trace[1613818080] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:707; }","duration":"118.538061ms","start":"2026-04-17T05:54:59.911824Z","end":"2026-04-17T05:55:00.030362Z","steps":["trace[1613818080] 'agreement among raft nodes before linearized reading' (duration: 118.310276ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:55:00.033627Z","caller":"traceutil/trace.go:171","msg":"trace[2052705408] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"117.299331ms","start":"2026-04-17T05:54:59.916297Z","end":"2026-04-17T05:55:00.033596Z","steps":["trace[2052705408] 'process raft request' (duration: 117.098126ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:55:00.034142Z","caller":"traceutil/trace.go:171","msg":"trace[308099071] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"117.484716ms","start":"2026-04-17T05:54:59.916638Z","end":"2026-04-17T05:55:00.034123Z","steps":["trace[308099071] 'process raft request' (duration: 116.907922ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:55:00.034399Z","caller":"traceutil/trace.go:171","msg":"trace[1394153639] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"117.149628ms","start":"2026-04-17T05:54:59.917077Z","end":"2026-04-17T05:55:00.034226Z","steps":["trace[1394153639] 'process raft request' (duration: 116.965083ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:55:00.034883Z","caller":"traceutil/trace.go:171","msg":"trace[1634457097] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"109.517054ms","start":"2026-04-17T05:54:59.925346Z","end":"2026-04-17T05:55:00.034863Z","steps":["trace[1634457097] 'process raft request' (duration: 108.809037ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:55:12.341767Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.050630596s","expected-duration":"1s"} {"level":"info","ts":"2026-04-17T05:55:12.342392Z","caller":"traceutil/trace.go:171","msg":"trace[803110464] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"1.051320783s","start":"2026-04-17T05:55:11.291046Z","end":"2026-04-17T05:55:12.342367Z","steps":["trace[803110464] 'process raft request' (duration: 1.051173449s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:55:12.342538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:55:11.291028Z","time spent":"1.051434146s","remote":"127.0.0.1:47210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T05:55:12.447161Z","caller":"traceutil/trace.go:171","msg":"trace[1896409221] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"501.519439ms","start":"2026-04-17T05:55:11.945608Z","end":"2026-04-17T05:55:12.447127Z","steps":["trace[1896409221] 'process raft request' (duration: 501.268613ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:55:12.447437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:55:11.945579Z","time spent":"501.766635ms","remote":"127.0.0.1:47180","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-04-17T05:55:12.449532Z","caller":"traceutil/trace.go:171","msg":"trace[1838599931] linearizableReadLoop","detail":"{readStateIndex:827; appliedIndex:825; }","duration":"354.480281ms","start":"2026-04-17T05:55:12.095023Z","end":"2026-04-17T05:55:12.449503Z","steps":["trace[1838599931] 'read index received' (duration: 247.192182ms)","trace[1838599931] 'applied index is now lower than readState.Index' (duration: 107.286359ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:55:12.449788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.754837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-17T05:55:12.449863Z","caller":"traceutil/trace.go:171","msg":"trace[1472317125] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:793; }","duration":"354.8728ms","start":"2026-04-17T05:55:12.094975Z","end":"2026-04-17T05:55:12.449848Z","steps":["trace[1472317125] 'agreement among raft nodes before linearized reading' (duration: 354.641424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:55:12.449916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:55:12.094954Z","time spent":"354.954542ms","remote":"127.0.0.1:47210","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-04-17T05:55:12.449941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.477186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-17T05:55:12.449943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.945873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-17T05:55:12.450003Z","caller":"traceutil/trace.go:171","msg":"trace[580169380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:793; }","duration":"351.582088ms","start":"2026-04-17T05:55:12.098402Z","end":"2026-04-17T05:55:12.449984Z","steps":["trace[580169380] 'agreement among raft nodes before linearized reading' (duration: 351.296061ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:55:12.450014Z","caller":"traceutil/trace.go:171","msg":"trace[1331790782] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:793; }","duration":"351.057156ms","start":"2026-04-17T05:55:12.098939Z","end":"2026-04-17T05:55:12.449996Z","steps":["trace[1331790782] 'agreement among raft nodes before linearized reading' (duration: 350.801879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:55:12.450058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:55:12.098379Z","time spent":"351.665941ms","remote":"127.0.0.1:47210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-17T05:55:12.450058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:55:12.098917Z","time spent":"351.128198ms","remote":"127.0.0.1:47210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T05:55:12.450662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.470357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-17T05:55:12.450728Z","caller":"traceutil/trace.go:171","msg":"trace[890084816] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:793; }","duration":"347.552578ms","start":"2026-04-17T05:55:12.103158Z","end":"2026-04-17T05:55:12.450711Z","steps":["trace[890084816] 'agreement among raft nodes before linearized reading' (duration: 347.343073ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:55:12.450769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:55:12.103148Z","time spent":"347.61027ms","remote":"127.0.0.1:47210","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":"info","ts":"2026-04-17T05:55:19.819795Z","caller":"traceutil/trace.go:171","msg":"trace[897932494] linearizableReadLoop","detail":"{readStateIndex:900; appliedIndex:899; }","duration":"126.504653ms","start":"2026-04-17T05:55:19.693271Z","end":"2026-04-17T05:55:19.819776Z","steps":["trace[897932494] 'read index received' (duration: 126.303447ms)","trace[897932494] 'applied index is now lower than readState.Index' (duration: 200.296µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:55:19.819835Z","caller":"traceutil/trace.go:171","msg":"trace[1633986361] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"155.589851ms","start":"2026-04-17T05:55:19.664225Z","end":"2026-04-17T05:55:19.819814Z","steps":["trace[1633986361] 'process raft request' (duration: 155.430836ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:55:19.819977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.685178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T05:55:19.82002Z","caller":"traceutil/trace.go:171","msg":"trace[2098754218] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:863; }","duration":"126.761349ms","start":"2026-04-17T05:55:19.693247Z","end":"2026-04-17T05:55:19.820008Z","steps":["trace[2098754218] 'agreement among raft nodes before linearized reading' (duration: 126.634436ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:55:19.929291Z","caller":"traceutil/trace.go:171","msg":"trace[254526927] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"102.342127ms","start":"2026-04-17T05:55:19.82693Z","end":"2026-04-17T05:55:19.929273Z","steps":["trace[254526927] 'process raft request' (duration: 102.193243ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:55:19.933924Z","caller":"traceutil/trace.go:171","msg":"trace[840043899] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"106.629692ms","start":"2026-04-17T05:55:19.827265Z","end":"2026-04-17T05:55:19.933895Z","steps":["trace[840043899] 'process raft request' (duration: 106.252463ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:55:19.934117Z","caller":"traceutil/trace.go:171","msg":"trace[326594448] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"105.422201ms","start":"2026-04-17T05:55:19.828599Z","end":"2026-04-17T05:55:19.934022Z","steps":["trace[326594448] 'process raft request' (duration: 105.300948ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:55:29.706293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.20849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T05:55:29.706385Z","caller":"traceutil/trace.go:171","msg":"trace[1936469053] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:946; }","duration":"114.371553ms","start":"2026-04-17T05:55:29.591995Z","end":"2026-04-17T05:55:29.706366Z","steps":["trace[1936469053] 'count revisions from in-memory index tree' (duration: 114.110467ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:55:42.99391Z","caller":"traceutil/trace.go:171","msg":"trace[1722257820] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"260.174292ms","start":"2026-04-17T05:55:42.733552Z","end":"2026-04-17T05:55:42.993726Z","steps":["trace[1722257820] 'process raft request' (duration: 232.002587ms)","trace[1722257820] 'compare' (duration: 27.895048ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:55:47.896459Z","caller":"traceutil/trace.go:171","msg":"trace[2006434682] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"217.50763ms","start":"2026-04-17T05:55:47.678922Z","end":"2026-04-17T05:55:47.89643Z","steps":["trace[2006434682] 'process raft request' (duration: 217.335365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:55:49.269638Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225556512068983,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T05:55:49.539445Z","caller":"traceutil/trace.go:171","msg":"trace[779500767] linearizableReadLoop","detail":"{readStateIndex:1055; appliedIndex:1054; }","duration":"770.277362ms","start":"2026-04-17T05:55:48.769148Z","end":"2026-04-17T05:55:49.539426Z","steps":["trace[779500767] 'read index received' (duration: 770.134519ms)","trace[779500767] 'applied index is now lower than readState.Index' (duration: 141.883µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:55:49.539528Z","caller":"traceutil/trace.go:171","msg":"trace[1058071571] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"770.378015ms","start":"2026-04-17T05:55:48.769087Z","end":"2026-04-17T05:55:49.539465Z","steps":["trace[1058071571] 'process raft request' (duration: 770.210041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:55:49.539664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.357871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-17T05:55:49.539695Z","caller":"traceutil/trace.go:171","msg":"trace[1476448040] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1011; }","duration":"767.423503ms","start":"2026-04-17T05:55:48.772261Z","end":"2026-04-17T05:55:49.539684Z","steps":["trace[1476448040] 'agreement among raft nodes before linearized reading' (duration: 767.292679ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:55:49.539721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:55:48.77225Z","time spent":"767.464863ms","remote":"127.0.0.1:47210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T05:55:49.53979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.731083ms","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-04-17T05:55:49.53983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.809797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-17T05:55:49.539811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.624113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-17T05:55:49.539874Z","caller":"traceutil/trace.go:171","msg":"trace[295952317] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1011; }","duration":"522.873807ms","start":"2026-04-17T05:55:49.016976Z","end":"2026-04-17T05:55:49.53985Z","steps":["trace[295952317] 'agreement among raft nodes before linearized reading' (duration: 522.60469ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:55:49.539881Z","caller":"traceutil/trace.go:171","msg":"trace[1605398242] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1011; }","duration":"766.8992ms","start":"2026-04-17T05:55:48.772967Z","end":"2026-04-17T05:55:49.539866Z","steps":["trace[1605398242] 'agreement among raft nodes before linearized reading' (duration: 766.654014ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:55:49.539906Z","caller":"traceutil/trace.go:171","msg":"trace[1475419856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1011; }","duration":"770.787887ms","start":"2026-04-17T05:55:48.769095Z","end":"2026-04-17T05:55:49.539883Z","steps":["trace[1475419856] 'agreement among raft nodes before linearized reading' (duration: 770.445877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:55:49.539953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:55:49.016955Z","time spent":"522.962868ms","remote":"127.0.0.1:47210","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-04-17T05:55:49.539963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:55:48.77296Z","time spent":"766.993812ms","remote":"127.0.0.1:47210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-17T05:55:49.539977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:55:48.76907Z","time spent":"770.899589ms","remote":"127.0.0.1:47210","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-04-17T05:55:49.54017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:55:48.769061Z","time spent":"771.044123ms","remote":"127.0.0.1:47210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T05:55:50.243752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.908015ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T05:55:50.244032Z","caller":"traceutil/trace.go:171","msg":"trace[2012901183] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"699.017155ms","start":"2026-04-17T05:55:49.544978Z","end":"2026-04-17T05:55:50.243995Z","steps":["trace[2012901183] 'process raft request' (duration: 41.760871ms)","trace[2012901183] 'compare' (duration: 656.764592ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:55:50.244185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:55:49.54495Z","time spent":"699.150718ms","remote":"127.0.0.1:47210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T05:55:50.244308Z","caller":"traceutil/trace.go:171","msg":"trace[1009652323] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"341.544171ms","start":"2026-04-17T05:55:49.902739Z","end":"2026-04-17T05:55:50.244283Z","steps":["trace[1009652323] 'process raft request' (duration: 341.49134ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:55:50.244418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:55:49.902718Z","time spent":"341.644903ms","remote":"127.0.0.1:47210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T05:55:50.244848Z","caller":"traceutil/trace.go:171","msg":"trace[1931747004] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"698.741696ms","start":"2026-04-17T05:55:49.546094Z","end":"2026-04-17T05:55:50.244836Z","steps":["trace[1931747004] 'process raft request' (duration: 698.028659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:55:50.244916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:55:49.546086Z","time spent":"698.797959ms","remote":"127.0.0.1:47210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T05:55:50.244928Z","caller":"traceutil/trace.go:171","msg":"trace[1290310142] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"697.793564ms","start":"2026-04-17T05:55:49.547111Z","end":"2026-04-17T05:55:50.244904Z","steps":["trace[1290310142] 'process raft request' (duration: 697.073557ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:55:50.245039Z","caller":"traceutil/trace.go:171","msg":"trace[1082694022] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"699.758291ms","start":"2026-04-17T05:55:49.54527Z","end":"2026-04-17T05:55:50.245028Z","steps":["trace[1082694022] 'process raft request' (duration: 698.625344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:55:50.245089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:55:49.54526Z","time spent":"699.805033ms","remote":"127.0.0.1:47210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T05:55:50.245017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:55:49.547102Z","time spent":"697.867256ms","remote":"127.0.0.1:47210","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-04-17T05:55:54.593767Z","caller":"traceutil/trace.go:171","msg":"trace[2101939821] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"145.665335ms","start":"2026-04-17T05:55:54.448066Z","end":"2026-04-17T05:55:54.593731Z","steps":["trace[2101939821] 'process raft request' (duration: 51.842939ms)","trace[2101939821] 'compare' (duration: 93.656402ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:55:54.693477Z","caller":"traceutil/trace.go:171","msg":"trace[363742286] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"245.288384ms","start":"2026-04-17T05:55:54.44816Z","end":"2026-04-17T05:55:54.693448Z","steps":["trace[363742286] 'process raft request' (duration: 245.019277ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:55:54.780963Z","caller":"traceutil/trace.go:171","msg":"trace[1993190389] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"332.426935ms","start":"2026-04-17T05:55:54.448505Z","end":"2026-04-17T05:55:54.780931Z","steps":["trace[1993190389] 'process raft request' (duration: 244.925735ms)","trace[1993190389] 'compare' (duration: 87.317886ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:55:54.781157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:55:54.448487Z","time spent":"332.561069ms","remote":"127.0.0.1:47210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T05:55:56.633692Z","caller":"traceutil/trace.go:171","msg":"trace[1074903416] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"144.822445ms","start":"2026-04-17T05:55:56.488843Z","end":"2026-04-17T05:55:56.633666Z","steps":["trace[1074903416] 'process raft request' (duration: 143.363158ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:56:04.908939Z","caller":"traceutil/trace.go:171","msg":"trace[2128003864] linearizableReadLoop","detail":"{readStateIndex:1107; appliedIndex:1105; }","duration":"159.62945ms","start":"2026-04-17T05:56:04.749282Z","end":"2026-04-17T05:56:04.908911Z","steps":["trace[2128003864] 'read index received' (duration: 16.740063ms)","trace[2128003864] 'applied index is now lower than readState.Index' (duration: 142.887777ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:56:04.908993Z","caller":"traceutil/trace.go:171","msg":"trace[1729123420] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"202.123789ms","start":"2026-04-17T05:56:04.706842Z","end":"2026-04-17T05:56:04.908966Z","steps":["trace[1729123420] 'process raft request' (duration: 102.132101ms)","trace[1729123420] 'compare' (duration: 99.775833ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:56:04.909174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.881237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-17T05:56:04.909246Z","caller":"traceutil/trace.go:171","msg":"trace[1013435188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1060; }","duration":"160.00469ms","start":"2026-04-17T05:56:04.749224Z","end":"2026-04-17T05:56:04.909229Z","steps":["trace[1013435188] 'agreement among raft nodes before linearized reading' (duration: 159.790504ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:56:10.727191Z","caller":"traceutil/trace.go:171","msg":"trace[1365799406] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"134.963651ms","start":"2026-04-17T05:56:10.592195Z","end":"2026-04-17T05:56:10.727159Z","steps":["trace[1365799406] 'process raft request' (duration: 134.631523ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:56:14.969773Z","caller":"traceutil/trace.go:171","msg":"trace[2137409302] linearizableReadLoop","detail":"{readStateIndex:1140; appliedIndex:1139; }","duration":"130.45361ms","start":"2026-04-17T05:56:14.839296Z","end":"2026-04-17T05:56:14.96975Z","steps":["trace[2137409302] 'read index received' (duration: 130.252745ms)","trace[2137409302] 'applied index is now lower than readState.Index' (duration: 199.845µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:56:14.969971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.657675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-17T05:56:14.969998Z","caller":"traceutil/trace.go:171","msg":"trace[944304739] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"230.93303ms","start":"2026-04-17T05:56:14.739047Z","end":"2026-04-17T05:56:14.96998Z","steps":["trace[944304739] 'process raft request' (duration: 230.555951ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:56:14.970043Z","caller":"traceutil/trace.go:171","msg":"trace[1693347268] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1091; }","duration":"130.742317ms","start":"2026-04-17T05:56:14.83925Z","end":"2026-04-17T05:56:14.969993Z","steps":["trace[1693347268] 'agreement among raft nodes before linearized reading' (duration: 130.590084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:56:15.476898Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225556512069200,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T05:56:15.8193Z","caller":"traceutil/trace.go:171","msg":"trace[1014620642] linearizableReadLoop","detail":"{readStateIndex:1141; appliedIndex:1140; }","duration":"842.779162ms","start":"2026-04-17T05:56:14.976501Z","end":"2026-04-17T05:56:15.81928Z","steps":["trace[1014620642] 'read index received' (duration: 842.553857ms)","trace[1014620642] 'applied index is now lower than readState.Index' (duration: 224.335µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:56:15.819456Z","caller":"traceutil/trace.go:171","msg":"trace[2120499595] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"845.11681ms","start":"2026-04-17T05:56:14.974281Z","end":"2026-04-17T05:56:15.819398Z","steps":["trace[2120499595] 'process raft request' (duration: 844.824432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:56:15.819711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:56:14.974257Z","time spent":"845.322765ms","remote":"127.0.0.1:47210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T05:56:15.819668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.49128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-04-17T05:56:15.819738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.249252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T05:56:15.819811Z","caller":"traceutil/trace.go:171","msg":"trace[1476924137] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1092; }","duration":"127.368575ms","start":"2026-04-17T05:56:15.692425Z","end":"2026-04-17T05:56:15.819794Z","steps":["trace[1476924137] 'agreement among raft nodes before linearized reading' (duration: 127.246752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:56:15.819838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.494412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T05:56:15.819909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.717302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-17T05:56:15.819953Z","caller":"traceutil/trace.go:171","msg":"trace[936338345] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1092; }","duration":"256.663315ms","start":"2026-04-17T05:56:15.563246Z","end":"2026-04-17T05:56:15.819909Z","steps":["trace[936338345] 'agreement among raft nodes before linearized reading' (duration: 256.489911ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:56:15.819965Z","caller":"traceutil/trace.go:171","msg":"trace[815213564] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1092; }","duration":"746.792443ms","start":"2026-04-17T05:56:15.073164Z","end":"2026-04-17T05:56:15.819956Z","steps":["trace[815213564] 'agreement among raft nodes before linearized reading' (duration: 746.679261ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:56:15.820007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:56:15.073153Z","time spent":"746.844564ms","remote":"127.0.0.1:47210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-17T05:56:15.81982Z","caller":"traceutil/trace.go:171","msg":"trace[211755914] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1092; }","duration":"751.664234ms","start":"2026-04-17T05:56:15.068123Z","end":"2026-04-17T05:56:15.819787Z","steps":["trace[211755914] 'agreement among raft nodes before linearized reading' (duration: 751.389348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:56:15.820221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.723706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-17T05:56:15.820209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:56:15.068112Z","time spent":"752.052204ms","remote":"127.0.0.1:47210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-17T05:56:15.820335Z","caller":"traceutil/trace.go:171","msg":"trace[470106823] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1092; }","duration":"843.830598ms","start":"2026-04-17T05:56:14.976462Z","end":"2026-04-17T05:56:15.820292Z","steps":["trace[470106823] 'agreement among raft nodes before linearized reading' (duration: 842.943126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:56:15.820473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:56:14.976447Z","time spent":"844.006043ms","remote":"127.0.0.1:47210","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-04-17T05:56:16.028595Z","caller":"traceutil/trace.go:171","msg":"trace[1348039708] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"203.40927ms","start":"2026-04-17T05:56:15.825155Z","end":"2026-04-17T05:56:16.028564Z","steps":["trace[1348039708] 'process raft request' (duration: 197.70234ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:56:16.262409Z","caller":"traceutil/trace.go:171","msg":"trace[1330298071] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"436.543016ms","start":"2026-04-17T05:56:15.825827Z","end":"2026-04-17T05:56:16.26237Z","steps":["trace[1330298071] 'process raft request' (duration: 430.774854ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:56:16.262441Z","caller":"traceutil/trace.go:171","msg":"trace[247270149] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"435.742606ms","start":"2026-04-17T05:56:15.826668Z","end":"2026-04-17T05:56:16.262411Z","steps":["trace[247270149] 'process raft request' (duration: 435.627603ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:56:16.262614Z","caller":"traceutil/trace.go:171","msg":"trace[1665747512] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"435.776787ms","start":"2026-04-17T05:56:15.82682Z","end":"2026-04-17T05:56:16.262597Z","steps":["trace[1665747512] 'process raft request' (duration: 435.536852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:56:16.262731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:56:15.826814Z","time spent":"435.849249ms","remote":"127.0.0.1:47210","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-04-17T05:56:16.262852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:56:15.82665Z","time spent":"435.972592ms","remote":"127.0.0.1:47210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T05:56:16.263319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:56:15.82582Z","time spent":"436.684079ms","remote":"127.0.0.1:47210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T05:56:18.284259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.613161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T05:56:18.28435Z","caller":"traceutil/trace.go:171","msg":"trace[718029428] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1098; }","duration":"590.762014ms","start":"2026-04-17T05:56:17.693568Z","end":"2026-04-17T05:56:18.28433Z","steps":["trace[718029428] 'range keys from in-memory index tree' (duration: 590.449538ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:56:18.284395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:56:17.693541Z","time spent":"590.844787ms","remote":"127.0.0.1:46946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T05:56:18.28471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.240375ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T05:56:18.284827Z","caller":"traceutil/trace.go:171","msg":"trace[1662806754] linearizableReadLoop","detail":"{readStateIndex:1149; appliedIndex:1148; }","duration":"457.242742ms","start":"2026-04-17T05:56:17.827538Z","end":"2026-04-17T05:56:18.284781Z","steps":["trace[1662806754] 'read index received' (duration: 12.547916ms)","trace[1662806754] 'applied index is now lower than readState.Index' (duration: 444.693216ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:56:18.284905Z","caller":"traceutil/trace.go:171","msg":"trace[18340682] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"558.243916ms","start":"2026-04-17T05:56:17.726586Z","end":"2026-04-17T05:56:18.28483Z","steps":["trace[18340682] 'process raft request' (duration: 113.519579ms)","trace[18340682] 'compare' (duration: 444.067901ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:56:18.285042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.513378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-17T05:56:18.285074Z","caller":"traceutil/trace.go:171","msg":"trace[118843491] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1099; }","duration":"457.5928ms","start":"2026-04-17T05:56:17.827471Z","end":"2026-04-17T05:56:18.285064Z","steps":["trace[118843491] 'agreement among raft nodes before linearized reading' (duration: 457.428526ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:56:18.285104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:56:17.827449Z","time spent":"457.648162ms","remote":"127.0.0.1:47210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-17T05:56:18.285557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.841223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-17T05:56:18.285608Z","caller":"traceutil/trace.go:171","msg":"trace[1331356245] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1099; }","duration":"252.946057ms","start":"2026-04-17T05:56:18.03265Z","end":"2026-04-17T05:56:18.285596Z","steps":["trace[1331356245] 'agreement among raft nodes before linearized reading' (duration: 252.814364ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:56:18.285971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:56:17.726559Z","time spent":"558.487182ms","remote":"127.0.0.1:47210","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-04-17T05:56:18.772675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.557181ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T05:56:18.772795Z","caller":"traceutil/trace.go:171","msg":"trace[875912085] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"482.685149ms","start":"2026-04-17T05:56:18.29009Z","end":"2026-04-17T05:56:18.772775Z","steps":["trace[875912085] 'process raft request' (duration: 370.941894ms)","trace[875912085] 'compare' (duration: 111.403097ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:56:18.772858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:56:18.290069Z","time spent":"482.760822ms","remote":"127.0.0.1:47210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T05:56:19.192238Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225556512069223,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T05:56:19.452917Z","caller":"traceutil/trace.go:171","msg":"trace[860354709] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"1.162576491s","start":"2026-04-17T05:56:18.290277Z","end":"2026-04-17T05:56:19.452853Z","steps":["trace[860354709] 'process raft request' (duration: 1.125910959s)","trace[860354709] 'compare' (duration: 36.171339ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:56:19.453079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:56:18.29026Z","time spent":"1.162737864s","remote":"127.0.0.1:47210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T05:56:19.453304Z","caller":"traceutil/trace.go:171","msg":"trace[556989709] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"1.162990161s","start":"2026-04-17T05:56:18.29028Z","end":"2026-04-17T05:56:19.45327Z","steps":["trace[556989709] 'process raft request' (duration: 1.16253758s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:56:19.453532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:56:18.29025Z","time spent":"1.163155735s","remote":"127.0.0.1:47210","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-04-17T05:56:19.453595Z","caller":"traceutil/trace.go:171","msg":"trace[418867151] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"1.163269918s","start":"2026-04-17T05:56:18.290294Z","end":"2026-04-17T05:56:19.453564Z","steps":["trace[418867151] 'process raft request' (duration: 1.162789606s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:56:19.453828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:56:18.290282Z","time spent":"1.163504343s","remote":"127.0.0.1:47210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T05:56:19.453928Z","caller":"traceutil/trace.go:171","msg":"trace[393144734] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"1.163040712s","start":"2026-04-17T05:56:18.290878Z","end":"2026-04-17T05:56:19.453919Z","steps":["trace[393144734] 'process raft request' (duration: 1.162600542s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:56:19.453976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:56:18.29087Z","time spent":"1.163072683s","remote":"127.0.0.1:47210","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-04-17T05:56:19.524769Z","caller":"traceutil/trace.go:171","msg":"trace[86277070] linearizableReadLoop","detail":"{readStateIndex:1155; appliedIndex:1150; }","duration":"832.787027ms","start":"2026-04-17T05:56:18.691953Z","end":"2026-04-17T05:56:19.52474Z","steps":["trace[86277070] 'read index received' (duration: 724.140707ms)","trace[86277070] 'applied index is now lower than readState.Index' (duration: 108.64488ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:56:19.52485Z","caller":"traceutil/trace.go:171","msg":"trace[1582968962] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"534.491318ms","start":"2026-04-17T05:56:18.990307Z","end":"2026-04-17T05:56:19.524798Z","steps":["trace[1582968962] 'process raft request' (duration: 534.237762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:56:19.524987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"833.003092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T05:56:19.525041Z","caller":"traceutil/trace.go:171","msg":"trace[968513494] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1105; }","duration":"833.145416ms","start":"2026-04-17T05:56:18.691883Z","end":"2026-04-17T05:56:19.525029Z","steps":["trace[968513494] 'agreement among raft nodes before linearized reading' (duration: 832.979302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:56:19.525079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:56:18.691864Z","time spent":"833.206467ms","remote":"127.0.0.1:46936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T05:56:19.525074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:56:18.990284Z","time spent":"534.661652ms","remote":"127.0.0.1:47210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T05:56:21.634802Z","caller":"traceutil/trace.go:171","msg":"trace[1002375254] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"171.462726ms","start":"2026-04-17T05:56:21.46328Z","end":"2026-04-17T05:56:21.634743Z","steps":["trace[1002375254] 'process raft request' (duration: 171.20994ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:56:21.719952Z","caller":"traceutil/trace.go:171","msg":"trace[99754908] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"255.590921ms","start":"2026-04-17T05:56:21.464318Z","end":"2026-04-17T05:56:21.719909Z","steps":["trace[99754908] 'process raft request' (duration: 255.53471ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:56:21.719982Z","caller":"traceutil/trace.go:171","msg":"trace[47373655] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"256.242327ms","start":"2026-04-17T05:56:21.463674Z","end":"2026-04-17T05:56:21.719916Z","steps":["trace[47373655] 'process raft request' (duration: 256.030332ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:56:21.720196Z","caller":"traceutil/trace.go:171","msg":"trace[1686343904] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"254.931995ms","start":"2026-04-17T05:56:21.46524Z","end":"2026-04-17T05:56:21.720172Z","steps":["trace[1686343904] 'process raft request' (duration: 254.639058ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:56:21.720359Z","caller":"traceutil/trace.go:171","msg":"trace[1973460558] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"254.938255ms","start":"2026-04-17T05:56:21.465389Z","end":"2026-04-17T05:56:21.720328Z","steps":["trace[1973460558] 'process raft request' (duration: 254.672779ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:56:21.720388Z","caller":"traceutil/trace.go:171","msg":"trace[673242066] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"188.456859ms","start":"2026-04-17T05:56:21.531904Z","end":"2026-04-17T05:56:21.720361Z","steps":["trace[673242066] 'process raft request' (duration: 188.288665ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:56:31.670528Z","caller":"traceutil/trace.go:171","msg":"trace[1106660436] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"156.967785ms","start":"2026-04-17T05:56:31.513535Z","end":"2026-04-17T05:56:31.670502Z","steps":["trace[1106660436] 'process raft request' (duration: 156.909103ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:56:31.670602Z","caller":"traceutil/trace.go:171","msg":"trace[970996603] transaction","detail":"{read_only:false; response_revision:1157; number_of_response:1; }","duration":"158.096092ms","start":"2026-04-17T05:56:31.512473Z","end":"2026-04-17T05:56:31.670569Z","steps":["trace[970996603] 'process raft request' (duration: 157.821156ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:56:33.850724Z","caller":"traceutil/trace.go:171","msg":"trace[36191029] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"130.278045ms","start":"2026-04-17T05:56:33.720416Z","end":"2026-04-17T05:56:33.850695Z","steps":["trace[36191029] 'process raft request' (duration: 130.07328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:56:35.565197Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225556512069445,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T05:56:35.667618Z","caller":"traceutil/trace.go:171","msg":"trace[1106674985] linearizableReadLoop","detail":"{readStateIndex:1258; appliedIndex:1257; }","duration":"603.400924ms","start":"2026-04-17T05:56:35.064193Z","end":"2026-04-17T05:56:35.667594Z","steps":["trace[1106674985] 'read index received' (duration: 603.129447ms)","trace[1106674985] 'applied index is now lower than readState.Index' (duration: 270.577µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:56:35.668032Z","caller":"traceutil/trace.go:171","msg":"trace[1974516218] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"743.447617ms","start":"2026-04-17T05:56:34.924571Z","end":"2026-04-17T05:56:35.668019Z","steps":["trace[1974516218] 'process raft request' (duration: 742.875653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:56:35.668112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.889116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T05:56:35.668148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:56:34.924559Z","time spent":"743.511468ms","remote":"127.0.0.1:47210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T05:56:35.668166Z","caller":"traceutil/trace.go:171","msg":"trace[231332934] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1203; }","duration":"603.962197ms","start":"2026-04-17T05:56:35.064187Z","end":"2026-04-17T05:56:35.668149Z","steps":["trace[231332934] 'agreement among raft nodes before linearized reading' (duration: 603.836984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:56:35.668157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.265669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T05:56:35.668231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:56:35.064126Z","time spent":"604.0967ms","remote":"127.0.0.1:32942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"info","ts":"2026-04-17T05:56:35.668248Z","caller":"traceutil/trace.go:171","msg":"trace[1990439951] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1203; }","duration":"326.444283ms","start":"2026-04-17T05:56:35.341783Z","end":"2026-04-17T05:56:35.668228Z","steps":["trace[1990439951] 'agreement among raft nodes before linearized reading' (duration: 326.268839ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:56:35.668298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:56:35.341747Z","time spent":"326.540426ms","remote":"127.0.0.1:32896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-17T05:56:36.361956Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225556512069450,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T05:56:36.464773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.344857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T05:56:36.464832Z","caller":"traceutil/trace.go:171","msg":"trace[1116950284] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1203; }","duration":"772.45081ms","start":"2026-04-17T05:56:35.692368Z","end":"2026-04-17T05:56:36.464819Z","steps":["trace[1116950284] 'range keys from in-memory index tree' (duration: 772.227274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:56:36.465054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:56:35.692346Z","time spent":"772.508811ms","remote":"127.0.0.1:46936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T05:56:36.46544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.375128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-17T05:56:36.465462Z","caller":"traceutil/trace.go:171","msg":"trace[476697348] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1203; }","duration":"609.45917ms","start":"2026-04-17T05:56:35.855996Z","end":"2026-04-17T05:56:36.465455Z","steps":["trace[476697348] 'range keys from in-memory index tree' (duration: 609.231434ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:56:36.46548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:56:35.85597Z","time spent":"609.505332ms","remote":"127.0.0.1:47210","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":"info","ts":"2026-04-17T05:56:36.465914Z","caller":"traceutil/trace.go:171","msg":"trace[719050393] linearizableReadLoop","detail":"{readStateIndex:1259; appliedIndex:1258; }","duration":"603.984217ms","start":"2026-04-17T05:56:35.861915Z","end":"2026-04-17T05:56:36.465899Z","steps":["trace[719050393] 'read index received' (duration: 603.831343ms)","trace[719050393] 'applied index is now lower than readState.Index' (duration: 152.234µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:56:36.465984Z","caller":"traceutil/trace.go:171","msg":"trace[1218514696] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"608.302772ms","start":"2026-04-17T05:56:35.857674Z","end":"2026-04-17T05:56:36.465977Z","steps":["trace[1218514696] 'process raft request' (duration: 608.145808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:56:36.466088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.763162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-17T05:56:36.466117Z","caller":"traceutil/trace.go:171","msg":"trace[1034053374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1204; }","duration":"603.809193ms","start":"2026-04-17T05:56:35.8623Z","end":"2026-04-17T05:56:36.466109Z","steps":["trace[1034053374] 'agreement among raft nodes before linearized reading' (duration: 603.735551ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:56:36.466145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:56:35.862289Z","time spent":"603.851424ms","remote":"127.0.0.1:47210","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-04-17T05:56:36.466188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.276655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-17T05:56:36.466233Z","caller":"traceutil/trace.go:171","msg":"trace[689032585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1204; }","duration":"604.351646ms","start":"2026-04-17T05:56:35.861862Z","end":"2026-04-17T05:56:36.466214Z","steps":["trace[689032585] 'agreement among raft nodes before linearized reading' (duration: 604.232624ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:56:36.46622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.863596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-17T05:56:36.466255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:56:35.861843Z","time spent":"604.407417ms","remote":"127.0.0.1:47210","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-04-17T05:56:36.466273Z","caller":"traceutil/trace.go:171","msg":"trace[1102754125] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1204; }","duration":"599.97506ms","start":"2026-04-17T05:56:35.866285Z","end":"2026-04-17T05:56:36.46626Z","steps":["trace[1102754125] 'agreement among raft nodes before linearized reading' (duration: 599.806996ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:56:36.466296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:56:35.86627Z","time spent":"600.02155ms","remote":"127.0.0.1:47210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-17T05:56:36.466061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:56:35.857648Z","time spent":"608.355593ms","remote":"127.0.0.1:47210","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-04-17T05:56:42.515186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.293839ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-17T05:56:42.599593Z","caller":"traceutil/trace.go:171","msg":"trace[1469908888] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"115.826284ms","start":"2026-04-17T05:56:42.483726Z","end":"2026-04-17T05:56:42.599553Z","steps":["trace[1469908888] 'process raft request' (duration: 115.629619ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:56:42.599492Z","caller":"traceutil/trace.go:171","msg":"trace[791051697] linearizableReadLoop","detail":"{readStateIndex:1282; appliedIndex:1281; }","duration":"105.818322ms","start":"2026-04-17T05:56:42.493659Z","end":"2026-04-17T05:56:42.599478Z","steps":["trace[791051697] 'read index received' (duration: 105.636358ms)","trace[791051697] 'applied index is now lower than readState.Index' (duration: 181.444µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:56:42.599795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.128089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-17T05:56:42.599824Z","caller":"traceutil/trace.go:171","msg":"trace[1657164679] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1225; }","duration":"106.194031ms","start":"2026-04-17T05:56:42.493624Z","end":"2026-04-17T05:56:42.599818Z","steps":["trace[1657164679] 'agreement among raft nodes before linearized reading' (duration: 106.092199ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:56:49.021424Z","caller":"traceutil/trace.go:171","msg":"trace[1654359231] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"180.679771ms","start":"2026-04-17T05:56:48.840724Z","end":"2026-04-17T05:56:49.021404Z","steps":["trace[1654359231] 'process raft request' (duration: 180.560808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:56:52.030054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.920353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5331"} {"level":"info","ts":"2026-04-17T05:56:52.03013Z","caller":"traceutil/trace.go:171","msg":"trace[537113114] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1270; }","duration":"164.027537ms","start":"2026-04-17T05:56:51.866082Z","end":"2026-04-17T05:56:52.03011Z","steps":["trace[537113114] 'range keys from in-memory index tree' (duration: 163.77738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:56:52.423971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.658518ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-17T05:56:52.424101Z","caller":"traceutil/trace.go:171","msg":"trace[250920654] linearizableReadLoop","detail":"{readStateIndex:1332; appliedIndex:1330; }","duration":"134.400816ms","start":"2026-04-17T05:56:52.289676Z","end":"2026-04-17T05:56:52.424077Z","steps":["trace[250920654] 'read index received' (duration: 11.589271ms)","trace[250920654] 'applied index is now lower than readState.Index' (duration: 122.809695ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:56:52.4243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.60567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-rbwvb\" ","response":"range_response_count:1 size:11061"} {"level":"info","ts":"2026-04-17T05:56:52.424352Z","caller":"traceutil/trace.go:171","msg":"trace[1465326354] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-rbwvb; range_end:; response_count:1; response_revision:1273; }","duration":"134.688643ms","start":"2026-04-17T05:56:52.289651Z","end":"2026-04-17T05:56:52.424339Z","steps":["trace[1465326354] 'agreement among raft nodes before linearized reading' (duration: 134.509829ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:56:53.234885Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225556512069610,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T05:56:53.424895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.291168ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T05:56:53.425228Z","caller":"traceutil/trace.go:171","msg":"trace[89631941] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"928.288148ms","start":"2026-04-17T05:56:52.496921Z","end":"2026-04-17T05:56:53.42521Z","steps":["trace[89631941] 'process raft request' (duration: 928.227427ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:56:53.42532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:56:52.496907Z","time spent":"928.369751ms","remote":"127.0.0.1:47466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T05:56:53.42537Z","caller":"traceutil/trace.go:171","msg":"trace[1886695869] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"934.893298ms","start":"2026-04-17T05:56:52.490448Z","end":"2026-04-17T05:56:53.425341Z","steps":["trace[1886695869] 'process raft request' (duration: 57.074176ms)","trace[1886695869] 'compare' (duration: 876.877569ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:56:53.425437Z","caller":"traceutil/trace.go:171","msg":"trace[1396386072] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"934.911259ms","start":"2026-04-17T05:56:52.490495Z","end":"2026-04-17T05:56:53.425407Z","steps":["trace[1396386072] 'process raft request' (duration: 934.510309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:56:53.425524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:56:52.490427Z","time spent":"935.015592ms","remote":"127.0.0.1:47082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":915,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T05:56:53.425591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:56:52.490475Z","time spent":"935.038751ms","remote":"127.0.0.1:47218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1508,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T05:56:54.31503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.541761ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T05:56:54.315126Z","caller":"traceutil/trace.go:171","msg":"trace[1514272847] linearizableReadLoop","detail":"{readStateIndex:1337; appliedIndex:1333; }","duration":"1.580577008s","start":"2026-04-17T05:56:52.734534Z","end":"2026-04-17T05:56:54.315111Z","steps":["trace[1514272847] 'read index received' (duration: 690.253713ms)","trace[1514272847] 'applied index is now lower than readState.Index' (duration: 890.322255ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:56:54.315201Z","caller":"traceutil/trace.go:171","msg":"trace[1534207431] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"1.670146955s","start":"2026-04-17T05:56:52.645043Z","end":"2026-04-17T05:56:54.31519Z","steps":["trace[1534207431] 'process raft request' (duration: 1.481395499s)","trace[1534207431] 'compare' (duration: 188.398248ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:56:54.315312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:56:52.645027Z","time spent":"1.670219817s","remote":"127.0.0.1:47210","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-04-17T05:56:54.31578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.391619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-17T05:56:54.315817Z","caller":"traceutil/trace.go:171","msg":"trace[1432180805] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1278; }","duration":"479.468581ms","start":"2026-04-17T05:56:53.836338Z","end":"2026-04-17T05:56:54.315807Z","steps":["trace[1432180805] 'agreement among raft nodes before linearized reading' (duration: 479.341728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:56:54.315849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:56:53.836311Z","time spent":"479.531404ms","remote":"127.0.0.1:47210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-17T05:56:54.316085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.945123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T05:56:54.316098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.578757015s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-17T05:56:54.316197Z","caller":"traceutil/trace.go:171","msg":"trace[247337805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1278; }","duration":"1.578905208s","start":"2026-04-17T05:56:52.73727Z","end":"2026-04-17T05:56:54.316175Z","steps":["trace[247337805] 'agreement among raft nodes before linearized reading' (duration: 1.577961464s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:56:54.31625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:56:52.737253Z","time spent":"1.57898497s","remote":"127.0.0.1:47210","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-04-17T05:56:54.316354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.581825849s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-17T05:56:54.316391Z","caller":"traceutil/trace.go:171","msg":"trace[447010471] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1278; }","duration":"1.581905541s","start":"2026-04-17T05:56:52.734477Z","end":"2026-04-17T05:56:54.316382Z","steps":["trace[447010471] 'agreement among raft nodes before linearized reading' (duration: 1.581796698s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:56:54.316421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:56:52.734451Z","time spent":"1.581961682s","remote":"127.0.0.1:47210","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":"info","ts":"2026-04-17T05:56:54.316135Z","caller":"traceutil/trace.go:171","msg":"trace[1778606711] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1278; }","duration":"301.037245ms","start":"2026-04-17T05:56:54.015083Z","end":"2026-04-17T05:56:54.31612Z","steps":["trace[1778606711] 'agreement among raft nodes before linearized reading' (duration: 300.959764ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:56:54.31652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.379569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T05:56:54.316551Z","caller":"traceutil/trace.go:171","msg":"trace[827319725] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1278; }","duration":"623.482941ms","start":"2026-04-17T05:56:53.693055Z","end":"2026-04-17T05:56:54.316537Z","steps":["trace[827319725] 'agreement among raft nodes before linearized reading' (duration: 623.43703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:56:54.316578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:56:53.693032Z","time spent":"623.538102ms","remote":"127.0.0.1:46936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T05:56:54.316625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:56:54.015061Z","time spent":"301.472716ms","remote":"127.0.0.1:47146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-04-17T05:56:54.316718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.578724383s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-17T05:56:54.316842Z","caller":"traceutil/trace.go:171","msg":"trace[639455948] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1278; }","duration":"1.578890228s","start":"2026-04-17T05:56:52.737916Z","end":"2026-04-17T05:56:54.316806Z","steps":["trace[639455948] 'agreement among raft nodes before linearized reading' (duration: 1.578473818s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:56:54.316934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:56:52.73791Z","time spent":"1.579007111s","remote":"127.0.0.1:47210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T05:56:54.318225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.578423236s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-17T05:56:54.318308Z","caller":"traceutil/trace.go:171","msg":"trace[775672021] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1278; }","duration":"1.578518698s","start":"2026-04-17T05:56:52.739746Z","end":"2026-04-17T05:56:54.318265Z","steps":["trace[775672021] 'agreement among raft nodes before linearized reading' (duration: 1.576437488s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:56:54.31845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:56:52.739738Z","time spent":"1.57861509s","remote":"127.0.0.1:47210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-17T05:56:54.320533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.193776909s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T05:56:54.320749Z","caller":"traceutil/trace.go:171","msg":"trace[1441616750] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1278; }","duration":"1.194776403s","start":"2026-04-17T05:56:53.125955Z","end":"2026-04-17T05:56:54.320732Z","steps":["trace[1441616750] 'agreement among raft nodes before linearized reading' (duration: 1.190138981s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:56:54.320843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:56:53.125937Z","time spent":"1.194892535s","remote":"127.0.0.1:33040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-04-17T05:56:54.321381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.612728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T05:56:54.321425Z","caller":"traceutil/trace.go:171","msg":"trace[1519000198] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1278; }","duration":"166.660889ms","start":"2026-04-17T05:56:54.154754Z","end":"2026-04-17T05:56:54.321414Z","steps":["trace[1519000198] 'agreement among raft nodes before linearized reading' (duration: 161.229498ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:56:56.678496Z","caller":"traceutil/trace.go:171","msg":"trace[423272784] linearizableReadLoop","detail":"{readStateIndex:1344; appliedIndex:1343; }","duration":"317.642349ms","start":"2026-04-17T05:56:56.360819Z","end":"2026-04-17T05:56:56.678462Z","steps":["trace[423272784] 'read index received' (duration: 316.160693ms)","trace[423272784] 'applied index is now lower than readState.Index' (duration: 1.479536ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:56:56.678529Z","caller":"traceutil/trace.go:171","msg":"trace[1197030257] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"357.1347ms","start":"2026-04-17T05:56:56.321353Z","end":"2026-04-17T05:56:56.678488Z","steps":["trace[1197030257] 'process raft request' (duration: 355.864809ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:56:56.678821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.415083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-17T05:56:56.678838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.266387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-17T05:56:56.678852Z","caller":"traceutil/trace.go:171","msg":"trace[667180973] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1285; }","duration":"275.514185ms","start":"2026-04-17T05:56:56.403328Z","end":"2026-04-17T05:56:56.678843Z","steps":["trace[667180973] 'agreement among raft nodes before linearized reading' (duration: 275.341001ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:56:56.678877Z","caller":"traceutil/trace.go:171","msg":"trace[367271370] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1285; }","duration":"272.345578ms","start":"2026-04-17T05:56:56.406522Z","end":"2026-04-17T05:56:56.678867Z","steps":["trace[367271370] 'agreement among raft nodes before linearized reading' (duration: 272.145573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:56:56.678905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.728952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-17T05:56:56.678981Z","caller":"traceutil/trace.go:171","msg":"trace[967305073] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1285; }","duration":"273.831695ms","start":"2026-04-17T05:56:56.405131Z","end":"2026-04-17T05:56:56.678963Z","steps":["trace[967305073] 'agreement among raft nodes before linearized reading' (duration: 273.62457ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:56:56.679037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:56:56.321324Z","time spent":"357.303743ms","remote":"127.0.0.1:47210","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-04-17T05:56:56.679614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.803748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-17T05:56:56.679647Z","caller":"traceutil/trace.go:171","msg":"trace[237408102] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1285; }","duration":"318.838419ms","start":"2026-04-17T05:56:56.360801Z","end":"2026-04-17T05:56:56.679639Z","steps":["trace[237408102] 'agreement among raft nodes before linearized reading' (duration: 318.734296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:56:56.679667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:56:56.360742Z","time spent":"318.92047ms","remote":"127.0.0.1:47210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-17T05:56:56.679764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.951951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-17T05:56:56.679856Z","caller":"traceutil/trace.go:171","msg":"trace[527802631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1285; }","duration":"275.115315ms","start":"2026-04-17T05:56:56.404723Z","end":"2026-04-17T05:56:56.679838Z","steps":["trace[527802631] 'agreement among raft nodes before linearized reading' (duration: 274.798428ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:56:56.804944Z","caller":"traceutil/trace.go:171","msg":"trace[1873326769] linearizableReadLoop","detail":"{readStateIndex:1349; appliedIndex:1344; }","duration":"112.886633ms","start":"2026-04-17T05:56:56.692041Z","end":"2026-04-17T05:56:56.804928Z","steps":["trace[1873326769] 'read index received' (duration: 77.172425ms)","trace[1873326769] 'applied index is now lower than readState.Index' (duration: 35.713598ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:56:56.805058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.007646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T05:56:56.805078Z","caller":"traceutil/trace.go:171","msg":"trace[1907113439] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1290; }","duration":"113.074898ms","start":"2026-04-17T05:56:56.691998Z","end":"2026-04-17T05:56:56.805073Z","steps":["trace[1907113439] 'agreement among raft nodes before linearized reading' (duration: 112.995866ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:56:56.805363Z","caller":"traceutil/trace.go:171","msg":"trace[4764819] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"119.722079ms","start":"2026-04-17T05:56:56.685634Z","end":"2026-04-17T05:56:56.805356Z","steps":["trace[4764819] 'process raft request' (duration: 119.154486ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:56:56.805439Z","caller":"traceutil/trace.go:171","msg":"trace[1013243209] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"119.718329ms","start":"2026-04-17T05:56:56.685714Z","end":"2026-04-17T05:56:56.805433Z","steps":["trace[1013243209] 'process raft request' (duration: 119.173426ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:56:56.805505Z","caller":"traceutil/trace.go:171","msg":"trace[572606660] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"118.728005ms","start":"2026-04-17T05:56:56.686772Z","end":"2026-04-17T05:56:56.8055Z","steps":["trace[572606660] 'process raft request' (duration: 118.133491ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:56:57.876732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.051898ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T05:56:57.876864Z","caller":"traceutil/trace.go:171","msg":"trace[105150352] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1297; }","duration":"620.214203ms","start":"2026-04-17T05:56:57.256631Z","end":"2026-04-17T05:56:57.876845Z","steps":["trace[105150352] 'range keys from in-memory index tree' (duration: 620.036488ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:57:05.05306Z","caller":"traceutil/trace.go:171","msg":"trace[1993539515] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"121.325618ms","start":"2026-04-17T05:57:04.93171Z","end":"2026-04-17T05:57:05.053036Z","steps":["trace[1993539515] 'process raft request' (duration: 57.024695ms)","trace[1993539515] 'compare' (duration: 64.114409ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:57:05.053462Z","caller":"traceutil/trace.go:171","msg":"trace[296577240] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"121.113253ms","start":"2026-04-17T05:57:04.932312Z","end":"2026-04-17T05:57:05.053425Z","steps":["trace[296577240] 'process raft request' (duration: 120.681053ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:57:09.353227Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225556512069799,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T05:57:09.435167Z","caller":"traceutil/trace.go:171","msg":"trace[73563892] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"615.308723ms","start":"2026-04-17T05:57:08.819832Z","end":"2026-04-17T05:57:09.43514Z","steps":["trace[73563892] 'process raft request' (duration: 615.133888ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:57:09.435343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:57:08.819807Z","time spent":"615.434906ms","remote":"127.0.0.1:47210","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-04-17T05:57:10.57709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.54319ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T05:57:10.577285Z","caller":"traceutil/trace.go:171","msg":"trace[1524445135] linearizableReadLoop","detail":"{readStateIndex:1434; appliedIndex:1432; }","duration":"1.724795213s","start":"2026-04-17T05:57:08.852469Z","end":"2026-04-17T05:57:10.577264Z","steps":["trace[1524445135] 'read index received' (duration: 582.565327ms)","trace[1524445135] 'applied index is now lower than readState.Index' (duration: 1.142228476s)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:57:10.577609Z","caller":"traceutil/trace.go:171","msg":"trace[1090544624] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"1.325372087s","start":"2026-04-17T05:57:09.252225Z","end":"2026-04-17T05:57:10.577597Z","steps":["trace[1090544624] 'process raft request' (duration: 1.155211293s)","trace[1090544624] 'compare' (duration: 169.333414ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:57:10.577686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:57:09.252202Z","time spent":"1.325456568s","remote":"127.0.0.1:47210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T05:57:10.577844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.663870262s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-17T05:57:10.577931Z","caller":"traceutil/trace.go:171","msg":"trace[934696820] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1372; }","duration":"1.664029426s","start":"2026-04-17T05:57:08.913882Z","end":"2026-04-17T05:57:10.577911Z","steps":["trace[934696820] 'agreement among raft nodes before linearized reading' (duration: 1.663770629s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:57:10.577984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:57:08.913862Z","time spent":"1.664112609s","remote":"127.0.0.1:47210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-17T05:57:10.578146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.469640373s","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-04-17T05:57:10.578164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.617054726s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-17T05:57:10.578195Z","caller":"traceutil/trace.go:171","msg":"trace[243174514] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1372; }","duration":"1.617121108s","start":"2026-04-17T05:57:08.961066Z","end":"2026-04-17T05:57:10.578187Z","steps":["trace[243174514] 'agreement among raft nodes before linearized reading' (duration: 1.617027786s)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:57:10.578197Z","caller":"traceutil/trace.go:171","msg":"trace[586071363] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1372; }","duration":"1.469740766s","start":"2026-04-17T05:57:09.108444Z","end":"2026-04-17T05:57:10.578184Z","steps":["trace[586071363] 'agreement among raft nodes before linearized reading' (duration: 1.469578112s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:57:10.578228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:57:08.96105Z","time spent":"1.617173299s","remote":"127.0.0.1:47210","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-04-17T05:57:10.578237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:57:09.108418Z","time spent":"1.469813077s","remote":"127.0.0.1:47210","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-04-17T05:57:10.578216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.388408309s","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:531"} {"level":"warn","ts":"2026-04-17T05:57:10.578295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.725804958s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-17T05:57:10.578388Z","caller":"traceutil/trace.go:171","msg":"trace[1980678879] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1372; }","duration":"1.725949171s","start":"2026-04-17T05:57:08.852418Z","end":"2026-04-17T05:57:10.578367Z","steps":["trace[1980678879] 'agreement among raft nodes before linearized reading' (duration: 1.725693815s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:57:10.578369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.938241ms","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":"warn","ts":"2026-04-17T05:57:10.578451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:57:08.852398Z","time spent":"1.726033903s","remote":"127.0.0.1:47210","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":"info","ts":"2026-04-17T05:57:10.578453Z","caller":"traceutil/trace.go:171","msg":"trace[1074313716] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1372; }","duration":"733.064454ms","start":"2026-04-17T05:57:09.845366Z","end":"2026-04-17T05:57:10.578431Z","steps":["trace[1074313716] 'agreement among raft nodes before linearized reading' (duration: 732.89951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:57:10.578507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:57:09.845345Z","time spent":"733.149036ms","remote":"127.0.0.1:47210","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":"info","ts":"2026-04-17T05:57:10.578323Z","caller":"traceutil/trace.go:171","msg":"trace[2126644736] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1372; }","duration":"1.388550973s","start":"2026-04-17T05:57:09.189747Z","end":"2026-04-17T05:57:10.578298Z","steps":["trace[2126644736] 'agreement among raft nodes before linearized reading' (duration: 1.388250676s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:57:10.578213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.439639064s","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-04-17T05:57:10.578651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:57:09.189734Z","time spent":"1.388901311s","remote":"127.0.0.1:47210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-04-17T05:57:10.578742Z","caller":"traceutil/trace.go:171","msg":"trace[44184409] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1372; }","duration":"1.440184776s","start":"2026-04-17T05:57:09.138516Z","end":"2026-04-17T05:57:10.578701Z","steps":["trace[44184409] 'agreement among raft nodes before linearized reading' (duration: 1.439556051s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:57:10.578898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:57:09.138505Z","time spent":"1.440341871s","remote":"127.0.0.1:47210","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-04-17T05:57:13.161774Z","caller":"traceutil/trace.go:171","msg":"trace[1613939995] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"244.540722ms","start":"2026-04-17T05:57:12.917195Z","end":"2026-04-17T05:57:13.161735Z","steps":["trace[1613939995] 'process raft request' (duration: 244.319107ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:57:23.245332Z","caller":"traceutil/trace.go:171","msg":"trace[1975380029] transaction","detail":"{read_only:false; response_revision:1435; number_of_response:1; }","duration":"145.734459ms","start":"2026-04-17T05:57:23.099573Z","end":"2026-04-17T05:57:23.245307Z","steps":["trace[1975380029] 'process raft request' (duration: 145.575185ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:57:34.97111Z","caller":"traceutil/trace.go:171","msg":"trace[1084514724] transaction","detail":"{read_only:false; response_revision:1488; number_of_response:1; }","duration":"127.870396ms","start":"2026-04-17T05:57:34.84321Z","end":"2026-04-17T05:57:34.97108Z","steps":["trace[1084514724] 'process raft request' (duration: 123.48899ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:57:34.971798Z","caller":"traceutil/trace.go:171","msg":"trace[1307837851] transaction","detail":"{read_only:false; response_revision:1490; number_of_response:1; }","duration":"127.869285ms","start":"2026-04-17T05:57:34.843915Z","end":"2026-04-17T05:57:34.971784Z","steps":["trace[1307837851] 'process raft request' (duration: 127.812313ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:57:34.972068Z","caller":"traceutil/trace.go:171","msg":"trace[619509241] transaction","detail":"{read_only:false; response_revision:1489; number_of_response:1; }","duration":"128.213413ms","start":"2026-04-17T05:57:34.843831Z","end":"2026-04-17T05:57:34.972044Z","steps":["trace[619509241] 'process raft request' (duration: 127.185188ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:57:39.698127Z","caller":"traceutil/trace.go:171","msg":"trace[690109776] transaction","detail":"{read_only:false; response_revision:1510; number_of_response:1; }","duration":"107.951021ms","start":"2026-04-17T05:57:39.59014Z","end":"2026-04-17T05:57:39.698091Z","steps":["trace[690109776] 'process raft request' (duration: 107.733266ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:57:42.933614Z","caller":"traceutil/trace.go:171","msg":"trace[613118641] transaction","detail":"{read_only:false; response_revision:1523; number_of_response:1; }","duration":"123.889979ms","start":"2026-04-17T05:57:42.809694Z","end":"2026-04-17T05:57:42.933584Z","steps":["trace[613118641] 'process raft request' (duration: 105.299668ms)","trace[613118641] 'compare' (duration: 18.331204ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:57:51.05483Z","caller":"traceutil/trace.go:171","msg":"trace[746406919] transaction","detail":"{read_only:false; response_revision:1558; number_of_response:1; }","duration":"202.234551ms","start":"2026-04-17T05:57:50.852567Z","end":"2026-04-17T05:57:51.054801Z","steps":["trace[746406919] 'process raft request' (duration: 202.088878ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:57:51.193012Z","caller":"traceutil/trace.go:171","msg":"trace[787833682] transaction","detail":"{read_only:false; response_revision:1559; number_of_response:1; }","duration":"134.606639ms","start":"2026-04-17T05:57:51.058374Z","end":"2026-04-17T05:57:51.19298Z","steps":["trace[787833682] 'process raft request' (duration: 131.627596ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:57:51.193047Z","caller":"traceutil/trace.go:171","msg":"trace[336335756] transaction","detail":"{read_only:false; response_revision:1560; number_of_response:1; }","duration":"132.993941ms","start":"2026-04-17T05:57:51.060029Z","end":"2026-04-17T05:57:51.193023Z","steps":["trace[336335756] 'process raft request' (duration: 132.866528ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:27.438701Z","caller":"traceutil/trace.go:171","msg":"trace[1035392203] linearizableReadLoop","detail":"{readStateIndex:1802; appliedIndex:1801; }","duration":"101.645603ms","start":"2026-04-17T05:58:27.337031Z","end":"2026-04-17T05:58:27.438676Z","steps":["trace[1035392203] 'read index received' (duration: 65.547645ms)","trace[1035392203] 'applied index is now lower than readState.Index' (duration: 36.096798ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:58:27.438845Z","caller":"traceutil/trace.go:171","msg":"trace[317751135] transaction","detail":"{read_only:false; response_revision:1724; number_of_response:1; }","duration":"187.751098ms","start":"2026-04-17T05:58:27.251042Z","end":"2026-04-17T05:58:27.438793Z","steps":["trace[317751135] 'process raft request' (duration: 151.525876ms)","trace[317751135] 'compare' (duration: 35.982646ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:58:27.439021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.983901ms","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-04-17T05:58:27.439211Z","caller":"traceutil/trace.go:171","msg":"trace[1569533058] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1724; }","duration":"102.208276ms","start":"2026-04-17T05:58:27.336976Z","end":"2026-04-17T05:58:27.439184Z","steps":["trace[1569533058] 'agreement among raft nodes before linearized reading' (duration: 101.805147ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:27.654135Z","caller":"traceutil/trace.go:171","msg":"trace[1355335288] transaction","detail":"{read_only:false; response_revision:1731; number_of_response:1; }","duration":"140.631051ms","start":"2026-04-17T05:58:27.513476Z","end":"2026-04-17T05:58:27.654107Z","steps":["trace[1355335288] 'process raft request' (duration: 140.5845ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:27.654156Z","caller":"traceutil/trace.go:171","msg":"trace[299795672] transaction","detail":"{read_only:false; response_revision:1730; number_of_response:1; }","duration":"140.720513ms","start":"2026-04-17T05:58:27.513399Z","end":"2026-04-17T05:58:27.65412Z","steps":["trace[299795672] 'process raft request' (duration: 140.631051ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:27.654145Z","caller":"traceutil/trace.go:171","msg":"trace[675791529] transaction","detail":"{read_only:false; response_revision:1728; number_of_response:1; }","duration":"140.754364ms","start":"2026-04-17T05:58:27.513353Z","end":"2026-04-17T05:58:27.654108Z","steps":["trace[675791529] 'process raft request' (duration: 140.265533ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:27.654354Z","caller":"traceutil/trace.go:171","msg":"trace[1660563204] transaction","detail":"{read_only:false; response_revision:1729; number_of_response:1; }","duration":"140.962389ms","start":"2026-04-17T05:58:27.513365Z","end":"2026-04-17T05:58:27.654328Z","steps":["trace[1660563204] 'process raft request' (duration: 140.627941ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:55.923208Z","caller":"traceutil/trace.go:171","msg":"trace[1649719379] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"119.497486ms","start":"2026-04-17T05:58:55.803674Z","end":"2026-04-17T05:58:55.923171Z","steps":["trace[1649719379] 'process raft request' (duration: 119.284291ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:55.923438Z","caller":"traceutil/trace.go:171","msg":"trace[234868278] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"117.840757ms","start":"2026-04-17T05:58:55.805569Z","end":"2026-04-17T05:58:55.923409Z","steps":["trace[234868278] 'process raft request' (duration: 117.55853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:58:56.138851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.162361ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T05:58:56.139092Z","caller":"traceutil/trace.go:171","msg":"trace[1466787059] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"113.277316ms","start":"2026-04-17T05:58:56.025793Z","end":"2026-04-17T05:58:56.13907Z","steps":["trace[1466787059] 'process raft request' (duration: 113.210094ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:58:56.139177Z","caller":"traceutil/trace.go:171","msg":"trace[450996758] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"211.208658ms","start":"2026-04-17T05:58:55.927901Z","end":"2026-04-17T05:58:56.13911Z","steps":["trace[450996758] 'process raft request' (duration: 72.676648ms)","trace[450996758] 'compare' (duration: 137.992107ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:59:13.951374Z","caller":"traceutil/trace.go:171","msg":"trace[224808012] linearizableReadLoop","detail":"{readStateIndex:2024; appliedIndex:2023; }","duration":"137.809112ms","start":"2026-04-17T05:59:13.81353Z","end":"2026-04-17T05:59:13.951339Z","steps":["trace[224808012] 'read index received' (duration: 137.551356ms)","trace[224808012] 'applied index is now lower than readState.Index' (duration: 256.486µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:59:13.951458Z","caller":"traceutil/trace.go:171","msg":"trace[330670855] transaction","detail":"{read_only:false; response_revision:1937; number_of_response:1; }","duration":"144.019784ms","start":"2026-04-17T05:59:13.8074Z","end":"2026-04-17T05:59:13.95142Z","steps":["trace[330670855] 'process raft request' (duration: 143.729827ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:59:13.951711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.168951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-17T05:59:13.951761Z","caller":"traceutil/trace.go:171","msg":"trace[960878526] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1937; }","duration":"138.297264ms","start":"2026-04-17T05:59:13.813448Z","end":"2026-04-17T05:59:13.951745Z","steps":["trace[960878526] 'agreement among raft nodes before linearized reading' (duration: 138.107489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:59:13.951847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.70302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-17T05:59:13.95189Z","caller":"traceutil/trace.go:171","msg":"trace[54678298] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1937; }","duration":"137.786852ms","start":"2026-04-17T05:59:13.814091Z","end":"2026-04-17T05:59:13.951878Z","steps":["trace[54678298] 'agreement among raft nodes before linearized reading' (duration: 137.590167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:59:14.18479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.763422ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T05:59:14.185008Z","caller":"traceutil/trace.go:171","msg":"trace[1411560859] transaction","detail":"{read_only:false; response_revision:1940; number_of_response:1; }","duration":"138.748825ms","start":"2026-04-17T05:59:14.046234Z","end":"2026-04-17T05:59:14.184983Z","steps":["trace[1411560859] 'process raft request' (duration: 138.688614ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:59:14.185061Z","caller":"traceutil/trace.go:171","msg":"trace[2146603473] transaction","detail":"{read_only:false; response_revision:1939; number_of_response:1; }","duration":"227.446093ms","start":"2026-04-17T05:59:13.957581Z","end":"2026-04-17T05:59:14.185027Z","steps":["trace[2146603473] 'process raft request' (duration: 112.294302ms)","trace[2146603473] 'compare' (duration: 114.342312ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:59:14.185061Z","caller":"traceutil/trace.go:171","msg":"trace[818218921] linearizableReadLoop","detail":"{readStateIndex:2026; appliedIndex:2024; }","duration":"207.964649ms","start":"2026-04-17T05:59:13.977064Z","end":"2026-04-17T05:59:14.185028Z","steps":["trace[818218921] 'read index received' (duration: 40.371892ms)","trace[818218921] 'applied index is now lower than readState.Index' (duration: 167.589607ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:59:14.185504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.42042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-17T05:59:14.185573Z","caller":"traceutil/trace.go:171","msg":"trace[531936910] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1940; }","duration":"208.512262ms","start":"2026-04-17T05:59:13.977046Z","end":"2026-04-17T05:59:14.185559Z","steps":["trace[531936910] 'agreement among raft nodes before linearized reading' (duration: 208.261006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:59:14.185581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.913428ms","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-04-17T05:59:14.18561Z","caller":"traceutil/trace.go:171","msg":"trace[51746861] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1940; }","duration":"171.00331ms","start":"2026-04-17T05:59:14.014597Z","end":"2026-04-17T05:59:14.185601Z","steps":["trace[51746861] 'agreement among raft nodes before linearized reading' (duration: 170.833886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:59:14.185705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.431505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-17T05:59:14.185727Z","caller":"traceutil/trace.go:171","msg":"trace[384118044] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1940; }","duration":"135.503326ms","start":"2026-04-17T05:59:14.050219Z","end":"2026-04-17T05:59:14.185722Z","steps":["trace[384118044] 'agreement among raft nodes before linearized reading' (duration: 135.446665ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:59:15.164348Z","caller":"traceutil/trace.go:171","msg":"trace[996451987] transaction","detail":"{read_only:false; response_revision:1945; number_of_response:1; }","duration":"117.478898ms","start":"2026-04-17T05:59:15.046834Z","end":"2026-04-17T05:59:15.164313Z","steps":["trace[996451987] 'process raft request' (duration: 117.249803ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:59:20.394625Z","caller":"traceutil/trace.go:171","msg":"trace[822834664] transaction","detail":"{read_only:false; response_revision:1969; number_of_response:1; }","duration":"104.187665ms","start":"2026-04-17T05:59:20.290269Z","end":"2026-04-17T05:59:20.394457Z","steps":["trace[822834664] 'process raft request' (duration: 103.909938ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T05:59:20.394974Z","caller":"traceutil/trace.go:171","msg":"trace[1499399192] transaction","detail":"{read_only:false; response_revision:1970; number_of_response:1; }","duration":"103.850426ms","start":"2026-04-17T05:59:20.291075Z","end":"2026-04-17T05:59:20.394925Z","steps":["trace[1499399192] 'process raft request' (duration: 103.205861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T05:59:50.843807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.045812ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T05:59:50.843999Z","caller":"traceutil/trace.go:171","msg":"trace[1215106355] linearizableReadLoop","detail":"{readStateIndex:2202; appliedIndex:2201; }","duration":"147.125707ms","start":"2026-04-17T05:59:50.69685Z","end":"2026-04-17T05:59:50.843975Z","steps":["trace[1215106355] 'read index received' (duration: 29.677425ms)","trace[1215106355] 'applied index is now lower than readState.Index' (duration: 117.446232ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T05:59:50.844134Z","caller":"traceutil/trace.go:171","msg":"trace[1670330287] transaction","detail":"{read_only:false; response_revision:2108; number_of_response:1; }","duration":"196.619027ms","start":"2026-04-17T05:59:50.647437Z","end":"2026-04-17T05:59:50.844056Z","steps":["trace[1670330287] 'process raft request' (duration: 79.212826ms)","trace[1670330287] 'compare' (duration: 116.819097ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T05:59:50.844183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.317391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-17T05:59:50.844514Z","caller":"traceutil/trace.go:171","msg":"trace[1502367259] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2108; }","duration":"147.524477ms","start":"2026-04-17T05:59:50.696821Z","end":"2026-04-17T05:59:50.844345Z","steps":["trace[1502367259] 'agreement among raft nodes before linearized reading' (duration: 147.23222ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:13.217135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.219602ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T06:00:13.21726Z","caller":"traceutil/trace.go:171","msg":"trace[1771250071] linearizableReadLoop","detail":"{readStateIndex:2325; appliedIndex:2324; }","duration":"106.977895ms","start":"2026-04-17T06:00:13.110261Z","end":"2026-04-17T06:00:13.217239Z","steps":["trace[1771250071] 'read index received' (duration: 66.352µs)","trace[1771250071] 'applied index is now lower than readState.Index' (duration: 106.910403ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T06:00:13.217326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.071327ms","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-04-17T06:00:13.217315Z","caller":"traceutil/trace.go:171","msg":"trace[150676472] transaction","detail":"{read_only:false; response_revision:2225; number_of_response:1; }","duration":"226.764635ms","start":"2026-04-17T06:00:12.99053Z","end":"2026-04-17T06:00:13.217295Z","steps":["trace[150676472] 'process raft request' (duration: 54.295727ms)","trace[150676472] 'compare' (duration: 172.091409ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:00:13.217359Z","caller":"traceutil/trace.go:171","msg":"trace[446159761] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:2225; }","duration":"107.15516ms","start":"2026-04-17T06:00:13.110194Z","end":"2026-04-17T06:00:13.217349Z","steps":["trace[446159761] 'agreement among raft nodes before linearized reading' (duration: 107.095759ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:00:26.825155Z","caller":"traceutil/trace.go:171","msg":"trace[1963708235] linearizableReadLoop","detail":"{readStateIndex:2400; appliedIndex:2399; }","duration":"390.289043ms","start":"2026-04-17T06:00:26.434841Z","end":"2026-04-17T06:00:26.82513Z","steps":["trace[1963708235] 'read index received' (duration: 390.218171ms)","trace[1963708235] 'applied index is now lower than readState.Index' (duration: 69.802µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T06:00:26.825317Z","caller":"traceutil/trace.go:171","msg":"trace[1758037175] transaction","detail":"{read_only:false; response_revision:2298; number_of_response:1; }","duration":"393.830289ms","start":"2026-04-17T06:00:26.431422Z","end":"2026-04-17T06:00:26.825252Z","steps":["trace[1758037175] 'process raft request' (duration: 393.556502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:26.825517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.664171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-17T06:00:26.825552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:26.431403Z","time spent":"394.018993ms","remote":"127.0.0.1:47210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T06:00:26.825594Z","caller":"traceutil/trace.go:171","msg":"trace[1749715703] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2298; }","duration":"390.802486ms","start":"2026-04-17T06:00:26.434779Z","end":"2026-04-17T06:00:26.825582Z","steps":["trace[1749715703] 'agreement among raft nodes before linearized reading' (duration: 390.487377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:26.825678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:26.434752Z","time spent":"390.913509ms","remote":"127.0.0.1:47210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-17T06:00:26.900067Z","caller":"traceutil/trace.go:171","msg":"trace[1857657627] transaction","detail":"{read_only:false; response_revision:2299; number_of_response:1; }","duration":"170.194902ms","start":"2026-04-17T06:00:26.729838Z","end":"2026-04-17T06:00:26.900033Z","steps":["trace[1857657627] 'process raft request' (duration: 167.235039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:26.900149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.146777ms","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-04-17T06:00:26.900249Z","caller":"traceutil/trace.go:171","msg":"trace[806937376] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2300; }","duration":"457.293601ms","start":"2026-04-17T06:00:26.442925Z","end":"2026-04-17T06:00:26.900219Z","steps":["trace[806937376] 'agreement among raft nodes before linearized reading' (duration: 456.973303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:26.900307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:00:26.442892Z","time spent":"457.402523ms","remote":"127.0.0.1:47210","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-04-17T06:00:26.900377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.132874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:00:26.900406Z","caller":"traceutil/trace.go:171","msg":"trace[1122133458] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2300; }","duration":"207.198766ms","start":"2026-04-17T06:00:26.693194Z","end":"2026-04-17T06:00:26.900393Z","steps":["trace[1122133458] 'agreement among raft nodes before linearized reading' (duration: 207.137785ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:26.900555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.604044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T06:00:26.900575Z","caller":"traceutil/trace.go:171","msg":"trace[1015709153] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:2300; }","duration":"110.657606ms","start":"2026-04-17T06:00:26.789913Z","end":"2026-04-17T06:00:26.90057Z","steps":["trace[1015709153] 'agreement among raft nodes before linearized reading' (duration: 110.624655ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T06:00:26.900583Z","caller":"traceutil/trace.go:171","msg":"trace[937958192] transaction","detail":"{read_only:false; response_revision:2300; number_of_response:1; }","duration":"155.803139ms","start":"2026-04-17T06:00:26.744715Z","end":"2026-04-17T06:00:26.900518Z","steps":["trace[937958192] 'process raft request' (duration: 155.117733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:26.900711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.086577ms","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-04-17T06:00:26.900823Z","caller":"traceutil/trace.go:171","msg":"trace[1176845414] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:2300; }","duration":"208.202ms","start":"2026-04-17T06:00:26.692605Z","end":"2026-04-17T06:00:26.900807Z","steps":["trace[1176845414] 'agreement among raft nodes before linearized reading' (duration: 208.056237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T06:00:26.900876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.04834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-17T06:00:26.900897Z","caller":"traceutil/trace.go:171","msg":"trace[1924147224] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2300; }","duration":"247.108341ms","start":"2026-04-17T06:00:26.653783Z","end":"2026-04-17T06:00:26.900891Z","steps":["trace[1924147224] 'agreement among raft nodes before linearized reading' (duration: 247.017189ms)"],"step_count":1}