{"level":"warn","ts":"2026-05-20T17:22:40.471356Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-20T17:22:40.471502Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.216: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.216:2380","--initial-cluster=instance=https://199.204.45.216:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.216:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.216: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-05-20T17:22:40.471604Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-20T17:22:40.471618Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-05-20T17:22:40.471646Z","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-05-20T17:22:40.4724Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"]} {"level":"info","ts":"2026-05-20T17:22:40.472491Z","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.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.216: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-05-20T17:22:40.476664Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.882415ms"} {"level":"info","ts":"2026-05-20T17:22:40.487235Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e98569549cc430c","cluster-id":"65d70b4d1d4a8bf9"} {"level":"info","ts":"2026-05-20T17:22:40.487335Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=()"} {"level":"info","ts":"2026-05-20T17:22:40.487361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 0"} {"level":"info","ts":"2026-05-20T17:22:40.487392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e98569549cc430c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-20T17:22:40.48741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 1"} {"level":"info","ts":"2026-05-20T17:22:40.487457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"warn","ts":"2026-05-20T17:22:40.496447Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-20T17:22:40.498292Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-20T17:22:40.499666Z","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-05-20T17:22:40.502011Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e98569549cc430c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-20T17:22:40.502343Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e98569549cc430c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-20T17:22:40.502736Z","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-05-20T17:22:40.502857Z","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-05-20T17:22:40.502889Z","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-05-20T17:22:40.505253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"info","ts":"2026-05-20T17:22:40.505453Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","added-peer-id":"4e98569549cc430c","added-peer-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-05-20T17:22:40.506804Z","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-05-20T17:22:40.506899Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-05-20T17:22:40.50693Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-05-20T17:22:40.507351Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e98569549cc430c","initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-20T17:22:40.507388Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-20T17:22:40.988375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c is starting a new election at term 1"} {"level":"info","ts":"2026-05-20T17:22:40.988423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became pre-candidate at term 1"} {"level":"info","ts":"2026-05-20T17:22:40.98845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgPreVoteResp from 4e98569549cc430c at term 1"} {"level":"info","ts":"2026-05-20T17:22:40.988462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became candidate at term 2"} {"level":"info","ts":"2026-05-20T17:22:40.98847Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgVoteResp from 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-05-20T17:22:40.988483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became leader at term 2"} {"level":"info","ts":"2026-05-20T17:22:40.98849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e98569549cc430c elected leader 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-05-20T17:22:40.989241Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e98569549cc430c","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.216:2379]}","request-path":"/0/members/4e98569549cc430c/attributes","cluster-id":"65d70b4d1d4a8bf9","publish-timeout":"7s"} {"level":"info","ts":"2026-05-20T17:22:40.989386Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T17:22:40.989476Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-20T17:22:40.989515Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-20T17:22:40.989536Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-20T17:22:40.989945Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T17:22:40.989997Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T17:22:40.990018Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T17:22:40.990332Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-20T17:22:40.992403Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-20T17:22:40.99325Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.216:2379"} {"level":"info","ts":"2026-05-20T17:22:51.374519Z","caller":"traceutil/trace.go:171","msg":"trace[1831014871] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"461.70185ms","start":"2026-05-20T17:22:50.912792Z","end":"2026-05-20T17:22:51.374494Z","steps":["trace[1831014871] 'process raft request' (duration: 461.550354ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:22:51.375093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:22:50.912769Z","time spent":"461.848926ms","remote":"127.0.0.1:41500","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-05-20T17:22:51.583377Z","caller":"traceutil/trace.go:171","msg":"trace[900244323] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"147.894489ms","start":"2026-05-20T17:22:51.435461Z","end":"2026-05-20T17:22:51.583355Z","steps":["trace[900244323] 'process raft request' (duration: 144.339784ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:22:53.877162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.650215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-20T17:22:53.877354Z","caller":"traceutil/trace.go:171","msg":"trace[1530885797] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:361; }","duration":"239.889273ms","start":"2026-05-20T17:22:53.637441Z","end":"2026-05-20T17:22:53.877331Z","steps":["trace[1530885797] 'range keys from in-memory index tree' (duration: 239.534341ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:22:59.651675Z","caller":"traceutil/trace.go:171","msg":"trace[556500492] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"144.77431ms","start":"2026-05-20T17:22:59.506878Z","end":"2026-05-20T17:22:59.651653Z","steps":["trace[556500492] 'process raft request' (duration: 144.652535ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:23:00.115664Z","caller":"traceutil/trace.go:171","msg":"trace[771281199] linearizableReadLoop","detail":"{readStateIndex:389; appliedIndex:387; }","duration":"469.989456ms","start":"2026-05-20T17:22:59.645638Z","end":"2026-05-20T17:23:00.115628Z","steps":["trace[771281199] 'read index received' (duration: 5.876608ms)","trace[771281199] 'applied index is now lower than readState.Index' (duration: 464.111198ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T17:23:00.115814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.146082ms","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-05-20T17:23:00.115955Z","caller":"traceutil/trace.go:171","msg":"trace[810245890] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:376; }","duration":"470.353959ms","start":"2026-05-20T17:22:59.645586Z","end":"2026-05-20T17:23:00.11594Z","steps":["trace[810245890] 'agreement among raft nodes before linearized reading' (duration: 470.10645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:00.116005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:22:59.645565Z","time spent":"470.424322ms","remote":"127.0.0.1:41500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-20T17:23:00.116171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.012425ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:23:00.116316Z","caller":"traceutil/trace.go:171","msg":"trace[1210405873] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:376; }","duration":"384.176501ms","start":"2026-05-20T17:22:59.732117Z","end":"2026-05-20T17:23:00.116293Z","steps":["trace[1210405873] 'agreement among raft nodes before linearized reading' (duration: 383.977274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:00.116292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.519119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-20T17:23:00.116445Z","caller":"traceutil/trace.go:171","msg":"trace[1814731710] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:376; }","duration":"208.718275ms","start":"2026-05-20T17:22:59.907695Z","end":"2026-05-20T17:23:00.116413Z","steps":["trace[1814731710] 'agreement among raft nodes before linearized reading' (duration: 208.454276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:02.457767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.118043ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T17:23:02.457919Z","caller":"traceutil/trace.go:171","msg":"trace[869682376] linearizableReadLoop","detail":"{readStateIndex:397; appliedIndex:396; }","duration":"286.290093ms","start":"2026-05-20T17:23:02.171585Z","end":"2026-05-20T17:23:02.457876Z","steps":["trace[869682376] 'read index received' (duration: 14.776359ms)","trace[869682376] 'applied index is now lower than readState.Index' (duration: 271.512444ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T17:23:02.457938Z","caller":"traceutil/trace.go:171","msg":"trace[1048939030] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"325.623446ms","start":"2026-05-20T17:23:02.132279Z","end":"2026-05-20T17:23:02.457903Z","steps":["trace[1048939030] 'process raft request' (duration: 73.947444ms)","trace[1048939030] 'compare' (duration: 250.642336ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T17:23:02.458032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.448068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:23:02.458088Z","caller":"traceutil/trace.go:171","msg":"trace[2036627669] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:384; }","duration":"286.50943ms","start":"2026-05-20T17:23:02.171546Z","end":"2026-05-20T17:23:02.458055Z","steps":["trace[2036627669] 'agreement among raft nodes before linearized reading' (duration: 286.407657ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:02.458092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:23:02.132252Z","time spent":"325.755131ms","remote":"127.0.0.1:41500","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-05-20T17:23:03.199967Z","caller":"traceutil/trace.go:171","msg":"trace[947204221] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"147.123809ms","start":"2026-05-20T17:23:03.052541Z","end":"2026-05-20T17:23:03.199665Z","steps":["trace[947204221] 'process raft request' (duration: 146.992204ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:23:05.056362Z","caller":"traceutil/trace.go:171","msg":"trace[1183970241] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"313.674869ms","start":"2026-05-20T17:23:04.74266Z","end":"2026-05-20T17:23:05.056334Z","steps":["trace[1183970241] 'process raft request' (duration: 313.504604ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:05.056663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:23:04.742622Z","time spent":"313.967229ms","remote":"127.0.0.1:41500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T17:23:05.911976Z","caller":"traceutil/trace.go:171","msg":"trace[1379804967] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"107.663167ms","start":"2026-05-20T17:23:05.804295Z","end":"2026-05-20T17:23:05.911958Z","steps":["trace[1379804967] 'process raft request' (duration: 107.555543ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:06.980084Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410525511674510,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T17:23:07.481266Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410525511674510,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T17:23:07.874911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.702310042s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:23:07.874975Z","caller":"traceutil/trace.go:171","msg":"trace[802570961] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:392; }","duration":"1.702423686s","start":"2026-05-20T17:23:06.172538Z","end":"2026-05-20T17:23:07.874962Z","steps":["trace[802570961] 'range keys from in-memory index tree' (duration: 1.702190919s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:07.875003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:23:06.172518Z","time spent":"1.702479988s","remote":"127.0.0.1:41216","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-05-20T17:23:07.875159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.643002042s","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-05-20T17:23:07.875192Z","caller":"traceutil/trace.go:171","msg":"trace[1746534773] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:392; }","duration":"1.643085695s","start":"2026-05-20T17:23:06.232101Z","end":"2026-05-20T17:23:07.875186Z","steps":["trace[1746534773] 'range keys from in-memory index tree' (duration: 1.64290333s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:07.875226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:23:06.232071Z","time spent":"1.643150697s","remote":"127.0.0.1:41500","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-05-20T17:23:07.875417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.631903ms","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-05-20T17:23:07.87547Z","caller":"traceutil/trace.go:171","msg":"trace[1581505275] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:392; }","duration":"684.683364ms","start":"2026-05-20T17:23:07.190778Z","end":"2026-05-20T17:23:07.875461Z","steps":["trace[1581505275] 'range keys from in-memory index tree' (duration: 684.625172ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:07.982359Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410525511674510,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T17:23:08.005101Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.749695689s","expected-duration":"1s"} {"level":"info","ts":"2026-05-20T17:23:08.005584Z","caller":"traceutil/trace.go:171","msg":"trace[832750117] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"1.75035215s","start":"2026-05-20T17:23:06.255206Z","end":"2026-05-20T17:23:08.005558Z","steps":["trace[832750117] 'process raft request' (duration: 1.750187295s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:08.005797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:23:06.255166Z","time spent":"1.750527357s","remote":"127.0.0.1:41500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T17:23:08.410043Z","caller":"traceutil/trace.go:171","msg":"trace[1665486737] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"530.234994ms","start":"2026-05-20T17:23:07.879775Z","end":"2026-05-20T17:23:08.41001Z","steps":["trace[1665486737] 'process raft request' (duration: 473.784482ms)","trace[1665486737] 'compare' (duration: 56.108931ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T17:23:08.410623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.931174025s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-20T17:23:08.410845Z","caller":"traceutil/trace.go:171","msg":"trace[2090278873] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:394; }","duration":"1.931442213s","start":"2026-05-20T17:23:06.479384Z","end":"2026-05-20T17:23:08.410826Z","steps":["trace[2090278873] 'agreement among raft nodes before linearized reading' (duration: 1.931148164s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:08.410942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:23:06.479358Z","time spent":"1.931570577s","remote":"127.0.0.1:41500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-20T17:23:08.410918Z","caller":"traceutil/trace.go:171","msg":"trace[1116454290] linearizableReadLoop","detail":"{readStateIndex:408; appliedIndex:406; }","duration":"1.930492673s","start":"2026-05-20T17:23:06.479434Z","end":"2026-05-20T17:23:08.409926Z","steps":["trace[1116454290] 'read index received' (duration: 1.525933157s)","trace[1116454290] 'applied index is now lower than readState.Index' (duration: 404.557846ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T17:23:08.411174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:23:07.879756Z","time spent":"530.683889ms","remote":"127.0.0.1:41500","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-05-20T17:23:08.411338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.350582805s","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-05-20T17:23:08.411411Z","caller":"traceutil/trace.go:171","msg":"trace[1435652537] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:394; }","duration":"1.350707178s","start":"2026-05-20T17:23:07.06068Z","end":"2026-05-20T17:23:08.411387Z","steps":["trace[1435652537] 'agreement among raft nodes before linearized reading' (duration: 1.350528613s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:08.41147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:23:07.06066Z","time spent":"1.350797902s","remote":"127.0.0.1:41500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-20T17:23:08.411573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.514684ms","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-05-20T17:23:08.411646Z","caller":"traceutil/trace.go:171","msg":"trace[1481974142] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:394; }","duration":"486.618948ms","start":"2026-05-20T17:23:07.925015Z","end":"2026-05-20T17:23:08.411634Z","steps":["trace[1481974142] 'agreement among raft nodes before linearized reading' (duration: 486.473693ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:08.411656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.607028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:23:08.411729Z","caller":"traceutil/trace.go:171","msg":"trace[403361548] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:394; }","duration":"534.749532ms","start":"2026-05-20T17:23:07.876967Z","end":"2026-05-20T17:23:08.411716Z","steps":["trace[403361548] 'agreement among raft nodes before linearized reading' (duration: 534.647499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:08.411767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:23:07.87694Z","time spent":"534.819645ms","remote":"127.0.0.1:41226","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-05-20T17:23:08.411742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:23:07.924992Z","time spent":"486.710261ms","remote":"127.0.0.1:41500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-20T17:23:24.639225Z","caller":"traceutil/trace.go:171","msg":"trace[94920425] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"122.796599ms","start":"2026-05-20T17:23:24.516402Z","end":"2026-05-20T17:23:24.639198Z","steps":["trace[94920425] 'process raft request' (duration: 122.452309ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:23:24.642643Z","caller":"traceutil/trace.go:171","msg":"trace[1526669461] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"122.408748ms","start":"2026-05-20T17:23:24.52003Z","end":"2026-05-20T17:23:24.642439Z","steps":["trace[1526669461] 'process raft request' (duration: 122.336946ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:23:24.642642Z","caller":"traceutil/trace.go:171","msg":"trace[883757962] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"122.490481ms","start":"2026-05-20T17:23:24.519949Z","end":"2026-05-20T17:23:24.64244Z","steps":["trace[883757962] 'process raft request' (duration: 121.980886ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:23:24.642704Z","caller":"traceutil/trace.go:171","msg":"trace[1262043455] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"122.428359ms","start":"2026-05-20T17:23:24.520256Z","end":"2026-05-20T17:23:24.642684Z","steps":["trace[1262043455] 'process raft request' (duration: 122.151541ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:23:24.885217Z","caller":"traceutil/trace.go:171","msg":"trace[1147730974] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"161.964562ms","start":"2026-05-20T17:23:24.723195Z","end":"2026-05-20T17:23:24.885159Z","steps":["trace[1147730974] 'process raft request' (duration: 153.950762ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:23:25.062896Z","caller":"traceutil/trace.go:171","msg":"trace[1597075469] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"172.265295ms","start":"2026-05-20T17:23:24.890607Z","end":"2026-05-20T17:23:25.062872Z","steps":["trace[1597075469] 'process raft request' (duration: 172.234584ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:23:25.062979Z","caller":"traceutil/trace.go:171","msg":"trace[788542470] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"172.620236ms","start":"2026-05-20T17:23:24.890301Z","end":"2026-05-20T17:23:25.062921Z","steps":["trace[788542470] 'process raft request' (duration: 104.477425ms)","trace[788542470] 'compare' (duration: 67.921244ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T17:23:27.521121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.328874ms","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-05-20T17:23:27.521225Z","caller":"traceutil/trace.go:171","msg":"trace[596032915] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:500; }","duration":"331.457358ms","start":"2026-05-20T17:23:27.189756Z","end":"2026-05-20T17:23:27.521213Z","steps":["trace[596032915] 'range keys from in-memory index tree' (duration: 331.310984ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:27.521327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"873.348788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-20T17:23:27.521373Z","caller":"traceutil/trace.go:171","msg":"trace[1456368117] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:500; }","duration":"873.41781ms","start":"2026-05-20T17:23:26.647949Z","end":"2026-05-20T17:23:27.521367Z","steps":["trace[1456368117] 'range keys from in-memory index tree' (duration: 873.281036ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:27.521399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:23:26.647939Z","time spent":"873.454271ms","remote":"127.0.0.1:41500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-20T17:23:27.521601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"875.432906ms","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-05-20T17:23:27.521622Z","caller":"traceutil/trace.go:171","msg":"trace[1699602967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:500; }","duration":"875.55909ms","start":"2026-05-20T17:23:26.646057Z","end":"2026-05-20T17:23:27.521616Z","steps":["trace[1699602967] 'range keys from in-memory index tree' (duration: 875.258531ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:27.521594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"875.299272ms","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-05-20T17:23:27.521673Z","caller":"traceutil/trace.go:171","msg":"trace[1578663354] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:500; }","duration":"875.414106ms","start":"2026-05-20T17:23:26.64624Z","end":"2026-05-20T17:23:27.521654Z","steps":["trace[1578663354] 'range keys from in-memory index tree' (duration: 875.21289ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:27.521712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:23:26.64623Z","time spent":"875.475427ms","remote":"127.0.0.1:41500","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-05-20T17:23:27.521852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:23:26.646035Z","time spent":"875.599402ms","remote":"127.0.0.1:41500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-20T17:23:27.521863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.681228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:23:27.521934Z","caller":"traceutil/trace.go:171","msg":"trace[30726492] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:500; }","duration":"349.797551ms","start":"2026-05-20T17:23:27.172116Z","end":"2026-05-20T17:23:27.521914Z","steps":["trace[30726492] 'range keys from in-memory index tree' (duration: 349.602696ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:27.521976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:23:27.172099Z","time spent":"349.868934ms","remote":"127.0.0.1:41216","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-05-20T17:23:27.522081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"924.045439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:23:27.522128Z","caller":"traceutil/trace.go:171","msg":"trace[268339011] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:500; }","duration":"924.125131ms","start":"2026-05-20T17:23:26.597994Z","end":"2026-05-20T17:23:27.522119Z","steps":["trace[268339011] 'range keys from in-memory index tree' (duration: 923.967006ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:27.52215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:23:26.597978Z","time spent":"924.165602ms","remote":"127.0.0.1:41320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"info","ts":"2026-05-20T17:23:28.6433Z","caller":"traceutil/trace.go:171","msg":"trace[1045557567] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:589; }","duration":"117.884819ms","start":"2026-05-20T17:23:28.525397Z","end":"2026-05-20T17:23:28.643282Z","steps":["trace[1045557567] 'read index received' (duration: 99.511508ms)","trace[1045557567] 'applied index is now lower than readState.Index' (duration: 18.372031ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T17:23:28.643307Z","caller":"traceutil/trace.go:171","msg":"trace[1602049179] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"145.571389ms","start":"2026-05-20T17:23:28.497709Z","end":"2026-05-20T17:23:28.64328Z","steps":["trace[1602049179] 'process raft request' (duration: 127.27261ms)","trace[1602049179] 'compare' (duration: 18.047512ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T17:23:28.643472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.060574ms","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-05-20T17:23:28.643504Z","caller":"traceutil/trace.go:171","msg":"trace[992101403] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:569; }","duration":"118.138506ms","start":"2026-05-20T17:23:28.525357Z","end":"2026-05-20T17:23:28.643496Z","steps":["trace[992101403] 'agreement among raft nodes before linearized reading' (duration: 117.987812ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:34.403261Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410525511674901,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T17:23:34.895343Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.300959992s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-20T17:23:35.013642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.367474ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T17:23:35.013856Z","caller":"traceutil/trace.go:171","msg":"trace[1164281443] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"1.455817081s","start":"2026-05-20T17:23:33.558015Z","end":"2026-05-20T17:23:35.013832Z","steps":["trace[1164281443] 'process raft request' (duration: 1.33821852s)","trace[1164281443] 'compare' (duration: 117.20185ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T17:23:35.01394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:23:33.558002Z","time spent":"1.455893813s","remote":"127.0.0.1:41500","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-05-20T17:23:35.014197Z","caller":"traceutil/trace.go:171","msg":"trace[99309583] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"1.454979098s","start":"2026-05-20T17:23:33.559188Z","end":"2026-05-20T17:23:35.014168Z","steps":["trace[99309583] 'process raft request' (duration: 1.454590548s)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:23:35.014305Z","caller":"traceutil/trace.go:171","msg":"trace[755940042] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"1.431374016s","start":"2026-05-20T17:23:33.582914Z","end":"2026-05-20T17:23:35.014288Z","steps":["trace[755940042] 'process raft request' (duration: 1.431039597s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:35.014419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:23:33.582887Z","time spent":"1.431456717s","remote":"127.0.0.1:41500","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-05-20T17:23:35.01447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:23:33.559183Z","time spent":"1.455189894s","remote":"127.0.0.1:41500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T17:23:35.20423Z","caller":"traceutil/trace.go:171","msg":"trace[1154755446] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:629; }","duration":"1.301446868s","start":"2026-05-20T17:23:33.902728Z","end":"2026-05-20T17:23:35.204175Z","steps":["trace[1154755446] 'read index received' (duration: 993.314718ms)","trace[1154755446] 'applied index is now lower than readState.Index' (duration: 308.13129ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T17:23:35.204274Z","caller":"traceutil/trace.go:171","msg":"trace[369215332] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"1.455159602s","start":"2026-05-20T17:23:33.749073Z","end":"2026-05-20T17:23:35.204233Z","steps":["trace[369215332] 'process raft request' (duration: 1.454613177s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:35.204485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:23:33.749047Z","time spent":"1.455358627s","remote":"127.0.0.1:41320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T17:23:35.20454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.301796738s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-v9q27\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-20T17:23:35.204629Z","caller":"traceutil/trace.go:171","msg":"trace[196275376] range","detail":"{range_begin:/registry/pods/kube-system/cilium-v9q27; range_end:; response_count:1; response_revision:610; }","duration":"1.301898121s","start":"2026-05-20T17:23:33.902708Z","end":"2026-05-20T17:23:35.204606Z","steps":["trace[196275376] 'agreement among raft nodes before linearized reading' (duration: 1.301641264s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:35.204677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:23:33.902622Z","time spent":"1.302047144s","remote":"127.0.0.1:41430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-v9q27\" "} {"level":"warn","ts":"2026-05-20T17:23:35.204845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.163545226s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-20T17:23:35.204892Z","caller":"traceutil/trace.go:171","msg":"trace[492111395] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:610; }","duration":"1.163638479s","start":"2026-05-20T17:23:34.041242Z","end":"2026-05-20T17:23:35.20488Z","steps":["trace[492111395] 'agreement among raft nodes before linearized reading' (duration: 1.163503645s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:35.205013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.419791ms","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-05-20T17:23:35.205114Z","caller":"traceutil/trace.go:171","msg":"trace[833495044] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:610; }","duration":"530.554375ms","start":"2026-05-20T17:23:34.67455Z","end":"2026-05-20T17:23:35.205104Z","steps":["trace[833495044] 'agreement among raft nodes before linearized reading' (duration: 530.273387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:35.205052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.032136656s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T17:23:35.205182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:23:34.674526Z","time spent":"530.648097ms","remote":"127.0.0.1:41500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-20T17:23:35.205222Z","caller":"traceutil/trace.go:171","msg":"trace[1833219265] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:610; }","duration":"1.032369591s","start":"2026-05-20T17:23:34.172829Z","end":"2026-05-20T17:23:35.205199Z","steps":["trace[1833219265] 'agreement among raft nodes before linearized reading' (duration: 1.032159266s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:35.20527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:23:34.172785Z","time spent":"1.032477014s","remote":"127.0.0.1:41226","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-05-20T17:23:35.20533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:23:34.041226Z","time spent":"1.163801493s","remote":"127.0.0.1:41264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.216\" "} {"level":"warn","ts":"2026-05-20T17:23:35.714977Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410525511674908,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T17:23:35.989242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.425112ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-05-20T17:23:35.989337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:23:35.208428Z","time spent":"780.905187ms","remote":"127.0.0.1:41264","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-20T17:23:36.060835Z","caller":"traceutil/trace.go:171","msg":"trace[1805016506] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"850.535617ms","start":"2026-05-20T17:23:35.210264Z","end":"2026-05-20T17:23:36.0608Z","steps":["trace[1805016506] 'process raft request' (duration: 850.346622ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:36.060999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:23:35.210243Z","time spent":"850.66988ms","remote":"127.0.0.1:41500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T17:23:36.061117Z","caller":"traceutil/trace.go:171","msg":"trace[1834325556] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"843.577753ms","start":"2026-05-20T17:23:35.217521Z","end":"2026-05-20T17:23:36.061099Z","steps":["trace[1834325556] 'process raft request' (duration: 843.219494ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:23:36.061207Z","caller":"traceutil/trace.go:171","msg":"trace[1559880782] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"800.005959ms","start":"2026-05-20T17:23:35.261188Z","end":"2026-05-20T17:23:36.061194Z","steps":["trace[1559880782] 'process raft request' (duration: 799.786954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:36.06123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:23:35.217497Z","time spent":"843.673055ms","remote":"127.0.0.1:41430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T17:23:36.061316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:23:35.261164Z","time spent":"800.096122ms","remote":"127.0.0.1:41746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-20T17:23:36.061528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.355863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:23:36.061591Z","caller":"traceutil/trace.go:171","msg":"trace[1518349912] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:613; }","duration":"847.418404ms","start":"2026-05-20T17:23:35.214156Z","end":"2026-05-20T17:23:36.061574Z","steps":["trace[1518349912] 'agreement among raft nodes before linearized reading' (duration: 847.273951ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:36.061632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:23:35.21413Z","time spent":"847.490576ms","remote":"127.0.0.1:41216","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-05-20T17:23:36.061139Z","caller":"traceutil/trace.go:171","msg":"trace[1085861855] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:633; }","duration":"846.947281ms","start":"2026-05-20T17:23:35.214159Z","end":"2026-05-20T17:23:36.061106Z","steps":["trace[1085861855] 'read index received' (duration: 653.64584ms)","trace[1085861855] 'applied index is now lower than readState.Index' (duration: 193.299661ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T17:23:36.072301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.405012ms","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-05-20T17:23:36.07235Z","caller":"traceutil/trace.go:171","msg":"trace[288562174] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:614; }","duration":"475.483155ms","start":"2026-05-20T17:23:35.596855Z","end":"2026-05-20T17:23:36.072339Z","steps":["trace[288562174] 'agreement among raft nodes before linearized reading' (duration: 475.3184ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:36.072374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:23:35.596841Z","time spent":"475.528095ms","remote":"127.0.0.1:41500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-20T17:23:37.517871Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410525511674921,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T17:23:38.018549Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410525511674921,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T17:23:38.518753Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410525511674921,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T17:23:38.938016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.748069241s","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-05-20T17:23:38.938135Z","caller":"traceutil/trace.go:171","msg":"trace[1359710262] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:615; }","duration":"1.748196264s","start":"2026-05-20T17:23:37.189919Z","end":"2026-05-20T17:23:38.938115Z","steps":["trace[1359710262] 'range keys from in-memory index tree' (duration: 1.748054491s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:38.961498Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.465370761s","expected-duration":"1s"} {"level":"info","ts":"2026-05-20T17:23:38.962639Z","caller":"traceutil/trace.go:171","msg":"trace[1107761654] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"1.945016914s","start":"2026-05-20T17:23:37.017598Z","end":"2026-05-20T17:23:38.962615Z","steps":["trace[1107761654] 'read index received' (duration: 1.944100571s)","trace[1107761654] 'applied index is now lower than readState.Index' (duration: 915.403µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T17:23:38.962797Z","caller":"traceutil/trace.go:171","msg":"trace[820921334] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"2.46840452s","start":"2026-05-20T17:23:36.494368Z","end":"2026-05-20T17:23:38.962772Z","steps":["trace[820921334] 'process raft request' (duration: 2.467740923s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:38.963112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.945478137s","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-05-20T17:23:38.963225Z","caller":"traceutil/trace.go:171","msg":"trace[2093780288] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:616; }","duration":"1.945652162s","start":"2026-05-20T17:23:37.017549Z","end":"2026-05-20T17:23:38.963201Z","steps":["trace[2093780288] 'agreement among raft nodes before linearized reading' (duration: 1.945398915s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:38.963247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.943524786s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-20T17:23:38.963283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:23:37.017533Z","time spent":"1.945739893s","remote":"127.0.0.1:41500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-20T17:23:38.963334Z","caller":"traceutil/trace.go:171","msg":"trace[943430933] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:616; }","duration":"1.94366416s","start":"2026-05-20T17:23:37.019652Z","end":"2026-05-20T17:23:38.963316Z","steps":["trace[943430933] 'agreement among raft nodes before linearized reading' (duration: 1.943473975s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:38.963343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:23:36.494336Z","time spent":"2.468530054s","remote":"127.0.0.1:41320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T17:23:38.963491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.19325ms","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-05-20T17:23:38.963525Z","caller":"traceutil/trace.go:171","msg":"trace[1245542034] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:616; }","duration":"877.244262ms","start":"2026-05-20T17:23:38.086272Z","end":"2026-05-20T17:23:38.963517Z","steps":["trace[1245542034] 'agreement among raft nodes before linearized reading' (duration: 877.134559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:38.963551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:23:38.086262Z","time spent":"877.283152ms","remote":"127.0.0.1:41500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-20T17:23:38.963719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.820679ms","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-05-20T17:23:38.963749Z","caller":"traceutil/trace.go:171","msg":"trace[1615911409] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:616; }","duration":"896.898121ms","start":"2026-05-20T17:23:38.066843Z","end":"2026-05-20T17:23:38.963741Z","steps":["trace[1615911409] 'agreement among raft nodes before linearized reading' (duration: 896.810758ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:38.963774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:23:38.066814Z","time spent":"896.954372ms","remote":"127.0.0.1:41500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-20T17:23:38.963386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:23:37.019636Z","time spent":"1.943741211s","remote":"127.0.0.1:41500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-20T17:23:38.96386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.794565329s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:23:38.963901Z","caller":"traceutil/trace.go:171","msg":"trace[1402775781] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:616; }","duration":"1.794665621s","start":"2026-05-20T17:23:37.169224Z","end":"2026-05-20T17:23:38.963889Z","steps":["trace[1402775781] 'agreement among raft nodes before linearized reading' (duration: 1.79459802s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:38.963929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:23:37.169206Z","time spent":"1.794716823s","remote":"127.0.0.1:41216","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-05-20T17:23:40.878313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.816641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:23:40.878428Z","caller":"traceutil/trace.go:171","msg":"trace[1092261663] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"706.975425ms","start":"2026-05-20T17:23:40.171426Z","end":"2026-05-20T17:23:40.878401Z","steps":["trace[1092261663] 'range keys from in-memory index tree' (duration: 706.722989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:40.87848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:23:40.171409Z","time spent":"707.058238ms","remote":"127.0.0.1:41216","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-05-20T17:23:44.547838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.452353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:23:44.547931Z","caller":"traceutil/trace.go:171","msg":"trace[339029947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:695; }","duration":"365.557676ms","start":"2026-05-20T17:23:44.182349Z","end":"2026-05-20T17:23:44.547907Z","steps":["trace[339029947] 'range keys from in-memory index tree' (duration: 365.393861ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:44.547996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:23:44.182313Z","time spent":"365.653918ms","remote":"127.0.0.1:41216","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-05-20T17:23:44.548022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.754426ms","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":"info","ts":"2026-05-20T17:23:44.548126Z","caller":"traceutil/trace.go:171","msg":"trace[2053114165] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:695; }","duration":"405.91782ms","start":"2026-05-20T17:23:44.142183Z","end":"2026-05-20T17:23:44.548101Z","steps":["trace[2053114165] 'range keys from in-memory index tree' (duration: 405.561772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:44.548186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:23:44.14216Z","time spent":"406.011553ms","remote":"127.0.0.1:41284","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-05-20T17:23:44.551863Z","caller":"traceutil/trace.go:171","msg":"trace[1742275536] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"181.600413ms","start":"2026-05-20T17:23:44.370225Z","end":"2026-05-20T17:23:44.551826Z","steps":["trace[1742275536] 'process raft request' (duration: 180.807183ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:23:45.045152Z","caller":"traceutil/trace.go:171","msg":"trace[301734179] linearizableReadLoop","detail":"{readStateIndex:726; appliedIndex:725; }","duration":"102.311851ms","start":"2026-05-20T17:23:44.9428Z","end":"2026-05-20T17:23:45.045112Z","steps":["trace[301734179] 'read index received' (duration: 33.149836ms)","trace[301734179] 'applied index is now lower than readState.Index' (duration: 69.161355ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T17:23:45.045199Z","caller":"traceutil/trace.go:171","msg":"trace[369665028] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"137.477287ms","start":"2026-05-20T17:23:44.907687Z","end":"2026-05-20T17:23:45.045165Z","steps":["trace[369665028] 'process raft request' (duration: 68.523437ms)","trace[369665028] 'compare' (duration: 68.734895ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T17:23:45.045574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.766003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-05-20T17:23:45.045602Z","caller":"traceutil/trace.go:171","msg":"trace[506739280] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:701; }","duration":"102.857805ms","start":"2026-05-20T17:23:44.942737Z","end":"2026-05-20T17:23:45.045594Z","steps":["trace[506739280] 'agreement among raft nodes before linearized reading' (duration: 102.491416ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:23:45.990733Z","caller":"traceutil/trace.go:171","msg":"trace[1832262176] linearizableReadLoop","detail":"{readStateIndex:739; appliedIndex:737; }","duration":"142.317272ms","start":"2026-05-20T17:23:45.848399Z","end":"2026-05-20T17:23:45.990716Z","steps":["trace[1832262176] 'read index received' (duration: 1.884127ms)","trace[1832262176] 'applied index is now lower than readState.Index' (duration: 140.432325ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T17:23:45.990804Z","caller":"traceutil/trace.go:171","msg":"trace[1145421459] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"143.170763ms","start":"2026-05-20T17:23:45.847626Z","end":"2026-05-20T17:23:45.990796Z","steps":["trace[1145421459] 'process raft request' (duration: 142.758613ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:45.990919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.486566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:23:45.990967Z","caller":"traceutil/trace.go:171","msg":"trace[893133245] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:712; }","duration":"142.583748ms","start":"2026-05-20T17:23:45.848374Z","end":"2026-05-20T17:23:45.990958Z","steps":["trace[893133245] 'agreement among raft nodes before linearized reading' (duration: 142.484936ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:45.995012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.558257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:23:45.995083Z","caller":"traceutil/trace.go:171","msg":"trace[1053441303] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:713; }","duration":"146.67053ms","start":"2026-05-20T17:23:45.848394Z","end":"2026-05-20T17:23:45.995064Z","steps":["trace[1053441303] 'agreement among raft nodes before linearized reading' (duration: 146.533417ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:23:45.995099Z","caller":"traceutil/trace.go:171","msg":"trace[1917359122] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"144.006804ms","start":"2026-05-20T17:23:45.851085Z","end":"2026-05-20T17:23:45.995092Z","steps":["trace[1917359122] 'process raft request' (duration: 143.653045ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:45.995304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.018045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:23:45.995454Z","caller":"traceutil/trace.go:171","msg":"trace[1528614958] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:713; }","duration":"100.187549ms","start":"2026-05-20T17:23:45.895249Z","end":"2026-05-20T17:23:45.995437Z","steps":["trace[1528614958] 'agreement among raft nodes before linearized reading' (duration: 100.000965ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:45.995311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.740394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T17:23:45.995612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.058377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:23:45.995686Z","caller":"traceutil/trace.go:171","msg":"trace[1815206554] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:713; }","duration":"120.082752ms","start":"2026-05-20T17:23:45.875537Z","end":"2026-05-20T17:23:45.995619Z","steps":["trace[1815206554] 'agreement among raft nodes before linearized reading' (duration: 119.739273ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:23:45.995729Z","caller":"traceutil/trace.go:171","msg":"trace[1738210373] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:713; }","duration":"119.18181ms","start":"2026-05-20T17:23:45.876507Z","end":"2026-05-20T17:23:45.995689Z","steps":["trace[1738210373] 'agreement among raft nodes before linearized reading' (duration: 118.991215ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:45.995799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.026611ms","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":"warn","ts":"2026-05-20T17:23:45.995391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.065745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:23:45.995848Z","caller":"traceutil/trace.go:171","msg":"trace[935080662] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:0; response_revision:713; }","duration":"146.532866ms","start":"2026-05-20T17:23:45.8493Z","end":"2026-05-20T17:23:45.995832Z","steps":["trace[935080662] 'agreement among raft nodes before linearized reading' (duration: 146.037044ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:23:45.996187Z","caller":"traceutil/trace.go:171","msg":"trace[2085718526] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:713; }","duration":"120.092972ms","start":"2026-05-20T17:23:45.875732Z","end":"2026-05-20T17:23:45.995825Z","steps":["trace[2085718526] 'agreement among raft nodes before linearized reading' (duration: 119.456566ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:23:50.366943Z","caller":"traceutil/trace.go:171","msg":"trace[1284499467] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"104.191019ms","start":"2026-05-20T17:23:50.262728Z","end":"2026-05-20T17:23:50.366919Z","steps":["trace[1284499467] 'process raft request' (duration: 103.82489ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:23:52.273985Z","caller":"traceutil/trace.go:171","msg":"trace[1698828369] linearizableReadLoop","detail":"{readStateIndex:787; appliedIndex:786; }","duration":"102.50929ms","start":"2026-05-20T17:23:52.171451Z","end":"2026-05-20T17:23:52.273961Z","steps":["trace[1698828369] 'read index received' (duration: 102.335296ms)","trace[1698828369] 'applied index is now lower than readState.Index' (duration: 172.884µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T17:23:52.27406Z","caller":"traceutil/trace.go:171","msg":"trace[233804861] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"184.548395ms","start":"2026-05-20T17:23:52.089442Z","end":"2026-05-20T17:23:52.27399Z","steps":["trace[233804861] 'process raft request' (duration: 184.396872ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:52.2742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.737745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:23:52.274298Z","caller":"traceutil/trace.go:171","msg":"trace[248306851] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:756; }","duration":"102.873188ms","start":"2026-05-20T17:23:52.171409Z","end":"2026-05-20T17:23:52.274282Z","steps":["trace[248306851] 'agreement among raft nodes before linearized reading' (duration: 102.626903ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:23:55.752556Z","caller":"traceutil/trace.go:171","msg":"trace[523813889] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"497.135192ms","start":"2026-05-20T17:23:55.255394Z","end":"2026-05-20T17:23:55.752529Z","steps":["trace[523813889] 'process raft request' (duration: 496.757273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:55.752897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:23:55.255383Z","time spent":"497.422648ms","remote":"127.0.0.1:41500","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-05-20T17:23:55.770053Z","caller":"traceutil/trace.go:171","msg":"trace[1024502834] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"513.84121ms","start":"2026-05-20T17:23:55.256183Z","end":"2026-05-20T17:23:55.770024Z","steps":["trace[1024502834] 'process raft request' (duration: 513.605845ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:23:55.770283Z","caller":"traceutil/trace.go:171","msg":"trace[1720787215] linearizableReadLoop","detail":"{readStateIndex:804; appliedIndex:802; }","duration":"440.798689ms","start":"2026-05-20T17:23:55.329456Z","end":"2026-05-20T17:23:55.770255Z","steps":["trace[1720787215] 'read index received' (duration: 422.542013ms)","trace[1720787215] 'applied index is now lower than readState.Index' (duration: 18.254826ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T17:23:55.770333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:23:55.256155Z","time spent":"514.053486ms","remote":"127.0.0.1:41500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T17:23:55.770382Z","caller":"traceutil/trace.go:171","msg":"trace[298146657] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"436.837817ms","start":"2026-05-20T17:23:55.333506Z","end":"2026-05-20T17:23:55.770344Z","steps":["trace[298146657] 'process raft request' (duration: 436.475318ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:55.770547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:23:55.33349Z","time spent":"436.976429ms","remote":"127.0.0.1:41500","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-05-20T17:23:55.770611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.148987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-20T17:23:55.770699Z","caller":"traceutil/trace.go:171","msg":"trace[201256726] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:773; }","duration":"441.294681ms","start":"2026-05-20T17:23:55.329389Z","end":"2026-05-20T17:23:55.770683Z","steps":["trace[201256726] 'agreement among raft nodes before linearized reading' (duration: 440.974323ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:55.770727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:23:55.329351Z","time spent":"441.368452ms","remote":"127.0.0.1:41500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-20T17:23:55.770896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.139448ms","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-05-20T17:23:55.770946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.302608ms","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-05-20T17:23:55.771024Z","caller":"traceutil/trace.go:171","msg":"trace[1578679930] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:773; }","duration":"417.318782ms","start":"2026-05-20T17:23:55.353677Z","end":"2026-05-20T17:23:55.770996Z","steps":["trace[1578679930] 'agreement among raft nodes before linearized reading' (duration: 416.785719ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:23:55.771058Z","caller":"traceutil/trace.go:171","msg":"trace[1344644075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:773; }","duration":"440.473902ms","start":"2026-05-20T17:23:55.330557Z","end":"2026-05-20T17:23:55.771031Z","steps":["trace[1344644075] 'agreement among raft nodes before linearized reading' (duration: 439.882648ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:55.771096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:23:55.353666Z","time spent":"417.418604ms","remote":"127.0.0.1:41500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-20T17:23:55.771195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:23:55.330535Z","time spent":"440.627605ms","remote":"127.0.0.1:41500","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-05-20T17:23:57.918881Z","caller":"traceutil/trace.go:171","msg":"trace[1677666873] linearizableReadLoop","detail":"{readStateIndex:809; appliedIndex:808; }","duration":"143.359452ms","start":"2026-05-20T17:23:57.7755Z","end":"2026-05-20T17:23:57.918859Z","steps":["trace[1677666873] 'read index received' (duration: 143.137326ms)","trace[1677666873] 'applied index is now lower than readState.Index' (duration: 221.076µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T17:23:57.918944Z","caller":"traceutil/trace.go:171","msg":"trace[555691215] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"160.503427ms","start":"2026-05-20T17:23:57.758411Z","end":"2026-05-20T17:23:57.918914Z","steps":["trace[555691215] 'process raft request' (duration: 160.309262ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:57.919081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.564786ms","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-05-20T17:23:57.919113Z","caller":"traceutil/trace.go:171","msg":"trace[410944323] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:777; }","duration":"143.633388ms","start":"2026-05-20T17:23:57.775472Z","end":"2026-05-20T17:23:57.919106Z","steps":["trace[410944323] 'agreement among raft nodes before linearized reading' (duration: 143.505275ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:57.919244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.666524ms","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-05-20T17:23:57.919286Z","caller":"traceutil/trace.go:171","msg":"trace[2141436044] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:777; }","duration":"125.738516ms","start":"2026-05-20T17:23:57.793538Z","end":"2026-05-20T17:23:57.919276Z","steps":["trace[2141436044] 'agreement among raft nodes before linearized reading' (duration: 125.584813ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:57.919256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.648981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-20T17:23:57.919364Z","caller":"traceutil/trace.go:171","msg":"trace[1411242788] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:777; }","duration":"124.796934ms","start":"2026-05-20T17:23:57.794562Z","end":"2026-05-20T17:23:57.919359Z","steps":["trace[1411242788] 'agreement among raft nodes before linearized reading' (duration: 124.652501ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:57.919563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.152672ms","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-05-20T17:23:57.919765Z","caller":"traceutil/trace.go:171","msg":"trace[2059548138] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:777; }","duration":"125.364677ms","start":"2026-05-20T17:23:57.794386Z","end":"2026-05-20T17:23:57.919751Z","steps":["trace[2059548138] 'agreement among raft nodes before linearized reading' (duration: 124.865736ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:58.162737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.844229ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T17:23:58.162993Z","caller":"traceutil/trace.go:171","msg":"trace[1676049603] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"239.871494ms","start":"2026-05-20T17:23:57.922954Z","end":"2026-05-20T17:23:58.162825Z","steps":["trace[1676049603] 'process raft request' (duration: 121.82197ms)","trace[1676049603] 'compare' (duration: 117.483631ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T17:23:58.176433Z","caller":"traceutil/trace.go:171","msg":"trace[1726121891] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"253.348943ms","start":"2026-05-20T17:23:57.923066Z","end":"2026-05-20T17:23:58.176415Z","steps":["trace[1726121891] 'process raft request' (duration: 253.208079ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:23:58.176594Z","caller":"traceutil/trace.go:171","msg":"trace[561841168] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"253.456925ms","start":"2026-05-20T17:23:57.923126Z","end":"2026-05-20T17:23:58.176583Z","steps":["trace[561841168] 'process raft request' (duration: 253.25214ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:23:58.176882Z","caller":"traceutil/trace.go:171","msg":"trace[764993692] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"253.398843ms","start":"2026-05-20T17:23:57.923424Z","end":"2026-05-20T17:23:58.176823Z","steps":["trace[764993692] 'process raft request' (duration: 253.298311ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:23:59.740124Z","caller":"traceutil/trace.go:171","msg":"trace[2105025844] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"319.774952ms","start":"2026-05-20T17:23:59.420324Z","end":"2026-05-20T17:23:59.740099Z","steps":["trace[2105025844] 'process raft request' (duration: 319.606689ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:23:59.740307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:23:59.420306Z","time spent":"319.900146ms","remote":"127.0.0.1:41500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T17:24:00.613649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.830574ms","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-05-20T17:24:00.613711Z","caller":"traceutil/trace.go:171","msg":"trace[119732676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:783; }","duration":"432.940156ms","start":"2026-05-20T17:24:00.180758Z","end":"2026-05-20T17:24:00.613698Z","steps":["trace[119732676] 'range keys from in-memory index tree' (duration: 432.716031ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:24:00.613751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:24:00.180752Z","time spent":"432.994237ms","remote":"127.0.0.1:41500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-20T17:24:00.613851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.591282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:24:00.613954Z","caller":"traceutil/trace.go:171","msg":"trace[283179747] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:783; }","duration":"441.732995ms","start":"2026-05-20T17:24:00.172206Z","end":"2026-05-20T17:24:00.613939Z","steps":["trace[283179747] 'range keys from in-memory index tree' (duration: 441.522621ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:24:00.61401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:24:00.172189Z","time spent":"441.814277ms","remote":"127.0.0.1:41216","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-05-20T17:24:00.61407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.559837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-20T17:24:00.61415Z","caller":"traceutil/trace.go:171","msg":"trace[420470279] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:783; }","duration":"447.69116ms","start":"2026-05-20T17:24:00.166441Z","end":"2026-05-20T17:24:00.614132Z","steps":["trace[420470279] 'range keys from in-memory index tree' (duration: 447.364943ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:24:00.614191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.935978ms","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-05-20T17:24:00.6142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:24:00.166418Z","time spent":"447.770492ms","remote":"127.0.0.1:41500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-20T17:24:00.614243Z","caller":"traceutil/trace.go:171","msg":"trace[1633806104] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:783; }","duration":"434.02493ms","start":"2026-05-20T17:24:00.180206Z","end":"2026-05-20T17:24:00.614231Z","steps":["trace[1633806104] 'range keys from in-memory index tree' (duration: 433.628261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:24:00.614292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:24:00.180187Z","time spent":"434.088762ms","remote":"127.0.0.1:41500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-20T17:24:00.61413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.690296ms","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-05-20T17:24:00.614475Z","caller":"traceutil/trace.go:171","msg":"trace[1956814643] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:783; }","duration":"435.039283ms","start":"2026-05-20T17:24:00.17937Z","end":"2026-05-20T17:24:00.61441Z","steps":["trace[1956814643] 'range keys from in-memory index tree' (duration: 434.4539ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:24:00.614578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:24:00.179363Z","time spent":"435.155266ms","remote":"127.0.0.1:41500","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-05-20T17:24:02.918488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.935876ms","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-05-20T17:24:02.918561Z","caller":"traceutil/trace.go:171","msg":"trace[1703094930] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:788; }","duration":"232.036559ms","start":"2026-05-20T17:24:02.686507Z","end":"2026-05-20T17:24:02.918543Z","steps":["trace[1703094930] 'range keys from in-memory index tree' (duration: 231.783393ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:24:02.91859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.621802ms","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-05-20T17:24:02.918642Z","caller":"traceutil/trace.go:171","msg":"trace[2056244616] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:788; }","duration":"232.700064ms","start":"2026-05-20T17:24:02.685927Z","end":"2026-05-20T17:24:02.918627Z","steps":["trace[2056244616] 'range keys from in-memory index tree' (duration: 232.481839ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:24:02.91868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.167422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-20T17:24:02.918728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.240789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-20T17:24:02.918972Z","caller":"traceutil/trace.go:171","msg":"trace[379218623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:788; }","duration":"232.476768ms","start":"2026-05-20T17:24:02.686476Z","end":"2026-05-20T17:24:02.918953Z","steps":["trace[379218623] 'range keys from in-memory index tree' (duration: 232.033748ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:24:02.919126Z","caller":"traceutil/trace.go:171","msg":"trace[1146437319] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:788; }","duration":"247.688489ms","start":"2026-05-20T17:24:02.671421Z","end":"2026-05-20T17:24:02.919109Z","steps":["trace[1146437319] 'range keys from in-memory index tree' (duration: 246.945813ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:24:03.0265Z","caller":"traceutil/trace.go:171","msg":"trace[2105446689] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"104.339485ms","start":"2026-05-20T17:24:02.922137Z","end":"2026-05-20T17:24:03.026476Z","steps":["trace[2105446689] 'process raft request' (duration: 104.195621ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:24:03.081432Z","caller":"traceutil/trace.go:171","msg":"trace[932721979] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"159.180957ms","start":"2026-05-20T17:24:02.922221Z","end":"2026-05-20T17:24:03.081402Z","steps":["trace[932721979] 'process raft request' (duration: 158.808818ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:24:03.081151Z","caller":"traceutil/trace.go:171","msg":"trace[581575277] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"158.899831ms","start":"2026-05-20T17:24:02.9222Z","end":"2026-05-20T17:24:03.0811Z","steps":["trace[581575277] 'process raft request' (duration: 158.699256ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:24:03.085505Z","caller":"traceutil/trace.go:171","msg":"trace[1698721859] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"163.211127ms","start":"2026-05-20T17:24:02.922279Z","end":"2026-05-20T17:24:03.08549Z","steps":["trace[1698721859] 'process raft request' (duration: 163.130245ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:24:04.29884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.826656ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T17:24:04.298952Z","caller":"traceutil/trace.go:171","msg":"trace[42380988] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:827; }","duration":"188.325205ms","start":"2026-05-20T17:24:04.11061Z","end":"2026-05-20T17:24:04.298935Z","steps":["trace[42380988] 'read index received' (duration: 36.701µs)","trace[42380988] 'applied index is now lower than readState.Index' (duration: 188.287284ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T17:24:04.29903Z","caller":"traceutil/trace.go:171","msg":"trace[2099667900] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"232.106035ms","start":"2026-05-20T17:24:04.066893Z","end":"2026-05-20T17:24:04.298999Z","steps":["trace[2099667900] 'compare' (duration: 225.699883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:24:04.299105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.491228ms","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-05-20T17:24:04.299134Z","caller":"traceutil/trace.go:171","msg":"trace[569558729] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:794; }","duration":"188.55459ms","start":"2026-05-20T17:24:04.110571Z","end":"2026-05-20T17:24:04.299125Z","steps":["trace[569558729] 'agreement among raft nodes before linearized reading' (duration: 188.415817ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:24:04.564906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.727728ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-20T17:24:04.565068Z","caller":"traceutil/trace.go:171","msg":"trace[445138719] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"236.121294ms","start":"2026-05-20T17:24:04.328927Z","end":"2026-05-20T17:24:04.565049Z","steps":["trace[445138719] 'process raft request' (duration: 96.020788ms)","trace[445138719] 'compare' (duration: 139.35299ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T17:24:05.128114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.302574ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-20T17:24:05.128301Z","caller":"traceutil/trace.go:171","msg":"trace[2072300103] linearizableReadLoop","detail":"{readStateIndex:834; appliedIndex:833; }","duration":"118.293548ms","start":"2026-05-20T17:24:05.009941Z","end":"2026-05-20T17:24:05.128235Z","steps":["trace[2072300103] 'read index received' (duration: 67.491µs)","trace[2072300103] 'applied index is now lower than readState.Index' (duration: 118.224797ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T17:24:05.128655Z","caller":"traceutil/trace.go:171","msg":"trace[321957572] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"194.208491ms","start":"2026-05-20T17:24:04.934426Z","end":"2026-05-20T17:24:05.128634Z","steps":["trace[321957572] 'process raft request' (duration: 58.340024ms)","trace[321957572] 'compare' (duration: 135.189052ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T17:24:05.128952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.978593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:24:05.129078Z","caller":"traceutil/trace.go:171","msg":"trace[99873533] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:800; }","duration":"119.147747ms","start":"2026-05-20T17:24:05.009903Z","end":"2026-05-20T17:24:05.129051Z","steps":["trace[99873533] 'agreement among raft nodes before linearized reading' (duration: 118.972533ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:24:05.326655Z","caller":"traceutil/trace.go:171","msg":"trace[92809505] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"192.848013ms","start":"2026-05-20T17:24:05.133786Z","end":"2026-05-20T17:24:05.326634Z","steps":["trace[92809505] 'process raft request' (duration: 192.647399ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:24:05.326713Z","caller":"traceutil/trace.go:171","msg":"trace[1935045659] linearizableReadLoop","detail":"{readStateIndex:840; appliedIndex:834; }","duration":"154.779504ms","start":"2026-05-20T17:24:05.171913Z","end":"2026-05-20T17:24:05.326693Z","steps":["trace[1935045659] 'read index received' (duration: 22.21424ms)","trace[1935045659] 'applied index is now lower than readState.Index' (duration: 132.564104ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T17:24:05.326752Z","caller":"traceutil/trace.go:171","msg":"trace[1875731406] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"192.934575ms","start":"2026-05-20T17:24:05.13379Z","end":"2026-05-20T17:24:05.326724Z","steps":["trace[1875731406] 'process raft request' (duration: 192.71028ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:24:05.326855Z","caller":"traceutil/trace.go:171","msg":"trace[2122693780] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"193.133149ms","start":"2026-05-20T17:24:05.133713Z","end":"2026-05-20T17:24:05.326846Z","steps":["trace[2122693780] 'process raft request' (duration: 149.222101ms)","trace[2122693780] 'compare' (duration: 43.346406ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T17:24:05.32705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.127861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:24:05.327082Z","caller":"traceutil/trace.go:171","msg":"trace[268476604] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:806; }","duration":"155.183632ms","start":"2026-05-20T17:24:05.17189Z","end":"2026-05-20T17:24:05.327073Z","steps":["trace[268476604] 'agreement among raft nodes before linearized reading' (duration: 155.124101ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:24:05.327031Z","caller":"traceutil/trace.go:171","msg":"trace[1713054278] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"191.134756ms","start":"2026-05-20T17:24:05.135876Z","end":"2026-05-20T17:24:05.327011Z","steps":["trace[1713054278] 'process raft request' (duration: 190.695776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:24:05.327136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.084436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-20T17:24:05.327085Z","caller":"traceutil/trace.go:171","msg":"trace[1068863916] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"191.008302ms","start":"2026-05-20T17:24:05.136062Z","end":"2026-05-20T17:24:05.32707Z","steps":["trace[1068863916] 'process raft request' (duration: 190.569932ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:24:05.327266Z","caller":"traceutil/trace.go:171","msg":"trace[1807515673] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:806; }","duration":"119.24539ms","start":"2026-05-20T17:24:05.207969Z","end":"2026-05-20T17:24:05.327215Z","steps":["trace[1807515673] 'agreement among raft nodes before linearized reading' (duration: 119.069266ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:24:05.568995Z","caller":"traceutil/trace.go:171","msg":"trace[75096400] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"233.896719ms","start":"2026-05-20T17:24:05.335078Z","end":"2026-05-20T17:24:05.568975Z","steps":["trace[75096400] 'process raft request' (duration: 180.492931ms)","trace[75096400] 'compare' (duration: 53.318936ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T17:24:06.672513Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410525511675387,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T17:24:07.172816Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410525511675387,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T17:24:07.585491Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.678939649s","expected-duration":"1s"} {"level":"info","ts":"2026-05-20T17:24:07.585955Z","caller":"traceutil/trace.go:171","msg":"trace[1325961837] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"1.679499642s","start":"2026-05-20T17:24:05.906435Z","end":"2026-05-20T17:24:07.585935Z","steps":["trace[1325961837] 'process raft request' (duration: 1.679326648s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:24:07.586295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:24:05.906415Z","time spent":"1.679637594s","remote":"127.0.0.1:41500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T17:24:07.773906Z","caller":"traceutil/trace.go:171","msg":"trace[951773936] linearizableReadLoop","detail":"{readStateIndex:843; appliedIndex:841; }","duration":"1.601465206s","start":"2026-05-20T17:24:06.172417Z","end":"2026-05-20T17:24:07.773883Z","steps":["trace[951773936] 'read index received' (duration: 1.413404902s)","trace[951773936] 'applied index is now lower than readState.Index' (duration: 188.059264ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T17:24:07.774166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.601731763s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:24:07.774231Z","caller":"traceutil/trace.go:171","msg":"trace[664322272] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:809; }","duration":"1.601868696s","start":"2026-05-20T17:24:06.172348Z","end":"2026-05-20T17:24:07.774217Z","steps":["trace[664322272] 'agreement among raft nodes before linearized reading' (duration: 1.601755923s)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:24:07.774248Z","caller":"traceutil/trace.go:171","msg":"trace[308988338] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"1.438173054s","start":"2026-05-20T17:24:06.336066Z","end":"2026-05-20T17:24:07.774239Z","steps":["trace[308988338] 'process raft request' (duration: 1.437637423s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:24:07.774262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:24:06.172329Z","time spent":"1.601927497s","remote":"127.0.0.1:41216","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-05-20T17:24:07.774268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.337406ms","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-05-20T17:24:07.774288Z","caller":"traceutil/trace.go:171","msg":"trace[1442656283] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:809; }","duration":"443.392307ms","start":"2026-05-20T17:24:07.33089Z","end":"2026-05-20T17:24:07.774282Z","steps":["trace[1442656283] 'agreement among raft nodes before linearized reading' (duration: 443.304755ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:24:07.774308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:24:07.33087Z","time spent":"443.434088ms","remote":"127.0.0.1:41500","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-05-20T17:24:07.774309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:24:06.336032Z","time spent":"1.438239606s","remote":"127.0.0.1:41480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T17:24:07.774423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.61903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-20T17:24:07.77444Z","caller":"traceutil/trace.go:171","msg":"trace[1777674120] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:809; }","duration":"442.654192ms","start":"2026-05-20T17:24:07.331782Z","end":"2026-05-20T17:24:07.774436Z","steps":["trace[1777674120] 'agreement among raft nodes before linearized reading' (duration: 442.609361ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:24:07.774452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:24:07.331774Z","time spent":"442.675062ms","remote":"127.0.0.1:41500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-20T17:24:07.774414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.39419161s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-20T17:24:07.774644Z","caller":"traceutil/trace.go:171","msg":"trace[1094617082] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:809; }","duration":"1.39465722s","start":"2026-05-20T17:24:06.37997Z","end":"2026-05-20T17:24:07.774627Z","steps":["trace[1094617082] 'agreement among raft nodes before linearized reading' (duration: 1.39420168s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:24:07.774705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.798206ms","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-05-20T17:24:07.774723Z","caller":"traceutil/trace.go:171","msg":"trace[639015534] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:809; }","duration":"443.852877ms","start":"2026-05-20T17:24:07.330866Z","end":"2026-05-20T17:24:07.774719Z","steps":["trace[639015534] 'agreement among raft nodes before linearized reading' (duration: 443.816206ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:24:07.774737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:24:07.330844Z","time spent":"443.890298ms","remote":"127.0.0.1:41500","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-05-20T17:24:07.774774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:24:06.379947Z","time spent":"1.394764492s","remote":"127.0.0.1:41480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":1,"response size":29,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-05-20T17:24:07.774672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.82204ms","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-05-20T17:24:07.774866Z","caller":"traceutil/trace.go:171","msg":"trace[908765270] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:809; }","duration":"577.043975ms","start":"2026-05-20T17:24:07.197811Z","end":"2026-05-20T17:24:07.774855Z","steps":["trace[908765270] 'agreement among raft nodes before linearized reading' (duration: 576.82725ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:24:07.774915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:24:07.19779Z","time spent":"577.116456ms","remote":"127.0.0.1:41500","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-05-20T17:24:07.775102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.385248533s","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-05-20T17:24:07.775132Z","caller":"traceutil/trace.go:171","msg":"trace[1731310602] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:809; }","duration":"1.385311475s","start":"2026-05-20T17:24:06.389812Z","end":"2026-05-20T17:24:07.775123Z","steps":["trace[1731310602] 'agreement among raft nodes before linearized reading' (duration: 1.384961027s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:24:07.77515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:24:06.389793Z","time spent":"1.385352505s","remote":"127.0.0.1:41500","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-05-20T17:24:09.371531Z","caller":"traceutil/trace.go:171","msg":"trace[1068675664] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"589.681876ms","start":"2026-05-20T17:24:08.78183Z","end":"2026-05-20T17:24:09.371512Z","steps":["trace[1068675664] 'process raft request' (duration: 589.566893ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:24:09.371737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:24:08.7818Z","time spent":"589.846839ms","remote":"127.0.0.1:41480","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-05-20T17:24:09.540896Z","caller":"traceutil/trace.go:171","msg":"trace[1333510340] linearizableReadLoop","detail":"{readStateIndex:851; appliedIndex:849; }","duration":"369.728309ms","start":"2026-05-20T17:24:09.171133Z","end":"2026-05-20T17:24:09.540861Z","steps":["trace[1333510340] 'read index received' (duration: 200.297218ms)","trace[1333510340] 'applied index is now lower than readState.Index' (duration: 169.429321ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T17:24:09.541041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.882892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:24:09.541097Z","caller":"traceutil/trace.go:171","msg":"trace[1130449058] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:816; }","duration":"369.992405ms","start":"2026-05-20T17:24:09.171094Z","end":"2026-05-20T17:24:09.541086Z","steps":["trace[1130449058] 'agreement among raft nodes before linearized reading' (duration: 369.884462ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:24:09.54114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:24:09.171077Z","time spent":"370.053296ms","remote":"127.0.0.1:41216","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-05-20T17:24:10.252444Z","caller":"traceutil/trace.go:171","msg":"trace[562335060] linearizableReadLoop","detail":"{readStateIndex:853; appliedIndex:852; }","duration":"463.447438ms","start":"2026-05-20T17:24:09.78896Z","end":"2026-05-20T17:24:10.252407Z","steps":["trace[562335060] 'read index received' (duration: 372.973572ms)","trace[562335060] 'applied index is now lower than readState.Index' (duration: 90.471905ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T17:24:10.252586Z","caller":"traceutil/trace.go:171","msg":"trace[561759029] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"492.840382ms","start":"2026-05-20T17:24:09.759696Z","end":"2026-05-20T17:24:10.252536Z","steps":["trace[561759029] 'process raft request' (duration: 402.291655ms)","trace[561759029] 'compare' (duration: 89.961035ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T17:24:10.252822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.838236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-20T17:24:10.252837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:24:09.759665Z","time spent":"493.013386ms","remote":"127.0.0.1:41500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T17:24:10.252879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.099949ms","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-05-20T17:24:10.252927Z","caller":"traceutil/trace.go:171","msg":"trace[489521243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:818; }","duration":"407.246123ms","start":"2026-05-20T17:24:09.84567Z","end":"2026-05-20T17:24:10.252917Z","steps":["trace[489521243] 'agreement among raft nodes before linearized reading' (duration: 407.066889ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:24:10.252953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:24:09.845635Z","time spent":"407.313264ms","remote":"127.0.0.1:41500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-20T17:24:10.253026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.886745ms","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-05-20T17:24:10.253063Z","caller":"traceutil/trace.go:171","msg":"trace[746001434] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:818; }","duration":"406.966936ms","start":"2026-05-20T17:24:09.846087Z","end":"2026-05-20T17:24:10.253054Z","steps":["trace[746001434] 'agreement among raft nodes before linearized reading' (duration: 406.837584ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:24:10.253111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:24:09.846058Z","time spent":"407.040458ms","remote":"127.0.0.1:41500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-20T17:24:10.253226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.104549ms","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-05-20T17:24:10.253256Z","caller":"traceutil/trace.go:171","msg":"trace[1397767730] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:818; }","duration":"407.151761ms","start":"2026-05-20T17:24:09.846097Z","end":"2026-05-20T17:24:10.253249Z","steps":["trace[1397767730] 'agreement among raft nodes before linearized reading' (duration: 407.079029ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:24:10.253234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.833494ms","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-05-20T17:24:10.253442Z","caller":"traceutil/trace.go:171","msg":"trace[322854165] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:818; }","duration":"407.039758ms","start":"2026-05-20T17:24:09.846257Z","end":"2026-05-20T17:24:10.253297Z","steps":["trace[322854165] 'agreement among raft nodes before linearized reading' (duration: 406.615869ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:24:10.253506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:24:09.846233Z","time spent":"407.259413ms","remote":"127.0.0.1:41500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-20T17:24:10.253277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:24:09.84608Z","time spent":"407.192421ms","remote":"127.0.0.1:41500","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-05-20T17:24:10.252889Z","caller":"traceutil/trace.go:171","msg":"trace[1922473196] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:818; }","duration":"463.908737ms","start":"2026-05-20T17:24:09.788949Z","end":"2026-05-20T17:24:10.252858Z","steps":["trace[1922473196] 'agreement among raft nodes before linearized reading' (duration: 463.646072ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:24:10.253703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:24:09.788905Z","time spent":"464.787996ms","remote":"127.0.0.1:41500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-20T17:24:10.578955Z","caller":"traceutil/trace.go:171","msg":"trace[625329456] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"200.639494ms","start":"2026-05-20T17:24:10.378292Z","end":"2026-05-20T17:24:10.578931Z","steps":["trace[625329456] 'process raft request' (duration: 200.46263ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:24:10.73534Z","caller":"traceutil/trace.go:171","msg":"trace[1898801931] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"147.746297ms","start":"2026-05-20T17:24:10.587542Z","end":"2026-05-20T17:24:10.735288Z","steps":["trace[1898801931] 'process raft request' (duration: 120.43585ms)","trace[1898801931] 'compare' (duration: 26.869877ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T17:24:10.899583Z","caller":"traceutil/trace.go:171","msg":"trace[431606711] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"106.672354ms","start":"2026-05-20T17:24:10.792889Z","end":"2026-05-20T17:24:10.899562Z","steps":["trace[431606711] 'process raft request' (duration: 86.592112ms)","trace[431606711] 'compare' (duration: 19.9819ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T17:24:11.167408Z","caller":"traceutil/trace.go:171","msg":"trace[1869465526] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"264.195233ms","start":"2026-05-20T17:24:10.903191Z","end":"2026-05-20T17:24:11.167386Z","steps":["trace[1869465526] 'process raft request' (duration: 180.37682ms)","trace[1869465526] 'compare' (duration: 83.518266ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T17:24:11.532989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.044183ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-20T17:24:11.53324Z","caller":"traceutil/trace.go:171","msg":"trace[810611004] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"317.743129ms","start":"2026-05-20T17:24:11.215449Z","end":"2026-05-20T17:24:11.533192Z","steps":["trace[810611004] 'process raft request' (duration: 317.639446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:24:11.533309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:24:11.215435Z","time spent":"317.84654ms","remote":"127.0.0.1:41564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1116,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T17:24:11.533357Z","caller":"traceutil/trace.go:171","msg":"trace[174519347] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"317.915902ms","start":"2026-05-20T17:24:11.215395Z","end":"2026-05-20T17:24:11.533311Z","steps":["trace[174519347] 'process raft request' (duration: 79.488591ms)","trace[174519347] 'compare' (duration: 237.90828ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T17:24:11.533749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:24:11.215347Z","time spent":"318.079766ms","remote":"127.0.0.1:41564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1289,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T17:24:28.750193Z","caller":"traceutil/trace.go:171","msg":"trace[2012109590] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"106.611105ms","start":"2026-05-20T17:24:28.643559Z","end":"2026-05-20T17:24:28.75017Z","steps":["trace[2012109590] 'process raft request' (duration: 105.85038ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:24:28.787187Z","caller":"traceutil/trace.go:171","msg":"trace[1842848945] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"109.8853ms","start":"2026-05-20T17:24:28.677271Z","end":"2026-05-20T17:24:28.787157Z","steps":["trace[1842848945] 'process raft request' (duration: 109.732777ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:24:29.741064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.0018ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-05-20T17:24:30.568764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.416571ms","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-05-20T17:24:30.568837Z","caller":"traceutil/trace.go:171","msg":"trace[1901449736] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1009; }","duration":"115.556274ms","start":"2026-05-20T17:24:30.453265Z","end":"2026-05-20T17:24:30.568821Z","steps":["trace[1901449736] 'range keys from in-memory index tree' (duration: 115.237008ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:24:30.683447Z","caller":"traceutil/trace.go:171","msg":"trace[1636594492] linearizableReadLoop","detail":"{readStateIndex:1050; appliedIndex:1049; }","duration":"108.843823ms","start":"2026-05-20T17:24:30.574571Z","end":"2026-05-20T17:24:30.683415Z","steps":["trace[1636594492] 'read index received' (duration: 108.670469ms)","trace[1636594492] 'applied index is now lower than readState.Index' (duration: 172.214µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T17:24:30.683526Z","caller":"traceutil/trace.go:171","msg":"trace[811449865] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"111.539815ms","start":"2026-05-20T17:24:30.57196Z","end":"2026-05-20T17:24:30.6835Z","steps":["trace[811449865] 'process raft request' (duration: 111.305991ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:24:30.683735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.147608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-20T17:24:30.683781Z","caller":"traceutil/trace.go:171","msg":"trace[2125471954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1010; }","duration":"109.22506ms","start":"2026-05-20T17:24:30.574545Z","end":"2026-05-20T17:24:30.68377Z","steps":["trace[2125471954] 'agreement among raft nodes before linearized reading' (duration: 109.070157ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:24:30.936026Z","caller":"traceutil/trace.go:171","msg":"trace[280034780] linearizableReadLoop","detail":"{readStateIndex:1053; appliedIndex:1051; }","duration":"182.772587ms","start":"2026-05-20T17:24:30.753237Z","end":"2026-05-20T17:24:30.93601Z","steps":["trace[280034780] 'read index received' (duration: 180.650085ms)","trace[280034780] 'applied index is now lower than readState.Index' (duration: 2.121772ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T17:24:30.936173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.92153ms","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-05-20T17:24:30.93619Z","caller":"traceutil/trace.go:171","msg":"trace[832297023] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1013; }","duration":"182.979771ms","start":"2026-05-20T17:24:30.753205Z","end":"2026-05-20T17:24:30.936185Z","steps":["trace[832297023] 'agreement among raft nodes before linearized reading' (duration: 182.866399ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:24:30.936286Z","caller":"traceutil/trace.go:171","msg":"trace[472312623] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"247.235506ms","start":"2026-05-20T17:24:30.689026Z","end":"2026-05-20T17:24:30.936261Z","steps":["trace[472312623] 'process raft request' (duration: 246.95738ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:24:30.936625Z","caller":"traceutil/trace.go:171","msg":"trace[1634249064] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"248.284197ms","start":"2026-05-20T17:24:30.688324Z","end":"2026-05-20T17:24:30.936608Z","steps":["trace[1634249064] 'process raft request' (duration: 245.552813ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:24:30.980679Z","caller":"traceutil/trace.go:171","msg":"trace[1992259027] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"189.48555ms","start":"2026-05-20T17:24:30.79117Z","end":"2026-05-20T17:24:30.980655Z","steps":["trace[1992259027] 'process raft request' (duration: 189.320116ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:24:33.036426Z","caller":"traceutil/trace.go:171","msg":"trace[29804015] linearizableReadLoop","detail":"{readStateIndex:1056; appliedIndex:1055; }","duration":"313.375028ms","start":"2026-05-20T17:24:32.723032Z","end":"2026-05-20T17:24:33.036407Z","steps":["trace[29804015] 'read index received' (duration: 313.346697ms)","trace[29804015] 'applied index is now lower than readState.Index' (duration: 27.341µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T17:24:33.036522Z","caller":"traceutil/trace.go:171","msg":"trace[615443940] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"345.130718ms","start":"2026-05-20T17:24:32.691359Z","end":"2026-05-20T17:24:33.03649Z","steps":["trace[615443940] 'process raft request' (duration: 344.933454ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:24:33.036628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.585642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-20T17:24:33.036659Z","caller":"traceutil/trace.go:171","msg":"trace[640642844] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1016; }","duration":"313.701694ms","start":"2026-05-20T17:24:32.72295Z","end":"2026-05-20T17:24:33.036651Z","steps":["trace[640642844] 'agreement among raft nodes before linearized reading' (duration: 313.547241ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:24:33.036671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:24:32.691331Z","time spent":"345.25461ms","remote":"127.0.0.1:41500","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-05-20T17:24:33.036687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:24:32.722921Z","time spent":"313.760755ms","remote":"127.0.0.1:41500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-20T17:24:33.047308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.665234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:24:33.047367Z","caller":"traceutil/trace.go:171","msg":"trace[791250990] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1017; }","duration":"245.779516ms","start":"2026-05-20T17:24:32.801575Z","end":"2026-05-20T17:24:33.047355Z","steps":["trace[791250990] 'agreement among raft nodes before linearized reading' (duration: 245.694175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:24:33.047592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.584918ms","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-05-20T17:24:33.047644Z","caller":"traceutil/trace.go:171","msg":"trace[600876433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1017; }","duration":"107.65677ms","start":"2026-05-20T17:24:32.939967Z","end":"2026-05-20T17:24:33.047624Z","steps":["trace[600876433] 'agreement among raft nodes before linearized reading' (duration: 107.525037ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:24:33.047583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.346293ms","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-05-20T17:24:33.047724Z","caller":"traceutil/trace.go:171","msg":"trace[805509711] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1017; }","duration":"107.524286ms","start":"2026-05-20T17:24:32.940183Z","end":"2026-05-20T17:24:33.047708Z","steps":["trace[805509711] 'agreement among raft nodes before linearized reading' (duration: 107.305402ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:24:33.293568Z","caller":"traceutil/trace.go:171","msg":"trace[1098372225] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"242.722932ms","start":"2026-05-20T17:24:33.050821Z","end":"2026-05-20T17:24:33.293544Z","steps":["trace[1098372225] 'process raft request' (duration: 242.535708ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:24:33.293571Z","caller":"traceutil/trace.go:171","msg":"trace[1793135552] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"242.692671ms","start":"2026-05-20T17:24:33.050858Z","end":"2026-05-20T17:24:33.293551Z","steps":["trace[1793135552] 'process raft request' (duration: 242.63544ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:24:33.293771Z","caller":"traceutil/trace.go:171","msg":"trace[1206027578] linearizableReadLoop","detail":"{readStateIndex:1061; appliedIndex:1058; }","duration":"122.185697ms","start":"2026-05-20T17:24:33.171571Z","end":"2026-05-20T17:24:33.293757Z","steps":["trace[1206027578] 'read index received' (duration: 121.765599ms)","trace[1206027578] 'applied index is now lower than readState.Index' (duration: 419.318µs)"],"step_count":2} {"level":"warn","ts":"2026-05-20T17:24:33.293881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.294659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T17:24:33.293916Z","caller":"traceutil/trace.go:171","msg":"trace[1243515598] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"242.210722ms","start":"2026-05-20T17:24:33.051698Z","end":"2026-05-20T17:24:33.293909Z","steps":["trace[1243515598] 'process raft request' (duration: 241.824655ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:24:33.293911Z","caller":"traceutil/trace.go:171","msg":"trace[1287361409] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1021; }","duration":"122.367731ms","start":"2026-05-20T17:24:33.171535Z","end":"2026-05-20T17:24:33.293903Z","steps":["trace[1287361409] 'agreement among raft nodes before linearized reading' (duration: 122.256739ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:24:37.463077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.427921ms","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-05-20T17:24:37.463203Z","caller":"traceutil/trace.go:171","msg":"trace[1871937802] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1029; }","duration":"272.573365ms","start":"2026-05-20T17:24:37.190613Z","end":"2026-05-20T17:24:37.463186Z","steps":["trace[1871937802] 'range keys from in-memory index tree' (duration: 272.410171ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:24:37.463294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.169301ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T17:24:37.463436Z","caller":"traceutil/trace.go:171","msg":"trace[1690110190] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"323.916419ms","start":"2026-05-20T17:24:37.139508Z","end":"2026-05-20T17:24:37.463424Z","steps":["trace[1690110190] 'process raft request' (duration: 189.474862ms)","trace[1690110190] 'compare' (duration: 134.054449ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T17:24:37.463432Z","caller":"traceutil/trace.go:171","msg":"trace[1501679324] linearizableReadLoop","detail":"{readStateIndex:1071; appliedIndex:1070; }","duration":"283.185738ms","start":"2026-05-20T17:24:37.180224Z","end":"2026-05-20T17:24:37.46341Z","steps":["trace[1501679324] 'read index received' (duration: 148.695471ms)","trace[1501679324] 'applied index is now lower than readState.Index' (duration: 134.488507ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T17:24:37.463527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:24:37.13949Z","time spent":"323.978471ms","remote":"127.0.0.1:41500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T17:24:37.463585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.361291ms","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-05-20T17:24:37.463651Z","caller":"traceutil/trace.go:171","msg":"trace[1632688659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1030; }","duration":"283.491264ms","start":"2026-05-20T17:24:37.180141Z","end":"2026-05-20T17:24:37.463632Z","steps":["trace[1632688659] 'agreement among raft nodes before linearized reading' (duration: 283.329301ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:24:37.63158Z","caller":"traceutil/trace.go:171","msg":"trace[1845253159] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"164.918451ms","start":"2026-05-20T17:24:37.466636Z","end":"2026-05-20T17:24:37.631554Z","steps":["trace[1845253159] 'process raft request' (duration: 158.280924ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:24:37.668948Z","caller":"traceutil/trace.go:171","msg":"trace[407370776] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"201.541053ms","start":"2026-05-20T17:24:37.467398Z","end":"2026-05-20T17:24:37.668939Z","steps":["trace[407370776] 'process raft request' (duration: 201.488372ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:24:37.669142Z","caller":"traceutil/trace.go:171","msg":"trace[1711750272] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"201.497823ms","start":"2026-05-20T17:24:37.467618Z","end":"2026-05-20T17:24:37.669116Z","steps":["trace[1711750272] 'process raft request' (duration: 201.296969ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:24:37.669143Z","caller":"traceutil/trace.go:171","msg":"trace[1474659020] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"201.742248ms","start":"2026-05-20T17:24:37.467267Z","end":"2026-05-20T17:24:37.669009Z","steps":["trace[1474659020] 'process raft request' (duration: 201.544064ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:25:47.675328Z","caller":"traceutil/trace.go:171","msg":"trace[1338231618] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"176.672887ms","start":"2026-05-20T17:25:47.498622Z","end":"2026-05-20T17:25:47.675295Z","steps":["trace[1338231618] 'process raft request' (duration: 148.709803ms)","trace[1338231618] 'compare' (duration: 27.812541ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T17:25:48.421446Z","caller":"traceutil/trace.go:171","msg":"trace[304682431] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"146.908501ms","start":"2026-05-20T17:25:48.274506Z","end":"2026-05-20T17:25:48.421414Z","steps":["trace[304682431] 'process raft request' (duration: 99.263109ms)","trace[304682431] 'compare' (duration: 47.485939ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T17:25:49.124125Z","caller":"traceutil/trace.go:171","msg":"trace[793718866] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"105.965975ms","start":"2026-05-20T17:25:49.01813Z","end":"2026-05-20T17:25:49.124096Z","steps":["trace[793718866] 'process raft request' (duration: 105.763571ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:25:51.663821Z","caller":"traceutil/trace.go:171","msg":"trace[106744314] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"131.230298ms","start":"2026-05-20T17:25:51.532565Z","end":"2026-05-20T17:25:51.663796Z","steps":["trace[106744314] 'process raft request' (duration: 131.062316ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T17:25:59.752299Z","caller":"traceutil/trace.go:171","msg":"trace[1099383142] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"327.357731ms","start":"2026-05-20T17:25:59.424911Z","end":"2026-05-20T17:25:59.752269Z","steps":["trace[1099383142] 'process raft request' (duration: 326.732041ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T17:25:59.7527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T17:25:59.424888Z","time spent":"327.499482ms","remote":"127.0.0.1:41500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"}