{"level":"warn","ts":"2026-07-21T06:41:43.100762Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-21T06:41:43.100853Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.72: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.72:2380","--initial-cluster=instance=https://199.204.45.72:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.72:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.72: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-07-21T06:41:43.100918Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-21T06:41:43.100929Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-07-21T06:41:43.100944Z","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-07-21T06:41:43.101595Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"]} {"level":"info","ts":"2026-07-21T06:41:43.101667Z","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.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.72: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-07-21T06:41:43.107993Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.089305ms"} {"level":"info","ts":"2026-07-21T06:41:43.115351Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"50e03cebfd94cbba","cluster-id":"120c03c1d5b80f94"} {"level":"info","ts":"2026-07-21T06:41:43.115418Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=()"} {"level":"info","ts":"2026-07-21T06:41:43.115436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 0"} {"level":"info","ts":"2026-07-21T06:41:43.115449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 50e03cebfd94cbba [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-21T06:41:43.115456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 1"} {"level":"info","ts":"2026-07-21T06:41:43.115485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"warn","ts":"2026-07-21T06:41:43.12363Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-21T06:41:43.124887Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-21T06:41:43.127179Z","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-07-21T06:41:43.129277Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"50e03cebfd94cbba","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-21T06:41:43.129457Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"50e03cebfd94cbba","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-21T06:41:43.129573Z","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-07-21T06:41:43.129619Z","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-07-21T06:41:43.129628Z","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-07-21T06:41:43.133943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"info","ts":"2026-07-21T06:41:43.134113Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","added-peer-id":"50e03cebfd94cbba","added-peer-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-07-21T06:41:43.138013Z","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-07-21T06:41:43.138072Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-07-21T06:41:43.138588Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-07-21T06:41:43.138718Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"50e03cebfd94cbba","initial-advertise-peer-urls":["https://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-21T06:41:43.13882Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-21T06:41:44.116323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba is starting a new election at term 1"} {"level":"info","ts":"2026-07-21T06:41:44.116383Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became pre-candidate at term 1"} {"level":"info","ts":"2026-07-21T06:41:44.116414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgPreVoteResp from 50e03cebfd94cbba at term 1"} {"level":"info","ts":"2026-07-21T06:41:44.116439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became candidate at term 2"} {"level":"info","ts":"2026-07-21T06:41:44.116513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgVoteResp from 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-07-21T06:41:44.116532Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became leader at term 2"} {"level":"info","ts":"2026-07-21T06:41:44.11655Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 50e03cebfd94cbba elected leader 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-07-21T06:41:44.117523Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T06:41:44.118696Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"50e03cebfd94cbba","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.72:2379]}","request-path":"/0/members/50e03cebfd94cbba/attributes","cluster-id":"120c03c1d5b80f94","publish-timeout":"7s"} {"level":"info","ts":"2026-07-21T06:41:44.118733Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-21T06:41:44.118961Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-21T06:41:44.118962Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-21T06:41:44.119008Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-21T06:41:44.12064Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-21T06:41:44.121072Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.72:2379"} {"level":"info","ts":"2026-07-21T06:41:44.121256Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T06:41:44.121563Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T06:41:44.121638Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T06:42:05.822332Z","caller":"traceutil/trace.go:171","msg":"trace[1920508364] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"135.359198ms","start":"2026-07-21T06:42:05.686957Z","end":"2026-07-21T06:42:05.822316Z","steps":["trace[1920508364] 'process raft request' (duration: 135.215763ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:42:06.018098Z","caller":"traceutil/trace.go:171","msg":"trace[2019834831] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"168.601796ms","start":"2026-07-21T06:42:05.849467Z","end":"2026-07-21T06:42:06.018068Z","steps":["trace[2019834831] 'process raft request' (duration: 102.568131ms)","trace[2019834831] 'compare' (duration: 65.797698ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:42:06.867443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.933795ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T06:42:06.867534Z","caller":"traceutil/trace.go:171","msg":"trace[236364947] linearizableReadLoop","detail":"{readStateIndex:404; appliedIndex:403; }","duration":"281.846496ms","start":"2026-07-21T06:42:06.585676Z","end":"2026-07-21T06:42:06.867522Z","steps":["trace[236364947] 'read index received' (duration: 73.219769ms)","trace[236364947] 'applied index is now lower than readState.Index' (duration: 208.625717ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:42:06.867597Z","caller":"traceutil/trace.go:171","msg":"trace[619594908] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"622.819104ms","start":"2026-07-21T06:42:06.244744Z","end":"2026-07-21T06:42:06.867563Z","steps":["trace[619594908] 'process raft request' (duration: 414.272319ms)","trace[619594908] 'compare' (duration: 207.851252ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:42:06.86848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:06.244725Z","time spent":"622.932297ms","remote":"127.0.0.1:37118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:42:06.868615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.928431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:42:06.868708Z","caller":"traceutil/trace.go:171","msg":"trace[359760099] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:388; }","duration":"283.028855ms","start":"2026-07-21T06:42:06.585645Z","end":"2026-07-21T06:42:06.868674Z","steps":["trace[359760099] 'agreement among raft nodes before linearized reading' (duration: 282.023792ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:06.868798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.91387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4368"} {"level":"info","ts":"2026-07-21T06:42:06.868887Z","caller":"traceutil/trace.go:171","msg":"trace[1545889453] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:388; }","duration":"178.052544ms","start":"2026-07-21T06:42:06.690819Z","end":"2026-07-21T06:42:06.868871Z","steps":["trace[1545889453] 'agreement among raft nodes before linearized reading' (duration: 177.859168ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:42:07.109859Z","caller":"traceutil/trace.go:171","msg":"trace[1296681307] linearizableReadLoop","detail":"{readStateIndex:405; appliedIndex:404; }","duration":"162.898772ms","start":"2026-07-21T06:42:06.946926Z","end":"2026-07-21T06:42:07.109825Z","steps":["trace[1296681307] 'read index received' (duration: 162.511579ms)","trace[1296681307] 'applied index is now lower than readState.Index' (duration: 385.783µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:42:07.109917Z","caller":"traceutil/trace.go:171","msg":"trace[848029371] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"227.403138ms","start":"2026-07-21T06:42:06.882485Z","end":"2026-07-21T06:42:07.109888Z","steps":["trace[848029371] 'process raft request' (duration: 227.017116ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:07.110187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.236303ms","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-07-21T06:42:07.110257Z","caller":"traceutil/trace.go:171","msg":"trace[627562899] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:389; }","duration":"163.368428ms","start":"2026-07-21T06:42:06.946871Z","end":"2026-07-21T06:42:07.110239Z","steps":["trace[627562899] 'agreement among raft nodes before linearized reading' (duration: 163.236223ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:17.418593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.457632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-21T06:42:17.418684Z","caller":"traceutil/trace.go:171","msg":"trace[517921989] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:443; }","duration":"184.611348ms","start":"2026-07-21T06:42:17.23405Z","end":"2026-07-21T06:42:17.418661Z","steps":["trace[517921989] 'range keys from in-memory index tree' (duration: 184.265366ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:23.697494Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221322113240911,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-21T06:42:23.740229Z","caller":"traceutil/trace.go:171","msg":"trace[1938560763] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"590.70142ms","start":"2026-07-21T06:42:23.149498Z","end":"2026-07-21T06:42:23.7402Z","steps":["trace[1938560763] 'process raft request' (duration: 590.562906ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:23.740377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:23.149478Z","time spent":"590.835055ms","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T06:42:23.790066Z","caller":"traceutil/trace.go:171","msg":"trace[76825947] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"635.911779ms","start":"2026-07-21T06:42:23.154122Z","end":"2026-07-21T06:42:23.790034Z","steps":["trace[76825947] 'process raft request' (duration: 635.677922ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:42:23.790161Z","caller":"traceutil/trace.go:171","msg":"trace[237112428] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"625.77779ms","start":"2026-07-21T06:42:23.164375Z","end":"2026-07-21T06:42:23.790153Z","steps":["trace[237112428] 'process raft request' (duration: 625.657986ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:42:23.790155Z","caller":"traceutil/trace.go:171","msg":"trace[826363538] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"625.812761ms","start":"2026-07-21T06:42:23.164316Z","end":"2026-07-21T06:42:23.790129Z","steps":["trace[826363538] 'process raft request' (duration: 625.661066ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:23.790232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:23.1643Z","time spent":"625.896305ms","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T06:42:23.79023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:23.164372Z","time spent":"625.817931ms","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T06:42:23.790244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:23.154102Z","time spent":"636.063295ms","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T06:42:23.790112Z","caller":"traceutil/trace.go:171","msg":"trace[1807487212] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"633.302295ms","start":"2026-07-21T06:42:23.156789Z","end":"2026-07-21T06:42:23.790091Z","steps":["trace[1807487212] 'process raft request' (duration: 633.13564ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:42:23.790387Z","caller":"traceutil/trace.go:171","msg":"trace[1282325643] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"625.972567ms","start":"2026-07-21T06:42:23.164408Z","end":"2026-07-21T06:42:23.79038Z","steps":["trace[1282325643] 'process raft request' (duration: 625.688237ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:42:23.790404Z","caller":"traceutil/trace.go:171","msg":"trace[1712700731] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"620.794408ms","start":"2026-07-21T06:42:23.169597Z","end":"2026-07-21T06:42:23.790391Z","steps":["trace[1712700731] 'process raft request' (duration: 620.575511ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:23.790448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:23.164405Z","time spent":"626.020618ms","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T06:42:23.790464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:23.169585Z","time spent":"620.85572ms","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T06:42:23.79036Z","caller":"traceutil/trace.go:171","msg":"trace[1603781592] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"624.454017ms","start":"2026-07-21T06:42:23.165894Z","end":"2026-07-21T06:42:23.790348Z","steps":["trace[1603781592] 'process raft request' (duration: 624.23881ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:42:23.790561Z","caller":"traceutil/trace.go:171","msg":"trace[709521819] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"600.779058ms","start":"2026-07-21T06:42:23.189716Z","end":"2026-07-21T06:42:23.790495Z","steps":["trace[709521819] 'process raft request' (duration: 600.555181ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:42:23.790603Z","caller":"traceutil/trace.go:171","msg":"trace[1428733554] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"578.346029ms","start":"2026-07-21T06:42:23.212246Z","end":"2026-07-21T06:42:23.790592Z","steps":["trace[1428733554] 'process raft request' (duration: 578.123242ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:23.790621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:23.165886Z","time spent":"624.710296ms","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T06:42:23.790648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:23.212225Z","time spent":"578.40142ms","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T06:42:23.790696Z","caller":"traceutil/trace.go:171","msg":"trace[1255812591] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"459.498178ms","start":"2026-07-21T06:42:23.331188Z","end":"2026-07-21T06:42:23.790686Z","steps":["trace[1255812591] 'process raft request' (duration: 459.281651ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:23.790641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:23.189696Z","time spent":"600.909352ms","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T06:42:23.790741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:23.331162Z","time spent":"459.559981ms","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T06:42:23.790725Z","caller":"traceutil/trace.go:171","msg":"trace[976636080] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"600.715226ms","start":"2026-07-21T06:42:23.189794Z","end":"2026-07-21T06:42:23.790509Z","steps":["trace[976636080] 'process raft request' (duration: 600.52914ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:23.79089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:23.189789Z","time spent":"601.041626ms","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T06:42:23.79094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.542032ms","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-07-21T06:42:23.790983Z","caller":"traceutil/trace.go:171","msg":"trace[654822876] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:502; }","duration":"593.617935ms","start":"2026-07-21T06:42:23.197355Z","end":"2026-07-21T06:42:23.790973Z","steps":["trace[654822876] 'agreement among raft nodes before linearized reading' (duration: 593.490361ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:23.791015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:23.197341Z","time spent":"593.667967ms","remote":"127.0.0.1:37118","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-07-21T06:42:23.790934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:23.156774Z","time spent":"633.616075ms","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T06:42:23.791082Z","caller":"traceutil/trace.go:171","msg":"trace[1796098117] linearizableReadLoop","detail":"{readStateIndex:523; appliedIndex:512; }","duration":"593.260074ms","start":"2026-07-21T06:42:23.197399Z","end":"2026-07-21T06:42:23.790659Z","steps":["trace[1796098117] 'read index received' (duration: 542.791724ms)","trace[1796098117] 'applied index is now lower than readState.Index' (duration: 50.466769ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:42:23.795494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.473486ms","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-07-21T06:42:23.795551Z","caller":"traceutil/trace.go:171","msg":"trace[1719919429] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:504; }","duration":"334.536108ms","start":"2026-07-21T06:42:23.461003Z","end":"2026-07-21T06:42:23.795539Z","steps":["trace[1719919429] 'agreement among raft nodes before linearized reading' (duration: 334.384933ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:23.795581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:23.460892Z","time spent":"334.682123ms","remote":"127.0.0.1:37118","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-07-21T06:42:23.795766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.373475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:42:23.795796Z","caller":"traceutil/trace.go:171","msg":"trace[1850830025] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:504; }","duration":"210.402826ms","start":"2026-07-21T06:42:23.585384Z","end":"2026-07-21T06:42:23.795787Z","steps":["trace[1850830025] 'agreement among raft nodes before linearized reading' (duration: 210.360235ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:42:25.287881Z","caller":"traceutil/trace.go:171","msg":"trace[180203615] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:590; }","duration":"318.410464ms","start":"2026-07-21T06:42:24.9694Z","end":"2026-07-21T06:42:25.287811Z","steps":["trace[180203615] 'read index received' (duration: 317.775653ms)","trace[180203615] 'applied index is now lower than readState.Index' (duration: 633.841µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:42:25.28796Z","caller":"traceutil/trace.go:171","msg":"trace[1294114015] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"332.079918ms","start":"2026-07-21T06:42:24.955811Z","end":"2026-07-21T06:42:25.287891Z","steps":["trace[1294114015] 'process raft request' (duration: 331.445648ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:25.288077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.656502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xbackendtrafficpolicies.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:23081"} {"level":"info","ts":"2026-07-21T06:42:25.288123Z","caller":"traceutil/trace.go:171","msg":"trace[60849396] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/xbackendtrafficpolicies.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:566; }","duration":"318.753205ms","start":"2026-07-21T06:42:24.969357Z","end":"2026-07-21T06:42:25.28811Z","steps":["trace[60849396] 'agreement among raft nodes before linearized reading' (duration: 318.59899ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:25.288168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:24.969341Z","time spent":"318.817327ms","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":1,"response size":23103,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xbackendtrafficpolicies.gateway.networking.x-k8s.io\" "} {"level":"warn","ts":"2026-07-21T06:42:25.288194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:24.95579Z","time spent":"332.271855ms","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:42:25.293428Z","caller":"traceutil/trace.go:171","msg":"trace[1328666356] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"309.35588ms","start":"2026-07-21T06:42:24.98406Z","end":"2026-07-21T06:42:25.293416Z","steps":["trace[1328666356] 'process raft request' (duration: 309.178584ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:25.293654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:24.984033Z","time spent":"309.568637ms","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208461,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:42:30.538217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.431759ms","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-07-21T06:42:30.53831Z","caller":"traceutil/trace.go:171","msg":"trace[1426859983] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:597; }","duration":"577.556034ms","start":"2026-07-21T06:42:29.960733Z","end":"2026-07-21T06:42:30.538289Z","steps":["trace[1426859983] 'range keys from in-memory index tree' (duration: 577.288115ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:30.538353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:29.960697Z","time spent":"577.647066ms","remote":"127.0.0.1:37118","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-07-21T06:42:30.538743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.405244ms","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-07-21T06:42:30.53887Z","caller":"traceutil/trace.go:171","msg":"trace[1160192179] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:597; }","duration":"700.557409ms","start":"2026-07-21T06:42:29.838293Z","end":"2026-07-21T06:42:30.538851Z","steps":["trace[1160192179] 'range keys from in-memory index tree' (duration: 700.233918ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:30.538991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:29.838274Z","time spent":"700.702664ms","remote":"127.0.0.1:37118","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-07-21T06:42:30.53875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.151925ms","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-07-21T06:42:30.539208Z","caller":"traceutil/trace.go:171","msg":"trace[205593617] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:597; }","duration":"708.666233ms","start":"2026-07-21T06:42:29.830527Z","end":"2026-07-21T06:42:30.539193Z","steps":["trace[205593617] 'range keys from in-memory index tree' (duration: 707.724011ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:30.539307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:29.830507Z","time spent":"708.785246ms","remote":"127.0.0.1:37118","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-07-21T06:42:30.638912Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221322113241092,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T06:42:31.146237Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221322113241092,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T06:42:31.646833Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221322113241092,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T06:42:31.798974Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.6641109s","expected-duration":"1s"} {"level":"info","ts":"2026-07-21T06:42:31.800242Z","caller":"traceutil/trace.go:171","msg":"trace[2015449366] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"1.66656599s","start":"2026-07-21T06:42:30.133648Z","end":"2026-07-21T06:42:31.800213Z","steps":["trace[2015449366] 'process raft request' (duration: 1.666032113s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:31.800413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:30.133622Z","time spent":"1.666686844s","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:42:31.831688Z","caller":"traceutil/trace.go:171","msg":"trace[1681838912] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:623; }","duration":"1.696036557s","start":"2026-07-21T06:42:30.13561Z","end":"2026-07-21T06:42:31.831647Z","steps":["trace[1681838912] 'read index received' (duration: 1.66378869s)","trace[1681838912] 'applied index is now lower than readState.Index' (duration: 32.247157ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:42:31.83178Z","caller":"traceutil/trace.go:171","msg":"trace[741027787] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"1.287362811s","start":"2026-07-21T06:42:30.544409Z","end":"2026-07-21T06:42:31.831772Z","steps":["trace[741027787] 'process raft request' (duration: 1.286781822s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:31.831858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.696244594s","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-07-21T06:42:31.831893Z","caller":"traceutil/trace.go:171","msg":"trace[986956542] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:601; }","duration":"1.696313727s","start":"2026-07-21T06:42:30.135572Z","end":"2026-07-21T06:42:31.831886Z","steps":["trace[986956542] 'agreement among raft nodes before linearized reading' (duration: 1.696226344s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:31.831909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:30.135556Z","time spent":"1.696350208s","remote":"127.0.0.1:37118","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-07-21T06:42:31.831992Z","caller":"traceutil/trace.go:171","msg":"trace[1073051749] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"1.287542947s","start":"2026-07-21T06:42:30.544443Z","end":"2026-07-21T06:42:31.831986Z","steps":["trace[1073051749] 'process raft request' (duration: 1.287149234s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:31.831851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:30.544386Z","time spent":"1.287411522s","remote":"127.0.0.1:37118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:42:31.832025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:30.544421Z","time spent":"1.287586808s","remote":"127.0.0.1:37118","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-07-21T06:42:31.83207Z","caller":"traceutil/trace.go:171","msg":"trace[961076776] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"1.287493775s","start":"2026-07-21T06:42:30.544572Z","end":"2026-07-21T06:42:31.832065Z","steps":["trace[961076776] 'process raft request' (duration: 1.28705028s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:31.832097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:30.544544Z","time spent":"1.287539966s","remote":"127.0.0.1:37118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:42:31.832181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.246796903s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:42:31.832218Z","caller":"traceutil/trace.go:171","msg":"trace[470467175] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:601; }","duration":"1.246861985s","start":"2026-07-21T06:42:30.585348Z","end":"2026-07-21T06:42:31.83221Z","steps":["trace[470467175] 'agreement among raft nodes before linearized reading' (duration: 1.246811654s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:31.83232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:30.585332Z","time spent":"1.246980409s","remote":"127.0.0.1:36850","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-07-21T06:42:31.832392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.114176285s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dwbkc\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-21T06:42:31.832427Z","caller":"traceutil/trace.go:171","msg":"trace[1950169596] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dwbkc; range_end:; response_count:1; response_revision:601; }","duration":"1.114248377s","start":"2026-07-21T06:42:30.718169Z","end":"2026-07-21T06:42:31.832417Z","steps":["trace[1950169596] 'agreement among raft nodes before linearized reading' (duration: 1.114150254s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:31.832454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:30.718152Z","time spent":"1.114296089s","remote":"127.0.0.1:37052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16983,"request content":"key:\"/registry/pods/kube-system/cilium-dwbkc\" "} {"level":"warn","ts":"2026-07-21T06:42:32.351458Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221322113241101,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T06:42:32.852513Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221322113241101,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T06:42:33.353142Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221322113241101,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T06:42:33.8512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000385076s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-21T06:42:33.851313Z","caller":"traceutil/trace.go:171","msg":"trace[1268283183] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000553111s","start":"2026-07-21T06:42:31.850731Z","end":"2026-07-21T06:42:33.851284Z","steps":["trace[1268283183] 'agreement among raft nodes before linearized reading' (duration: 2.000392566s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:33.851934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:31.850725Z","time spent":"2.000924453s","remote":"127.0.0.1:36850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/21 06:42:33 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-21T06:42:33.853345Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221322113241101,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T06:42:34.353974Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221322113241101,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T06:42:34.401064Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.551325164s","expected-duration":"1s"} {"level":"info","ts":"2026-07-21T06:42:34.401551Z","caller":"traceutil/trace.go:171","msg":"trace[1724810905] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"2.559700997s","start":"2026-07-21T06:42:31.841786Z","end":"2026-07-21T06:42:34.401487Z","steps":["trace[1724810905] 'process raft request' (duration: 2.559517631s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:34.401747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:31.841775Z","time spent":"2.559884443s","remote":"127.0.0.1:37052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:42:34.420474Z","caller":"traceutil/trace.go:171","msg":"trace[2127494011] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"2.554155827s","start":"2026-07-21T06:42:31.866249Z","end":"2026-07-21T06:42:34.420405Z","steps":["trace[2127494011] 'process raft request' (duration: 2.5539412s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:34.420652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:31.866228Z","time spent":"2.554335082s","remote":"127.0.0.1:37330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T06:42:34.42871Z","caller":"traceutil/trace.go:171","msg":"trace[688351042] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:628; }","duration":"2.577943099s","start":"2026-07-21T06:42:31.850748Z","end":"2026-07-21T06:42:34.428691Z","steps":["trace[688351042] 'read index received' (duration: 2.550670363s)","trace[688351042] 'applied index is now lower than readState.Index' (duration: 27.271986ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:42:34.428848Z","caller":"traceutil/trace.go:171","msg":"trace[1732933950] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"573.943356ms","start":"2026-07-21T06:42:33.854895Z","end":"2026-07-21T06:42:34.428838Z","steps":["trace[1732933950] 'process raft request' (duration: 573.706318ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:34.428929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:33.854876Z","time spent":"573.996518ms","remote":"127.0.0.1:36950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T06:42:34.429247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.947853ms","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-07-21T06:42:34.429288Z","caller":"traceutil/trace.go:171","msg":"trace[419364837] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:605; }","duration":"593.048527ms","start":"2026-07-21T06:42:33.83623Z","end":"2026-07-21T06:42:34.429278Z","steps":["trace[419364837] 'agreement among raft nodes before linearized reading' (duration: 592.935323ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:34.429315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:33.83621Z","time spent":"593.097998ms","remote":"127.0.0.1:37118","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-07-21T06:42:34.42949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.97214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:42:34.429525Z","caller":"traceutil/trace.go:171","msg":"trace[1009531336] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:605; }","duration":"571.006981ms","start":"2026-07-21T06:42:33.85851Z","end":"2026-07-21T06:42:34.429517Z","steps":["trace[1009531336] 'agreement among raft nodes before linearized reading' (duration: 570.957419ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:34.429549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:33.858465Z","time spent":"571.077443ms","remote":"127.0.0.1:36844","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-07-21T06:42:34.429688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.215613ms","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-07-21T06:42:34.42978Z","caller":"traceutil/trace.go:171","msg":"trace[1800647679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:605; }","duration":"593.314825ms","start":"2026-07-21T06:42:33.83643Z","end":"2026-07-21T06:42:34.429745Z","steps":["trace[1800647679] 'agreement among raft nodes before linearized reading' (duration: 593.064637ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:34.429914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:33.836421Z","time spent":"593.47759ms","remote":"127.0.0.1:37118","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-07-21T06:42:34.430041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.302552ms","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-07-21T06:42:34.430082Z","caller":"traceutil/trace.go:171","msg":"trace[687107027] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:605; }","duration":"576.384155ms","start":"2026-07-21T06:42:33.853688Z","end":"2026-07-21T06:42:34.430072Z","steps":["trace[687107027] 'agreement among raft nodes before linearized reading' (duration: 576.255581ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:34.430111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:33.853632Z","time spent":"576.470198ms","remote":"127.0.0.1:37118","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-07-21T06:42:34.430216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.743979ms","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-07-21T06:42:34.430242Z","caller":"traceutil/trace.go:171","msg":"trace[317751260] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:605; }","duration":"593.799851ms","start":"2026-07-21T06:42:33.836434Z","end":"2026-07-21T06:42:34.430234Z","steps":["trace[317751260] 'agreement among raft nodes before linearized reading' (duration: 593.75171ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:34.430263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:33.836412Z","time spent":"593.845253ms","remote":"127.0.0.1:37118","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-07-21T06:42:37.497049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.028804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dwbkc\" ","response":"range_response_count:1 size:17520"} {"level":"info","ts":"2026-07-21T06:42:37.49716Z","caller":"traceutil/trace.go:171","msg":"trace[76340149] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dwbkc; range_end:; response_count:1; response_revision:625; }","duration":"753.180619ms","start":"2026-07-21T06:42:36.743954Z","end":"2026-07-21T06:42:37.497135Z","steps":["trace[76340149] 'range keys from in-memory index tree' (duration: 752.860638ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:37.497176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.054925021s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-21T06:42:37.497199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:36.743938Z","time spent":"753.250321ms","remote":"127.0.0.1:37052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17542,"request content":"key:\"/registry/pods/kube-system/cilium-dwbkc\" "} {"level":"info","ts":"2026-07-21T06:42:37.497225Z","caller":"traceutil/trace.go:171","msg":"trace[234429811] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:625; }","duration":"1.055005623s","start":"2026-07-21T06:42:36.442206Z","end":"2026-07-21T06:42:37.497211Z","steps":["trace[234429811] 'range keys from in-memory index tree' (duration: 1.054782146s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:37.49728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:36.442187Z","time spent":"1.055072726s","remote":"127.0.0.1:37118","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-07-21T06:42:37.497401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.054475837s","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-07-21T06:42:37.497497Z","caller":"traceutil/trace.go:171","msg":"trace[1035297850] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:625; }","duration":"1.05459329s","start":"2026-07-21T06:42:36.44288Z","end":"2026-07-21T06:42:37.497473Z","steps":["trace[1035297850] 'range keys from in-memory index tree' (duration: 1.054373253s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:37.497521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.054514598s","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-07-21T06:42:37.497553Z","caller":"traceutil/trace.go:171","msg":"trace[870155061] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:625; }","duration":"1.054557599s","start":"2026-07-21T06:42:36.442986Z","end":"2026-07-21T06:42:37.497544Z","steps":["trace[870155061] 'range keys from in-memory index tree' (duration: 1.054440735s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:37.497562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:36.442874Z","time spent":"1.054673463s","remote":"127.0.0.1:37118","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-07-21T06:42:37.497581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:36.44298Z","time spent":"1.0545956s","remote":"127.0.0.1:37118","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-07-21T06:42:37.497405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.054411794s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-21T06:42:37.497416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.668461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-07-21T06:42:37.497681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.504834ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T06:42:37.497719Z","caller":"traceutil/trace.go:171","msg":"trace[642712823] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:625; }","duration":"459.044903ms","start":"2026-07-21T06:42:37.038662Z","end":"2026-07-21T06:42:37.497707Z","steps":["trace[642712823] 'range keys from in-memory index tree' (duration: 458.505975ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:42:37.497762Z","caller":"traceutil/trace.go:171","msg":"trace[1059649008] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"326.532208ms","start":"2026-07-21T06:42:37.171199Z","end":"2026-07-21T06:42:37.497731Z","steps":["trace[1059649008] 'process raft request' (duration: 177.922151ms)","trace[1059649008] 'compare' (duration: 148.418871ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:42:37.497826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:37.17117Z","time spent":"326.629941ms","remote":"127.0.0.1:37118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:42:37.498265Z","caller":"traceutil/trace.go:171","msg":"trace[1548641686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:625; }","duration":"1.055013874s","start":"2026-07-21T06:42:36.44268Z","end":"2026-07-21T06:42:37.497694Z","steps":["trace[1548641686] 'range keys from in-memory index tree' (duration: 1.054193237s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:37.498498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:37.038638Z","time spent":"459.843289ms","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.72\" "} {"level":"warn","ts":"2026-07-21T06:42:37.498779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:36.442662Z","time spent":"1.056094069s","remote":"127.0.0.1:37118","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-07-21T06:42:37.990303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.892237ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-21T06:42:37.990599Z","caller":"traceutil/trace.go:171","msg":"trace[1832757904] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"248.795403ms","start":"2026-07-21T06:42:37.741771Z","end":"2026-07-21T06:42:37.990567Z","steps":["trace[1832757904] 'process raft request' (duration: 103.569555ms)","trace[1832757904] 'compare' (duration: 144.760113ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:42:37.990668Z","caller":"traceutil/trace.go:171","msg":"trace[624173574] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:664; }","duration":"241.813376ms","start":"2026-07-21T06:42:37.748834Z","end":"2026-07-21T06:42:37.990647Z","steps":["trace[624173574] 'read index received' (duration: 13.494228ms)","trace[624173574] 'applied index is now lower than readState.Index' (duration: 228.318097ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:42:37.99072Z","caller":"traceutil/trace.go:171","msg":"trace[1685958069] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"245.841237ms","start":"2026-07-21T06:42:37.744838Z","end":"2026-07-21T06:42:37.99068Z","steps":["trace[1685958069] 'process raft request' (duration: 245.64487ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:42:37.990826Z","caller":"traceutil/trace.go:171","msg":"trace[894849136] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"241.156675ms","start":"2026-07-21T06:42:37.749654Z","end":"2026-07-21T06:42:37.990811Z","steps":["trace[894849136] 'process raft request' (duration: 240.927157ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:37.990965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.122556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dwbkc\" ","response":"range_response_count:1 size:17704"} {"level":"info","ts":"2026-07-21T06:42:37.991038Z","caller":"traceutil/trace.go:171","msg":"trace[1148598210] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dwbkc; range_end:; response_count:1; response_revision:640; }","duration":"242.22851ms","start":"2026-07-21T06:42:37.748797Z","end":"2026-07-21T06:42:37.991026Z","steps":["trace[1148598210] 'agreement among raft nodes before linearized reading' (duration: 241.943931ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:42:38.03291Z","caller":"traceutil/trace.go:171","msg":"trace[867745465] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"267.464829ms","start":"2026-07-21T06:42:37.765416Z","end":"2026-07-21T06:42:38.032881Z","steps":["trace[867745465] 'process raft request' (duration: 267.159339ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:42:38.034011Z","caller":"traceutil/trace.go:171","msg":"trace[1139669828] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"267.836691ms","start":"2026-07-21T06:42:37.766151Z","end":"2026-07-21T06:42:38.033988Z","steps":["trace[1139669828] 'process raft request' (duration: 267.196731ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:42:38.103688Z","caller":"traceutil/trace.go:171","msg":"trace[599642393] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"168.167853ms","start":"2026-07-21T06:42:37.93549Z","end":"2026-07-21T06:42:38.103658Z","steps":["trace[599642393] 'process raft request' (duration: 166.100326ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:42:38.103787Z","caller":"traceutil/trace.go:171","msg":"trace[1378467408] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"109.261279ms","start":"2026-07-21T06:42:37.994513Z","end":"2026-07-21T06:42:38.103774Z","steps":["trace[1378467408] 'process raft request' (duration: 109.092674ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:42:38.16535Z","caller":"traceutil/trace.go:171","msg":"trace[1552249459] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"126.301832ms","start":"2026-07-21T06:42:38.039021Z","end":"2026-07-21T06:42:38.165323Z","steps":["trace[1552249459] 'process raft request' (duration: 126.121286ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:42:38.165769Z","caller":"traceutil/trace.go:171","msg":"trace[1587711449] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"125.446284ms","start":"2026-07-21T06:42:38.040291Z","end":"2026-07-21T06:42:38.165737Z","steps":["trace[1587711449] 'process raft request' (duration: 124.986989ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:42:38.301436Z","caller":"traceutil/trace.go:171","msg":"trace[1185654196] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"166.87416ms","start":"2026-07-21T06:42:38.13454Z","end":"2026-07-21T06:42:38.301414Z","steps":["trace[1185654196] 'process raft request' (duration: 166.739726ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:42:38.36336Z","caller":"traceutil/trace.go:171","msg":"trace[1116770361] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"192.69014ms","start":"2026-07-21T06:42:38.170649Z","end":"2026-07-21T06:42:38.36334Z","steps":["trace[1116770361] 'process raft request' (duration: 192.407671ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:42:38.363417Z","caller":"traceutil/trace.go:171","msg":"trace[1615444450] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"189.382023ms","start":"2026-07-21T06:42:38.174019Z","end":"2026-07-21T06:42:38.363401Z","steps":["trace[1615444450] 'process raft request' (duration: 189.263159ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:42:38.363843Z","caller":"traceutil/trace.go:171","msg":"trace[723385971] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"190.51288ms","start":"2026-07-21T06:42:38.173301Z","end":"2026-07-21T06:42:38.363814Z","steps":["trace[723385971] 'process raft request' (duration: 189.929741ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:42:38.537163Z","caller":"traceutil/trace.go:171","msg":"trace[224897048] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"200.648379ms","start":"2026-07-21T06:42:38.336407Z","end":"2026-07-21T06:42:38.537055Z","steps":["trace[224897048] 'process raft request' (duration: 113.596431ms)","trace[224897048] 'compare' (duration: 86.845721ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:42:38.572969Z","caller":"traceutil/trace.go:171","msg":"trace[751232075] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"200.584076ms","start":"2026-07-21T06:42:38.372354Z","end":"2026-07-21T06:42:38.572938Z","steps":["trace[751232075] 'process raft request' (duration: 200.368689ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:42:38.573318Z","caller":"traceutil/trace.go:171","msg":"trace[1533424966] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"200.551635ms","start":"2026-07-21T06:42:38.372747Z","end":"2026-07-21T06:42:38.573298Z","steps":["trace[1533424966] 'process raft request' (duration: 200.124761ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:42:38.573372Z","caller":"traceutil/trace.go:171","msg":"trace[1111065517] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"200.315508ms","start":"2026-07-21T06:42:38.373039Z","end":"2026-07-21T06:42:38.573355Z","steps":["trace[1111065517] 'process raft request' (duration: 199.891934ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:42:38.57358Z","caller":"traceutil/trace.go:171","msg":"trace[450736136] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"180.980589ms","start":"2026-07-21T06:42:38.392582Z","end":"2026-07-21T06:42:38.573562Z","steps":["trace[450736136] 'process raft request' (duration: 180.39438ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:40.139762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.815926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-07-21T06:42:40.139895Z","caller":"traceutil/trace.go:171","msg":"trace[951149859] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:680; }","duration":"205.955881ms","start":"2026-07-21T06:42:39.9339Z","end":"2026-07-21T06:42:40.139856Z","steps":["trace[951149859] 'range keys from in-memory index tree' (duration: 205.61703ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:42:47.782962Z","caller":"traceutil/trace.go:171","msg":"trace[1782384097] linearizableReadLoop","detail":"{readStateIndex:777; appliedIndex:776; }","duration":"177.986462ms","start":"2026-07-21T06:42:47.604955Z","end":"2026-07-21T06:42:47.782941Z","steps":["trace[1782384097] 'read index received' (duration: 177.756375ms)","trace[1782384097] 'applied index is now lower than readState.Index' (duration: 228.427µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:42:47.783119Z","caller":"traceutil/trace.go:171","msg":"trace[1273477475] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"217.462085ms","start":"2026-07-21T06:42:47.565603Z","end":"2026-07-21T06:42:47.783066Z","steps":["trace[1273477475] 'process raft request' (duration: 217.158195ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:47.783223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.25572ms","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-07-21T06:42:47.783301Z","caller":"traceutil/trace.go:171","msg":"trace[751099822] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:746; }","duration":"178.381295ms","start":"2026-07-21T06:42:47.604903Z","end":"2026-07-21T06:42:47.783284Z","steps":["trace[751099822] 'agreement among raft nodes before linearized reading' (duration: 178.193629ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:47.783381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.234038ms","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-07-21T06:42:47.783437Z","caller":"traceutil/trace.go:171","msg":"trace[620036109] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:746; }","duration":"177.30562ms","start":"2026-07-21T06:42:47.606117Z","end":"2026-07-21T06:42:47.783423Z","steps":["trace[620036109] 'agreement among raft nodes before linearized reading' (duration: 177.142204ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:47.783472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.746167ms","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-07-21T06:42:47.783521Z","caller":"traceutil/trace.go:171","msg":"trace[454283255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:746; }","duration":"174.84387ms","start":"2026-07-21T06:42:47.608664Z","end":"2026-07-21T06:42:47.783508Z","steps":["trace[454283255] 'agreement among raft nodes before linearized reading' (duration: 174.718446ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:42:51.035236Z","caller":"traceutil/trace.go:171","msg":"trace[2128656760] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"203.538932ms","start":"2026-07-21T06:42:50.83168Z","end":"2026-07-21T06:42:51.035218Z","steps":["trace[2128656760] 'process raft request' (duration: 203.405158ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:43:26.17268Z","caller":"traceutil/trace.go:171","msg":"trace[186175049] linearizableReadLoop","detail":"{readStateIndex:1029; appliedIndex:1028; }","duration":"216.753453ms","start":"2026-07-21T06:43:25.955911Z","end":"2026-07-21T06:43:26.172664Z","steps":["trace[186175049] 'read index received' (duration: 216.635849ms)","trace[186175049] 'applied index is now lower than readState.Index' (duration: 116.964µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:43:26.172724Z","caller":"traceutil/trace.go:171","msg":"trace[2053590761] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"216.815215ms","start":"2026-07-21T06:43:25.955878Z","end":"2026-07-21T06:43:26.172693Z","steps":["trace[2053590761] 'process raft request' (duration: 216.679911ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:43:26.172846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.921279ms","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-07-21T06:43:26.172873Z","caller":"traceutil/trace.go:171","msg":"trace[1391597587] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:988; }","duration":"216.990181ms","start":"2026-07-21T06:43:25.955876Z","end":"2026-07-21T06:43:26.172866Z","steps":["trace[1391597587] 'agreement among raft nodes before linearized reading' (duration: 216.896348ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:43:26.172901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.021661ms","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-07-21T06:43:26.172959Z","caller":"traceutil/trace.go:171","msg":"trace[1909541795] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:988; }","duration":"214.086573ms","start":"2026-07-21T06:43:25.958859Z","end":"2026-07-21T06:43:26.172946Z","steps":["trace[1909541795] 'agreement among raft nodes before linearized reading' (duration: 213.923637ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:43:26.17319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.477387ms","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-07-21T06:43:26.17342Z","caller":"traceutil/trace.go:171","msg":"trace[217494223] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:988; }","duration":"197.84887ms","start":"2026-07-21T06:43:25.975554Z","end":"2026-07-21T06:43:26.173403Z","steps":["trace[217494223] 'agreement among raft nodes before linearized reading' (duration: 197.372663ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:43:26.173144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.05395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-21T06:43:26.173636Z","caller":"traceutil/trace.go:171","msg":"trace[472137192] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:988; }","duration":"194.696212ms","start":"2026-07-21T06:43:25.978929Z","end":"2026-07-21T06:43:26.173626Z","steps":["trace[472137192] 'agreement among raft nodes before linearized reading' (duration: 193.979418ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:43:26.77314Z","caller":"traceutil/trace.go:171","msg":"trace[200971735] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"596.928414ms","start":"2026-07-21T06:43:26.176191Z","end":"2026-07-21T06:43:26.77312Z","steps":["trace[200971735] 'process raft request' (duration: 563.236336ms)","trace[200971735] 'compare' (duration: 33.583594ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:43:26.77325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:43:26.176176Z","time spent":"597.018627ms","remote":"127.0.0.1:37118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:43:28.006841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.396404ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T06:43:28.007082Z","caller":"traceutil/trace.go:171","msg":"trace[835503278] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"1.82918128s","start":"2026-07-21T06:43:26.177879Z","end":"2026-07-21T06:43:28.007061Z","steps":["trace[835503278] 'process raft request' (duration: 1.829058286s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:43:28.007176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:43:26.177868Z","time spent":"1.829260833s","remote":"127.0.0.1:37118","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-07-21T06:43:28.007279Z","caller":"traceutil/trace.go:171","msg":"trace[1811760538] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"1.830325829s","start":"2026-07-21T06:43:26.176936Z","end":"2026-07-21T06:43:28.007262Z","steps":["trace[1811760538] 'process raft request' (duration: 1.236391497s)","trace[1811760538] 'compare' (duration: 593.26774ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:43:28.007346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:43:26.176915Z","time spent":"1.830395842s","remote":"127.0.0.1:37118","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-07-21T06:43:28.007356Z","caller":"traceutil/trace.go:171","msg":"trace[2064036247] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"1.829328606s","start":"2026-07-21T06:43:26.178003Z","end":"2026-07-21T06:43:28.007332Z","steps":["trace[2064036247] 'process raft request' (duration: 1.829009005s)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:43:28.007466Z","caller":"traceutil/trace.go:171","msg":"trace[1435120160] linearizableReadLoop","detail":"{readStateIndex:1034; appliedIndex:1030; }","duration":"1.064210052s","start":"2026-07-21T06:43:26.943242Z","end":"2026-07-21T06:43:28.007452Z","steps":["trace[1435120160] 'read index received' (duration: 470.00115ms)","trace[1435120160] 'applied index is now lower than readState.Index' (duration: 594.207602ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:43:28.007473Z","caller":"traceutil/trace.go:171","msg":"trace[1289034672] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"1.690472905s","start":"2026-07-21T06:43:26.31699Z","end":"2026-07-21T06:43:28.007463Z","steps":["trace[1289034672] 'process raft request' (duration: 1.690282079s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:43:28.007483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:43:26.177996Z","time spent":"1.829413718s","remote":"127.0.0.1:37118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:43:28.007523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:43:26.316968Z","time spent":"1.690529197s","remote":"127.0.0.1:37118","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-07-21T06:43:28.007576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.022080367s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:43:28.007607Z","caller":"traceutil/trace.go:171","msg":"trace[806824706] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:993; }","duration":"1.02216193s","start":"2026-07-21T06:43:26.985434Z","end":"2026-07-21T06:43:28.007596Z","steps":["trace[806824706] 'agreement among raft nodes before linearized reading' (duration: 1.022096597s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:43:28.007606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.189417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T06:43:28.007636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:43:26.985415Z","time spent":"1.022212202s","remote":"127.0.0.1:37088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-07-21T06:43:28.007617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.064344926s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T06:43:28.007658Z","caller":"traceutil/trace.go:171","msg":"trace[1112463480] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:993; }","duration":"421.26938ms","start":"2026-07-21T06:43:27.586374Z","end":"2026-07-21T06:43:28.007643Z","steps":["trace[1112463480] 'agreement among raft nodes before linearized reading' (duration: 421.185287ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:43:28.00771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"966.036598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-21T06:43:28.007711Z","caller":"traceutil/trace.go:171","msg":"trace[1617287496] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:993; }","duration":"1.06445493s","start":"2026-07-21T06:43:26.943229Z","end":"2026-07-21T06:43:28.007684Z","steps":["trace[1617287496] 'agreement among raft nodes before linearized reading' (duration: 1.064294264s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:43:28.007735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:43:27.586357Z","time spent":"421.366654ms","remote":"127.0.0.1:36844","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-07-21T06:43:28.007764Z","caller":"traceutil/trace.go:171","msg":"trace[1009930924] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:993; }","duration":"966.126971ms","start":"2026-07-21T06:43:27.041622Z","end":"2026-07-21T06:43:28.007748Z","steps":["trace[1009930924] 'agreement among raft nodes before linearized reading' (duration: 965.956315ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:43:28.007783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:43:26.943172Z","time spent":"1.064596485s","remote":"127.0.0.1:37320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":29,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-07-21T06:43:28.007809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:43:27.041606Z","time spent":"966.191083ms","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.72\" "} {"level":"warn","ts":"2026-07-21T06:43:50.616651Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221322113242153,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T06:43:51.070513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.483854745s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:43:51.070618Z","caller":"traceutil/trace.go:171","msg":"trace[182728298] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1063; }","duration":"1.484037682s","start":"2026-07-21T06:43:49.58655Z","end":"2026-07-21T06:43:51.070588Z","steps":["trace[182728298] 'range keys from in-memory index tree' (duration: 1.483737621s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:43:51.070672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:43:49.58653Z","time spent":"1.484128044s","remote":"127.0.0.1:36844","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-07-21T06:43:51.071435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.726213ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T06:43:51.071527Z","caller":"traceutil/trace.go:171","msg":"trace[256331217] linearizableReadLoop","detail":"{readStateIndex:1110; appliedIndex:1109; }","duration":"955.470567ms","start":"2026-07-21T06:43:50.11604Z","end":"2026-07-21T06:43:51.071511Z","steps":["trace[256331217] 'read index received' (duration: 519.542478ms)","trace[256331217] 'applied index is now lower than readState.Index' (duration: 435.926499ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:43:51.071556Z","caller":"traceutil/trace.go:171","msg":"trace[842218294] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"970.81956ms","start":"2026-07-21T06:43:50.100711Z","end":"2026-07-21T06:43:51.07153Z","steps":["trace[842218294] 'process raft request' (duration: 534.914741ms)","trace[842218294] 'compare' (duration: 434.816732ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:43:51.071714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:43:50.100684Z","time spent":"970.964795ms","remote":"127.0.0.1:37118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:43:51.071883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"955.512458ms","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-07-21T06:43:51.071946Z","caller":"traceutil/trace.go:171","msg":"trace[635743262] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1064; }","duration":"955.638063ms","start":"2026-07-21T06:43:50.116291Z","end":"2026-07-21T06:43:51.071929Z","steps":["trace[635743262] 'agreement among raft nodes before linearized reading' (duration: 955.339432ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:43:51.071769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"955.718176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-21T06:43:51.072048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:43:50.116275Z","time spent":"955.756737ms","remote":"127.0.0.1:37118","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":"info","ts":"2026-07-21T06:43:51.072124Z","caller":"traceutil/trace.go:171","msg":"trace[1503501102] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1064; }","duration":"956.093248ms","start":"2026-07-21T06:43:50.115997Z","end":"2026-07-21T06:43:51.07209Z","steps":["trace[1503501102] 'agreement among raft nodes before linearized reading' (duration: 955.617672ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:43:51.072155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.329619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-21T06:43:51.072202Z","caller":"traceutil/trace.go:171","msg":"trace[231073693] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1064; }","duration":"184.413421ms","start":"2026-07-21T06:43:50.887776Z","end":"2026-07-21T06:43:51.07219Z","steps":["trace[231073693] 'agreement among raft nodes before linearized reading' (duration: 184.305688ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:43:51.072221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:43:50.115975Z","time spent":"956.214542ms","remote":"127.0.0.1:37118","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-07-21T06:43:51.072052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.953762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-21T06:43:51.072331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.043904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:43:51.072356Z","caller":"traceutil/trace.go:171","msg":"trace[1947809358] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1064; }","duration":"680.297624ms","start":"2026-07-21T06:43:50.392047Z","end":"2026-07-21T06:43:51.072344Z","steps":["trace[1947809358] 'agreement among raft nodes before linearized reading' (duration: 679.960392ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:43:51.07237Z","caller":"traceutil/trace.go:171","msg":"trace[552733466] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1064; }","duration":"451.112626ms","start":"2026-07-21T06:43:50.621246Z","end":"2026-07-21T06:43:51.072358Z","steps":["trace[552733466] 'agreement among raft nodes before linearized reading' (duration: 451.058244ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:43:51.072394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:43:50.392026Z","time spent":"680.354786ms","remote":"127.0.0.1:37060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":29,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-07-21T06:43:51.072404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:43:50.621229Z","time spent":"451.167158ms","remote":"127.0.0.1:36844","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-07-21T06:43:51.072406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.099512ms","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-07-21T06:43:51.072451Z","caller":"traceutil/trace.go:171","msg":"trace[421424636] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1064; }","duration":"765.179505ms","start":"2026-07-21T06:43:50.307258Z","end":"2026-07-21T06:43:51.072437Z","steps":["trace[421424636] 'agreement among raft nodes before linearized reading' (duration: 765.060471ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:43:51.072628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.590443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:43:51.07275Z","caller":"traceutil/trace.go:171","msg":"trace[1227618122] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1064; }","duration":"815.762698ms","start":"2026-07-21T06:43:50.256975Z","end":"2026-07-21T06:43:51.072738Z","steps":["trace[1227618122] 'agreement among raft nodes before linearized reading' (duration: 815.610003ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:43:51.072852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:43:50.256956Z","time spent":"815.883933ms","remote":"127.0.0.1:37150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-21T06:43:51.072829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"946.279474ms","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-07-21T06:43:51.073025Z","caller":"traceutil/trace.go:171","msg":"trace[425474178] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1064; }","duration":"946.517902ms","start":"2026-07-21T06:43:50.126462Z","end":"2026-07-21T06:43:51.07298Z","steps":["trace[425474178] 'agreement among raft nodes before linearized reading' (duration: 946.235213ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:43:51.073125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:43:50.126449Z","time spent":"946.655717ms","remote":"127.0.0.1:37118","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-07-21T06:43:51.072663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:43:50.30724Z","time spent":"765.407083ms","remote":"127.0.0.1:37118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-21T06:43:51.236107Z","caller":"traceutil/trace.go:171","msg":"trace[1805208898] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"157.498195ms","start":"2026-07-21T06:43:51.078591Z","end":"2026-07-21T06:43:51.236089Z","steps":["trace[1805208898] 'process raft request' (duration: 157.465794ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:43:51.236278Z","caller":"traceutil/trace.go:171","msg":"trace[137049934] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"157.815226ms","start":"2026-07-21T06:43:51.07844Z","end":"2026-07-21T06:43:51.236255Z","steps":["trace[137049934] 'process raft request' (duration: 157.542326ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:43:51.236447Z","caller":"traceutil/trace.go:171","msg":"trace[1001094648] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"158.456058ms","start":"2026-07-21T06:43:51.077976Z","end":"2026-07-21T06:43:51.236432Z","steps":["trace[1001094648] 'process raft request' (duration: 150.357532ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:43:51.236488Z","caller":"traceutil/trace.go:171","msg":"trace[1749095433] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"157.94837ms","start":"2026-07-21T06:43:51.078534Z","end":"2026-07-21T06:43:51.236483Z","steps":["trace[1749095433] 'process raft request' (duration: 157.498435ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:43:51.236466Z","caller":"traceutil/trace.go:171","msg":"trace[1419038781] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"157.9437ms","start":"2026-07-21T06:43:51.078514Z","end":"2026-07-21T06:43:51.236457Z","steps":["trace[1419038781] 'process raft request' (duration: 157.497265ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:43:51.735252Z","caller":"traceutil/trace.go:171","msg":"trace[1719255952] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"190.368165ms","start":"2026-07-21T06:43:51.544861Z","end":"2026-07-21T06:43:51.73523Z","steps":["trace[1719255952] 'process raft request' (duration: 190.23566ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:43:53.796443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.45020541s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-21T06:43:53.796567Z","caller":"traceutil/trace.go:171","msg":"trace[1947112694] linearizableReadLoop","detail":"{readStateIndex:1117; appliedIndex:1116; }","duration":"1.211770387s","start":"2026-07-21T06:43:52.584767Z","end":"2026-07-21T06:43:53.796537Z","steps":["trace[1947112694] 'read index received' (duration: 47.341µs)","trace[1947112694] 'applied index is now lower than readState.Index' (duration: 1.211721266s)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:43:53.797958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.213166856s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:43:53.798031Z","caller":"traceutil/trace.go:171","msg":"trace[122072719] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1070; }","duration":"1.213255419s","start":"2026-07-21T06:43:52.584759Z","end":"2026-07-21T06:43:53.798015Z","steps":["trace[122072719] 'agreement among raft nodes before linearized reading' (duration: 1.213116734s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:43:53.798069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:43:52.584704Z","time spent":"1.213355931s","remote":"127.0.0.1:36844","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-07-21T06:43:53.814215Z","caller":"traceutil/trace.go:171","msg":"trace[763586384] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"735.813464ms","start":"2026-07-21T06:43:53.078369Z","end":"2026-07-21T06:43:53.814182Z","steps":["trace[763586384] 'process raft request' (duration: 735.438112ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:43:53.814298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.238486ms","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-07-21T06:43:53.814354Z","caller":"traceutil/trace.go:171","msg":"trace[1262003121] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1071; }","duration":"568.33717ms","start":"2026-07-21T06:43:53.245995Z","end":"2026-07-21T06:43:53.814332Z","steps":["trace[1262003121] 'agreement among raft nodes before linearized reading' (duration: 568.126622ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:43:53.814421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:43:53.245946Z","time spent":"568.460313ms","remote":"127.0.0.1:37118","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-07-21T06:43:53.814525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.721592ms","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-07-21T06:43:53.814559Z","caller":"traceutil/trace.go:171","msg":"trace[871925265] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1071; }","duration":"568.795685ms","start":"2026-07-21T06:43:53.245754Z","end":"2026-07-21T06:43:53.814549Z","steps":["trace[871925265] 'agreement among raft nodes before linearized reading' (duration: 568.636339ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:43:53.814579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:43:53.245726Z","time spent":"568.847847ms","remote":"127.0.0.1:37118","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-07-21T06:43:53.814571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.575147ms","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-07-21T06:43:53.814615Z","caller":"traceutil/trace.go:171","msg":"trace[943616009] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1071; }","duration":"568.65693ms","start":"2026-07-21T06:43:53.245948Z","end":"2026-07-21T06:43:53.814605Z","steps":["trace[943616009] 'agreement among raft nodes before linearized reading' (duration: 568.234515ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:43:53.814641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:43:53.245931Z","time spent":"568.704992ms","remote":"127.0.0.1:37118","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-07-21T06:43:53.814463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:43:53.078338Z","time spent":"735.933158ms","remote":"127.0.0.1:37118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:43:53.81466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.636459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-21T06:43:53.814713Z","caller":"traceutil/trace.go:171","msg":"trace[1153507246] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1071; }","duration":"568.727402ms","start":"2026-07-21T06:43:53.245971Z","end":"2026-07-21T06:43:53.814699Z","steps":["trace[1153507246] 'agreement among raft nodes before linearized reading' (duration: 568.07491ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:43:53.814752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:43:53.245943Z","time spent":"568.801615ms","remote":"127.0.0.1:37118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-21T06:43:53.814782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.666661ms","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-07-21T06:43:53.814805Z","caller":"traceutil/trace.go:171","msg":"trace[269758191] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1071; }","duration":"568.692251ms","start":"2026-07-21T06:43:53.246107Z","end":"2026-07-21T06:43:53.814799Z","steps":["trace[269758191] 'agreement among raft nodes before linearized reading' (duration: 567.979147ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:43:53.814828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:43:53.246071Z","time spent":"568.751733ms","remote":"127.0.0.1:37118","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-07-21T06:43:56.339017Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221322113242176,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T06:43:56.839136Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221322113242176,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T06:43:57.253302Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.431806633s","expected-duration":"1s"} {"level":"info","ts":"2026-07-21T06:43:57.253639Z","caller":"traceutil/trace.go:171","msg":"trace[896005425] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"1.432232058s","start":"2026-07-21T06:43:55.821384Z","end":"2026-07-21T06:43:57.253616Z","steps":["trace[896005425] 'process raft request' (duration: 1.432111964s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:43:57.253754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:43:55.82136Z","time spent":"1.432324052s","remote":"127.0.0.1:37118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:43:57.254367Z","caller":"traceutil/trace.go:171","msg":"trace[1868873325] linearizableReadLoop","detail":"{readStateIndex:1124; appliedIndex:1124; }","duration":"1.415650923s","start":"2026-07-21T06:43:55.838683Z","end":"2026-07-21T06:43:57.254334Z","steps":["trace[1868873325] 'read index received' (duration: 1.415638522s)","trace[1868873325] 'applied index is now lower than readState.Index' (duration: 9.561µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:43:57.254658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.415958694s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-21T06:43:57.254719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.326422183s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:43:57.254728Z","caller":"traceutil/trace.go:171","msg":"trace[505705461] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1077; }","duration":"1.416081818s","start":"2026-07-21T06:43:55.838629Z","end":"2026-07-21T06:43:57.254711Z","steps":["trace[505705461] 'agreement among raft nodes before linearized reading' (duration: 1.415839299s)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:43:57.254774Z","caller":"traceutil/trace.go:171","msg":"trace[1103315579] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1077; }","duration":"1.326511546s","start":"2026-07-21T06:43:55.92825Z","end":"2026-07-21T06:43:57.254761Z","steps":["trace[1103315579] 'agreement among raft nodes before linearized reading' (duration: 1.326419133s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:43:57.254803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:43:55.838611Z","time spent":"1.416165501s","remote":"127.0.0.1:37118","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-07-21T06:43:57.254816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:43:55.928234Z","time spent":"1.326573049s","remote":"127.0.0.1:37302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-07-21T06:43:57.255086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.762304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-21T06:43:57.255123Z","caller":"traceutil/trace.go:171","msg":"trace[1680052325] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:1077; }","duration":"211.873497ms","start":"2026-07-21T06:43:57.043239Z","end":"2026-07-21T06:43:57.255112Z","steps":["trace[1680052325] 'agreement among raft nodes before linearized reading' (duration: 211.751883ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:43:57.255286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.879523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:43:57.255343Z","caller":"traceutil/trace.go:171","msg":"trace[26299652] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1077; }","duration":"670.975376ms","start":"2026-07-21T06:43:56.584352Z","end":"2026-07-21T06:43:57.255328Z","steps":["trace[26299652] 'agreement among raft nodes before linearized reading' (duration: 670.887593ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:43:57.255386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:43:56.584307Z","time spent":"671.068189ms","remote":"127.0.0.1:36844","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-07-21T06:43:57.255455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.364643165s","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-07-21T06:43:57.255506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.36507186s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-21T06:43:57.255572Z","caller":"traceutil/trace.go:171","msg":"trace[1495449178] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1077; }","duration":"1.365156383s","start":"2026-07-21T06:43:55.890399Z","end":"2026-07-21T06:43:57.255556Z","steps":["trace[1495449178] 'agreement among raft nodes before linearized reading' (duration: 1.364955727s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:43:57.255628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:43:55.890393Z","time spent":"1.365218745s","remote":"127.0.0.1:37118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-21T06:43:57.255661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.365240255s","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-07-21T06:43:57.255662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.364554603s","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-07-21T06:43:57.255526Z","caller":"traceutil/trace.go:171","msg":"trace[77705404] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1077; }","duration":"1.365432293s","start":"2026-07-21T06:43:55.890076Z","end":"2026-07-21T06:43:57.255508Z","steps":["trace[77705404] 'agreement among raft nodes before linearized reading' (duration: 1.364553553s)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:43:57.255718Z","caller":"traceutil/trace.go:171","msg":"trace[207369939] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1077; }","duration":"1.364622255s","start":"2026-07-21T06:43:55.891085Z","end":"2026-07-21T06:43:57.255707Z","steps":["trace[207369939] 'agreement among raft nodes before linearized reading' (duration: 1.36450159s)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:43:57.255718Z","caller":"traceutil/trace.go:171","msg":"trace[1080755879] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1077; }","duration":"1.365308878s","start":"2026-07-21T06:43:55.890392Z","end":"2026-07-21T06:43:57.255701Z","steps":["trace[1080755879] 'agreement among raft nodes before linearized reading' (duration: 1.365161223s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:43:57.255737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:43:55.890055Z","time spent":"1.36566567s","remote":"127.0.0.1:37118","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-07-21T06:43:57.255757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:43:55.891067Z","time spent":"1.364682657s","remote":"127.0.0.1:37118","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-07-21T06:43:57.255771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:43:55.890384Z","time spent":"1.36537478s","remote":"127.0.0.1:37118","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":"info","ts":"2026-07-21T06:43:57.677809Z","caller":"traceutil/trace.go:171","msg":"trace[1287574460] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"417.621565ms","start":"2026-07-21T06:43:57.260166Z","end":"2026-07-21T06:43:57.677788Z","steps":["trace[1287574460] 'process raft request' (duration: 365.51632ms)","trace[1287574460] 'compare' (duration: 51.566817ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:43:57.677808Z","caller":"traceutil/trace.go:171","msg":"trace[344577615] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"416.895531ms","start":"2026-07-21T06:43:57.260864Z","end":"2026-07-21T06:43:57.677759Z","steps":["trace[344577615] 'process raft request' (duration: 416.838129ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:43:57.677818Z","caller":"traceutil/trace.go:171","msg":"trace[1582418303] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"417.283214ms","start":"2026-07-21T06:43:57.260501Z","end":"2026-07-21T06:43:57.677784Z","steps":["trace[1582418303] 'process raft request' (duration: 416.998885ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:43:57.677913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:43:57.260145Z","time spent":"417.715669ms","remote":"127.0.0.1:37118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:43:57.677969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:43:57.260858Z","time spent":"417.022336ms","remote":"127.0.0.1:37118","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-07-21T06:43:57.678016Z","caller":"traceutil/trace.go:171","msg":"trace[897483733] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"417.1617ms","start":"2026-07-21T06:43:57.26085Z","end":"2026-07-21T06:43:57.678012Z","steps":["trace[897483733] 'process raft request' (duration: 416.806408ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:43:57.678015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:43:57.260494Z","time spent":"417.414279ms","remote":"127.0.0.1:37118","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-07-21T06:43:57.678043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:43:57.260842Z","time spent":"417.186231ms","remote":"127.0.0.1:37118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:43:57.677994Z","caller":"traceutil/trace.go:171","msg":"trace[1996472326] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"417.195781ms","start":"2026-07-21T06:43:57.260793Z","end":"2026-07-21T06:43:57.677989Z","steps":["trace[1996472326] 'process raft request' (duration: 416.811608ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:43:57.678191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:43:57.260777Z","time spent":"417.350917ms","remote":"127.0.0.1:37118","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-07-21T06:43:57.685159Z","caller":"traceutil/trace.go:171","msg":"trace[2129653970] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"412.678858ms","start":"2026-07-21T06:43:57.272466Z","end":"2026-07-21T06:43:57.685145Z","steps":["trace[2129653970] 'process raft request' (duration: 412.575115ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:43:57.685231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:43:57.272457Z","time spent":"412.73865ms","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:44:01.842242Z","caller":"traceutil/trace.go:171","msg":"trace[968592050] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"145.167716ms","start":"2026-07-21T06:44:01.697056Z","end":"2026-07-21T06:44:01.842223Z","steps":["trace[968592050] 'process raft request' (duration: 145.00313ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:44:01.842537Z","caller":"traceutil/trace.go:171","msg":"trace[154589177] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"145.327511ms","start":"2026-07-21T06:44:01.69719Z","end":"2026-07-21T06:44:01.842518Z","steps":["trace[154589177] 'process raft request' (duration: 144.99939ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:44:01.842609Z","caller":"traceutil/trace.go:171","msg":"trace[1822306446] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"145.351592ms","start":"2026-07-21T06:44:01.697251Z","end":"2026-07-21T06:44:01.842603Z","steps":["trace[1822306446] 'process raft request' (duration: 145.169645ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:44:01.842587Z","caller":"traceutil/trace.go:171","msg":"trace[80794735] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"145.245918ms","start":"2026-07-21T06:44:01.697318Z","end":"2026-07-21T06:44:01.842564Z","steps":["trace[80794735] 'process raft request' (duration: 145.201427ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:44:12.221176Z","caller":"traceutil/trace.go:171","msg":"trace[1431825346] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"122.05988ms","start":"2026-07-21T06:44:12.099089Z","end":"2026-07-21T06:44:12.221149Z","steps":["trace[1431825346] 'process raft request' (duration: 121.841383ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:12.927063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.605808ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-21T06:44:12.927192Z","caller":"traceutil/trace.go:171","msg":"trace[1890116783] linearizableReadLoop","detail":"{readStateIndex:1235; appliedIndex:1234; }","duration":"341.15667ms","start":"2026-07-21T06:44:12.586011Z","end":"2026-07-21T06:44:12.927168Z","steps":["trace[1890116783] 'read index received' (duration: 207.380446ms)","trace[1890116783] 'applied index is now lower than readState.Index' (duration: 133.774184ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:44:12.927312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.288605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:44:12.927478Z","caller":"traceutil/trace.go:171","msg":"trace[509103170] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1182; }","duration":"341.495172ms","start":"2026-07-21T06:44:12.585964Z","end":"2026-07-21T06:44:12.927459Z","steps":["trace[509103170] 'agreement among raft nodes before linearized reading' (duration: 341.284125ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:12.927529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:44:12.585928Z","time spent":"341.585944ms","remote":"127.0.0.1:36844","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-07-21T06:44:14.433667Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221322113242414,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T06:44:14.934313Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221322113242414,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T06:44:15.420842Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.603411329s","expected-duration":"1s"} {"level":"info","ts":"2026-07-21T06:44:15.421269Z","caller":"traceutil/trace.go:171","msg":"trace[524556565] linearizableReadLoop","detail":"{readStateIndex:1237; appliedIndex:1236; }","duration":"1.48764282s","start":"2026-07-21T06:44:13.933605Z","end":"2026-07-21T06:44:15.421247Z","steps":["trace[524556565] 'read index received' (duration: 1.487453334s)","trace[524556565] 'applied index is now lower than readState.Index' (duration: 188.436µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:44:15.421433Z","caller":"traceutil/trace.go:171","msg":"trace[789544529] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"1.60402766s","start":"2026-07-21T06:44:13.817364Z","end":"2026-07-21T06:44:15.421392Z","steps":["trace[789544529] 'process raft request' (duration: 1.603706269s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:15.421478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.487847868s","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-07-21T06:44:15.421513Z","caller":"traceutil/trace.go:171","msg":"trace[639899729] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1184; }","duration":"1.487958151s","start":"2026-07-21T06:44:13.933544Z","end":"2026-07-21T06:44:15.421502Z","steps":["trace[639899729] 'agreement among raft nodes before linearized reading' (duration: 1.487816606s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:15.421541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:44:13.933527Z","time spent":"1.488006883s","remote":"127.0.0.1:37118","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-07-21T06:44:15.421599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:44:13.817348Z","time spent":"1.604154875s","remote":"127.0.0.1:37118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:44:15.440373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.944451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T06:44:15.440394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.501376043s","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-07-21T06:44:15.440515Z","caller":"traceutil/trace.go:171","msg":"trace[874876768] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1185; }","duration":"856.088236ms","start":"2026-07-21T06:44:14.584408Z","end":"2026-07-21T06:44:15.440496Z","steps":["trace[874876768] 'agreement among raft nodes before linearized reading' (duration: 855.92043ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:44:15.440547Z","caller":"traceutil/trace.go:171","msg":"trace[1390122975] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1185; }","duration":"1.501541607s","start":"2026-07-21T06:44:13.93897Z","end":"2026-07-21T06:44:15.440511Z","steps":["trace[1390122975] 'agreement among raft nodes before linearized reading' (duration: 1.501276769s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:15.440563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:44:14.584366Z","time spent":"856.18693ms","remote":"127.0.0.1:36844","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-07-21T06:44:15.440604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:44:13.938958Z","time spent":"1.501630841s","remote":"127.0.0.1:37118","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-07-21T06:44:15.440616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.463513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:44:15.440702Z","caller":"traceutil/trace.go:171","msg":"trace[435658404] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1185; }","duration":"676.583736ms","start":"2026-07-21T06:44:14.7641Z","end":"2026-07-21T06:44:15.440684Z","steps":["trace[435658404] 'agreement among raft nodes before linearized reading' (duration: 676.485253ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:15.440927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.497769361s","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-07-21T06:44:15.440964Z","caller":"traceutil/trace.go:171","msg":"trace[1252594086] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1185; }","duration":"1.497818463s","start":"2026-07-21T06:44:13.943134Z","end":"2026-07-21T06:44:15.440953Z","steps":["trace[1252594086] 'agreement among raft nodes before linearized reading' (duration: 1.497683139s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:15.440991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:44:13.943128Z","time spent":"1.497856774s","remote":"127.0.0.1:37118","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-07-21T06:44:15.441471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:44:14.76408Z","time spent":"676.776853ms","remote":"127.0.0.1:37138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":27,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-21T06:44:15.442217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.502548911s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-21T06:44:15.442268Z","caller":"traceutil/trace.go:171","msg":"trace[1479637584] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1185; }","duration":"1.502621424s","start":"2026-07-21T06:44:13.939636Z","end":"2026-07-21T06:44:15.442257Z","steps":["trace[1479637584] 'agreement among raft nodes before linearized reading' (duration: 1.50132281s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:15.442305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:44:13.939627Z","time spent":"1.502671205s","remote":"127.0.0.1:37118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-21T06:44:16.096953Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221322113242420,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T06:44:16.35491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:44:15.785206Z","time spent":"569.697577ms","remote":"127.0.0.1:40728","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-21T06:44:16.355365Z","caller":"traceutil/trace.go:171","msg":"trace[1732791415] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"909.891415ms","start":"2026-07-21T06:44:15.44545Z","end":"2026-07-21T06:44:16.355341Z","steps":["trace[1732791415] 'process raft request' (duration: 905.598391ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:16.355472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:44:15.445426Z","time spent":"909.987277ms","remote":"127.0.0.1:37118","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-07-21T06:44:16.388013Z","caller":"traceutil/trace.go:171","msg":"trace[1713933373] linearizableReadLoop","detail":"{readStateIndex:1242; appliedIndex:1239; }","duration":"792.391758ms","start":"2026-07-21T06:44:15.595598Z","end":"2026-07-21T06:44:16.38799Z","steps":["trace[1713933373] 'read index received' (duration: 755.465787ms)","trace[1713933373] 'applied index is now lower than readState.Index' (duration: 36.924821ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:44:16.388051Z","caller":"traceutil/trace.go:171","msg":"trace[1425358295] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"941.535337ms","start":"2026-07-21T06:44:15.446471Z","end":"2026-07-21T06:44:16.388006Z","steps":["trace[1425358295] 'process raft request' (duration: 941.252998ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:16.38813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.513972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:44:16.388102Z","caller":"traceutil/trace.go:171","msg":"trace[1946016816] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"941.171935ms","start":"2026-07-21T06:44:15.446887Z","end":"2026-07-21T06:44:16.388059Z","steps":["trace[1946016816] 'process raft request' (duration: 941.02101ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:44:16.388166Z","caller":"traceutil/trace.go:171","msg":"trace[1950552861] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1189; }","duration":"792.566943ms","start":"2026-07-21T06:44:15.595589Z","end":"2026-07-21T06:44:16.388156Z","steps":["trace[1950552861] 'agreement among raft nodes before linearized reading' (duration: 792.491771ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:16.388195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:44:15.595529Z","time spent":"792.658736ms","remote":"127.0.0.1:36844","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-07-21T06:44:16.388281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:44:15.446459Z","time spent":"941.701183ms","remote":"127.0.0.1:37118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:44:16.388314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:44:15.446862Z","time spent":"941.32949ms","remote":"127.0.0.1:37118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:44:19.552999Z","caller":"traceutil/trace.go:171","msg":"trace[1357805946] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"105.267577ms","start":"2026-07-21T06:44:19.447701Z","end":"2026-07-21T06:44:19.552968Z","steps":["trace[1357805946] 'process raft request' (duration: 105.036239ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:44:19.634519Z","caller":"traceutil/trace.go:171","msg":"trace[1558530001] linearizableReadLoop","detail":"{readStateIndex:1252; appliedIndex:1250; }","duration":"169.029717ms","start":"2026-07-21T06:44:19.465468Z","end":"2026-07-21T06:44:19.634498Z","steps":["trace[1558530001] 'read index received' (duration: 87.266541ms)","trace[1558530001] 'applied index is now lower than readState.Index' (duration: 81.761856ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:44:19.634637Z","caller":"traceutil/trace.go:171","msg":"trace[708938838] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"174.4535ms","start":"2026-07-21T06:44:19.460125Z","end":"2026-07-21T06:44:19.634579Z","steps":["trace[708938838] 'process raft request' (duration: 174.251073ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:19.634728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.241245ms","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-07-21T06:44:19.63476Z","caller":"traceutil/trace.go:171","msg":"trace[1942651852] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1198; }","duration":"169.317518ms","start":"2026-07-21T06:44:19.465434Z","end":"2026-07-21T06:44:19.634752Z","steps":["trace[1942651852] 'agreement among raft nodes before linearized reading' (duration: 169.162853ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:44:19.75964Z","caller":"traceutil/trace.go:171","msg":"trace[158830605] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"121.432219ms","start":"2026-07-21T06:44:19.638165Z","end":"2026-07-21T06:44:19.759597Z","steps":["trace[158830605] 'process raft request' (duration: 119.135042ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:44:19.945207Z","caller":"traceutil/trace.go:171","msg":"trace[1122195239] linearizableReadLoop","detail":"{readStateIndex:1254; appliedIndex:1253; }","duration":"130.327228ms","start":"2026-07-21T06:44:19.814855Z","end":"2026-07-21T06:44:19.945182Z","steps":["trace[1122195239] 'read index received' (duration: 117.333091ms)","trace[1122195239] 'applied index is now lower than readState.Index' (duration: 12.993197ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:44:19.945376Z","caller":"traceutil/trace.go:171","msg":"trace[262000754] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"194.276816ms","start":"2026-07-21T06:44:19.751069Z","end":"2026-07-21T06:44:19.945346Z","steps":["trace[262000754] 'process raft request' (duration: 181.188847ms)","trace[262000754] 'compare' (duration: 12.319503ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:44:19.945691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.795603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T06:44:19.946387Z","caller":"traceutil/trace.go:171","msg":"trace[1745681171] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1200; }","duration":"131.545399ms","start":"2026-07-21T06:44:19.814803Z","end":"2026-07-21T06:44:19.946348Z","steps":["trace[1745681171] 'agreement among raft nodes before linearized reading' (duration: 130.469123ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:44:35.381123Z","caller":"traceutil/trace.go:171","msg":"trace[1914400406] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"177.023817ms","start":"2026-07-21T06:44:35.204076Z","end":"2026-07-21T06:44:35.3811Z","steps":["trace[1914400406] 'process raft request' (duration: 176.896482ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:35.569202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.565191ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T06:44:35.56935Z","caller":"traceutil/trace.go:171","msg":"trace[343064099] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"181.351322ms","start":"2026-07-21T06:44:35.387965Z","end":"2026-07-21T06:44:35.569316Z","steps":["trace[343064099] 'process raft request' (duration: 71.113648ms)","trace[343064099] 'compare' (duration: 109.383315ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:44:35.573773Z","caller":"traceutil/trace.go:171","msg":"trace[1432992491] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"183.172023ms","start":"2026-07-21T06:44:35.39058Z","end":"2026-07-21T06:44:35.573752Z","steps":["trace[1432992491] 'process raft request' (duration: 183.100551ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:44:35.574175Z","caller":"traceutil/trace.go:171","msg":"trace[562806747] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"186.132892ms","start":"2026-07-21T06:44:35.388028Z","end":"2026-07-21T06:44:35.574161Z","steps":["trace[562806747] 'process raft request' (duration: 185.550282ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:36.809429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.759757ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T06:44:36.809676Z","caller":"traceutil/trace.go:171","msg":"trace[1045619212] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"1.151760919s","start":"2026-07-21T06:44:35.657882Z","end":"2026-07-21T06:44:36.809643Z","steps":["trace[1045619212] 'process raft request' (duration: 609.685781ms)","trace[1045619212] 'compare' (duration: 541.565721ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:44:36.809722Z","caller":"traceutil/trace.go:171","msg":"trace[170693313] linearizableReadLoop","detail":"{readStateIndex:1328; appliedIndex:1326; }","duration":"953.54545ms","start":"2026-07-21T06:44:35.85616Z","end":"2026-07-21T06:44:36.809705Z","steps":["trace[170693313] 'read index received' (duration: 411.401049ms)","trace[170693313] 'applied index is now lower than readState.Index' (duration: 542.143211ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:44:36.809812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:44:35.65787Z","time spent":"1.151887623s","remote":"127.0.0.1:37118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:44:36.809883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"781.047816ms","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:7"} {"level":"info","ts":"2026-07-21T06:44:36.80994Z","caller":"traceutil/trace.go:171","msg":"trace[1480725878] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1271; }","duration":"781.158689ms","start":"2026-07-21T06:44:36.028767Z","end":"2026-07-21T06:44:36.809926Z","steps":["trace[1480725878] 'agreement among raft nodes before linearized reading' (duration: 781.059786ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:36.809969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:44:36.028747Z","time spent":"781.216022ms","remote":"127.0.0.1:52866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-07-21T06:44:36.809845Z","caller":"traceutil/trace.go:171","msg":"trace[2057425827] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"1.099832924s","start":"2026-07-21T06:44:35.709978Z","end":"2026-07-21T06:44:36.809811Z","steps":["trace[2057425827] 'process raft request' (duration: 1.099578776s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:36.810002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.832559ms","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-07-21T06:44:36.810082Z","caller":"traceutil/trace.go:171","msg":"trace[2134436494] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1271; }","duration":"953.953704ms","start":"2026-07-21T06:44:35.856111Z","end":"2026-07-21T06:44:36.810064Z","steps":["trace[2134436494] 'agreement among raft nodes before linearized reading' (duration: 953.700375ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:36.810158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.539838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-21T06:44:36.810178Z","caller":"traceutil/trace.go:171","msg":"trace[902157117] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1271; }","duration":"192.60149ms","start":"2026-07-21T06:44:36.617571Z","end":"2026-07-21T06:44:36.810172Z","steps":["trace[902157117] 'agreement among raft nodes before linearized reading' (duration: 192.506326ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:36.81021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:44:35.856088Z","time spent":"954.088539ms","remote":"127.0.0.1:37118","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-07-21T06:44:36.810248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.255109ms","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-07-21T06:44:36.810263Z","caller":"traceutil/trace.go:171","msg":"trace[792414849] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1271; }","duration":"206.294179ms","start":"2026-07-21T06:44:36.603965Z","end":"2026-07-21T06:44:36.810259Z","steps":["trace[792414849] 'agreement among raft nodes before linearized reading' (duration: 206.252298ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:36.810315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.989964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:44:36.810332Z","caller":"traceutil/trace.go:171","msg":"trace[631933559] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1271; }","duration":"224.035836ms","start":"2026-07-21T06:44:36.586293Z","end":"2026-07-21T06:44:36.810329Z","steps":["trace[631933559] 'agreement among raft nodes before linearized reading' (duration: 224.011145ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:36.810229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:44:35.709952Z","time spent":"1.100090793s","remote":"127.0.0.1:37118","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-07-21T06:44:36.810552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.570896ms","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-07-21T06:44:36.810617Z","caller":"traceutil/trace.go:171","msg":"trace[825251883] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1271; }","duration":"244.670099ms","start":"2026-07-21T06:44:36.56593Z","end":"2026-07-21T06:44:36.810601Z","steps":["trace[825251883] 'agreement among raft nodes before linearized reading' (duration: 244.517574ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:44:37.382141Z","caller":"traceutil/trace.go:171","msg":"trace[1684272442] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"568.562429ms","start":"2026-07-21T06:44:36.813561Z","end":"2026-07-21T06:44:37.382124Z","steps":["trace[1684272442] 'process raft request' (duration: 564.081679ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:37.382243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:44:36.813548Z","time spent":"568.647611ms","remote":"127.0.0.1:37118","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-07-21T06:44:37.388731Z","caller":"traceutil/trace.go:171","msg":"trace[1491859611] linearizableReadLoop","detail":"{readStateIndex:1332; appliedIndex:1328; }","duration":"448.941831ms","start":"2026-07-21T06:44:36.939776Z","end":"2026-07-21T06:44:37.388718Z","steps":["trace[1491859611] 'read index received' (duration: 437.874659ms)","trace[1491859611] 'applied index is now lower than readState.Index' (duration: 11.066462ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:44:37.388896Z","caller":"traceutil/trace.go:171","msg":"trace[714771416] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"574.773127ms","start":"2026-07-21T06:44:36.814116Z","end":"2026-07-21T06:44:37.388889Z","steps":["trace[714771416] 'process raft request' (duration: 574.500718ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:37.388948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:44:36.814103Z","time spent":"574.810768ms","remote":"127.0.0.1:37118","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-07-21T06:44:37.389051Z","caller":"traceutil/trace.go:171","msg":"trace[1240438460] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"571.982614ms","start":"2026-07-21T06:44:36.817062Z","end":"2026-07-21T06:44:37.389044Z","steps":["trace[1240438460] 'process raft request' (duration: 571.609861ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:37.389114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:44:36.817036Z","time spent":"572.028305ms","remote":"127.0.0.1:37118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:44:37.389215Z","caller":"traceutil/trace.go:171","msg":"trace[443604368] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"572.050356ms","start":"2026-07-21T06:44:36.817158Z","end":"2026-07-21T06:44:37.389208Z","steps":["trace[443604368] 'process raft request' (duration: 571.538489ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:37.389247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:44:36.817153Z","time spent":"572.076627ms","remote":"127.0.0.1:37118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:44:37.389342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.552571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T06:44:37.389364Z","caller":"traceutil/trace.go:171","msg":"trace[1706478602] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1275; }","duration":"449.620733ms","start":"2026-07-21T06:44:36.939736Z","end":"2026-07-21T06:44:37.389356Z","steps":["trace[1706478602] 'agreement among raft nodes before linearized reading' (duration: 449.575411ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:37.389386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:44:36.939712Z","time spent":"449.669015ms","remote":"127.0.0.1:37038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":29,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-07-21T06:44:37.389578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.370034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-21T06:44:37.389608Z","caller":"traceutil/trace.go:171","msg":"trace[1764957819] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:1275; }","duration":"343.430546ms","start":"2026-07-21T06:44:37.046169Z","end":"2026-07-21T06:44:37.3896Z","steps":["trace[1764957819] 'agreement among raft nodes before linearized reading' (duration: 343.313622ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:37.389633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:44:37.046152Z","time spent":"343.474548ms","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.72\" "} {"level":"info","ts":"2026-07-21T06:44:39.225853Z","caller":"traceutil/trace.go:171","msg":"trace[1965331411] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"409.384312ms","start":"2026-07-21T06:44:38.816451Z","end":"2026-07-21T06:44:39.225836Z","steps":["trace[1965331411] 'process raft request' (duration: 409.262248ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:39.225963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:44:38.816421Z","time spent":"409.482195ms","remote":"127.0.0.1:37118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:44:39.485643Z","caller":"traceutil/trace.go:171","msg":"trace[430439831] linearizableReadLoop","detail":"{readStateIndex:1336; appliedIndex:1335; }","duration":"100.191346ms","start":"2026-07-21T06:44:39.385434Z","end":"2026-07-21T06:44:39.485625Z","steps":["trace[430439831] 'read index received' (duration: 99.986979ms)","trace[430439831] 'applied index is now lower than readState.Index' (duration: 203.727µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:44:39.485915Z","caller":"traceutil/trace.go:171","msg":"trace[556817364] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"667.595935ms","start":"2026-07-21T06:44:38.818312Z","end":"2026-07-21T06:44:39.485908Z","steps":["trace[556817364] 'process raft request' (duration: 667.213752ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:39.485984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:44:38.818289Z","time spent":"667.645957ms","remote":"127.0.0.1:37118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:44:39.486236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.801226ms","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-07-21T06:44:39.486256Z","caller":"traceutil/trace.go:171","msg":"trace[383789070] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1278; }","duration":"100.847438ms","start":"2026-07-21T06:44:39.385401Z","end":"2026-07-21T06:44:39.486248Z","steps":["trace[383789070] 'agreement among raft nodes before linearized reading' (duration: 100.807776ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:44:39.646792Z","caller":"traceutil/trace.go:171","msg":"trace[2070937078] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"156.071863ms","start":"2026-07-21T06:44:39.490697Z","end":"2026-07-21T06:44:39.646769Z","steps":["trace[2070937078] 'process raft request' (duration: 155.98963ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:44:39.647047Z","caller":"traceutil/trace.go:171","msg":"trace[1183615611] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"157.090147ms","start":"2026-07-21T06:44:39.489933Z","end":"2026-07-21T06:44:39.647023Z","steps":["trace[1183615611] 'process raft request' (duration: 73.840481ms)","trace[1183615611] 'compare' (duration: 82.793031ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:44:39.647164Z","caller":"traceutil/trace.go:171","msg":"trace[38623155] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"155.652168ms","start":"2026-07-21T06:44:39.491495Z","end":"2026-07-21T06:44:39.647147Z","steps":["trace[38623155] 'process raft request' (duration: 155.240865ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:44:40.496102Z","caller":"traceutil/trace.go:171","msg":"trace[241752678] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"465.044241ms","start":"2026-07-21T06:44:40.03104Z","end":"2026-07-21T06:44:40.496084Z","steps":["trace[241752678] 'process raft request' (duration: 464.930257ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:40.496216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:44:40.03102Z","time spent":"465.133805ms","remote":"127.0.0.1:36950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":892,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T06:44:41.092668Z","caller":"traceutil/trace.go:171","msg":"trace[1605128469] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"780.872181ms","start":"2026-07-21T06:44:40.311773Z","end":"2026-07-21T06:44:41.092646Z","steps":["trace[1605128469] 'process raft request' (duration: 780.670284ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:41.092795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:44:40.311748Z","time spent":"780.979345ms","remote":"127.0.0.1:37118","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-07-21T06:44:41.093046Z","caller":"traceutil/trace.go:171","msg":"trace[2120608805] linearizableReadLoop","detail":"{readStateIndex:1342; appliedIndex:1341; }","duration":"472.056377ms","start":"2026-07-21T06:44:40.620533Z","end":"2026-07-21T06:44:41.092589Z","steps":["trace[2120608805] 'read index received' (duration: 471.939053ms)","trace[2120608805] 'applied index is now lower than readState.Index' (duration: 116.594µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:44:41.093299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.431599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:44:41.093393Z","caller":"traceutil/trace.go:171","msg":"trace[1820321119] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1284; }","duration":"472.866454ms","start":"2026-07-21T06:44:40.620502Z","end":"2026-07-21T06:44:41.093369Z","steps":["trace[1820321119] 'agreement among raft nodes before linearized reading' (duration: 472.330446ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:41.093479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:44:40.620487Z","time spent":"472.978388ms","remote":"127.0.0.1:36844","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-07-21T06:44:41.398124Z","caller":"traceutil/trace.go:171","msg":"trace[249404459] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"164.897088ms","start":"2026-07-21T06:44:41.233206Z","end":"2026-07-21T06:44:41.398103Z","steps":["trace[249404459] 'process raft request' (duration: 164.768344ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:44:46.995082Z","caller":"traceutil/trace.go:171","msg":"trace[132852305] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"368.032092ms","start":"2026-07-21T06:44:46.627026Z","end":"2026-07-21T06:44:46.995058Z","steps":["trace[132852305] 'process raft request' (duration: 367.885297ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:46.995237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:44:46.626999Z","time spent":"368.154507ms","remote":"127.0.0.1:37118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:44:48.027717Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221322113242741,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T06:44:48.459588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:44:47.371496Z","time spent":"1.08808494s","remote":"127.0.0.1:56108","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-21T06:44:48.459908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.180043339s","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-07-21T06:44:48.459995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:44:47.047528Z","time spent":"1.412463696s","remote":"127.0.0.1:36890","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-21T06:44:48.466972Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.050424816s","expected-duration":"1s"} {"level":"info","ts":"2026-07-21T06:44:48.467232Z","caller":"traceutil/trace.go:171","msg":"trace[90126173] linearizableReadLoop","detail":"{readStateIndex:1385; appliedIndex:1384; }","duration":"940.40038ms","start":"2026-07-21T06:44:47.526815Z","end":"2026-07-21T06:44:48.467216Z","steps":["trace[90126173] 'read index received' (duration: 940.336358ms)","trace[90126173] 'applied index is now lower than readState.Index' (duration: 63.232µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:44:48.467312Z","caller":"traceutil/trace.go:171","msg":"trace[704266513] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"1.05084026s","start":"2026-07-21T06:44:47.416455Z","end":"2026-07-21T06:44:48.467295Z","steps":["trace[704266513] 'process raft request' (duration: 1.050645163s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:48.4674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"940.568965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-21T06:44:48.467414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:44:47.416435Z","time spent":"1.050925982s","remote":"127.0.0.1:37118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:44:48.467426Z","caller":"traceutil/trace.go:171","msg":"trace[1255024515] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1325; }","duration":"940.616977ms","start":"2026-07-21T06:44:47.526801Z","end":"2026-07-21T06:44:48.467419Z","steps":["trace[1255024515] 'agreement among raft nodes before linearized reading' (duration: 940.491313ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:48.467448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:44:47.526749Z","time spent":"940.69386ms","remote":"127.0.0.1:37118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-21T06:44:48.763241Z","caller":"traceutil/trace.go:171","msg":"trace[2082849278] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"302.238363ms","start":"2026-07-21T06:44:48.460977Z","end":"2026-07-21T06:44:48.763215Z","steps":["trace[2082849278] 'process raft request' (duration: 214.510076ms)","trace[2082849278] 'compare' (duration: 87.573562ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:44:48.763252Z","caller":"traceutil/trace.go:171","msg":"trace[1771805660] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"292.159674ms","start":"2026-07-21T06:44:48.471072Z","end":"2026-07-21T06:44:48.763232Z","steps":["trace[1771805660] 'process raft request' (duration: 292.103462ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:48.76338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:44:48.460959Z","time spent":"302.350836ms","remote":"127.0.0.1:36890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:44:48.763427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.206395815s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-21T06:44:48.763439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.081577632s","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-07-21T06:44:48.763466Z","caller":"traceutil/trace.go:171","msg":"trace[165212792] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1327; }","duration":"1.206439856s","start":"2026-07-21T06:44:47.55702Z","end":"2026-07-21T06:44:48.763459Z","steps":["trace[165212792] 'agreement among raft nodes before linearized reading' (duration: 1.20625706s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:48.76349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:44:47.556972Z","time spent":"1.206512648s","remote":"127.0.0.1:37118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-21T06:44:48.763482Z","caller":"traceutil/trace.go:171","msg":"trace[1374350540] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1327; }","duration":"1.081643545s","start":"2026-07-21T06:44:47.681826Z","end":"2026-07-21T06:44:48.763469Z","steps":["trace[1374350540] 'agreement among raft nodes before linearized reading' (duration: 1.0815023s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:48.763523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:44:47.681818Z","time spent":"1.081695736s","remote":"127.0.0.1:37118","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-07-21T06:44:48.763249Z","caller":"traceutil/trace.go:171","msg":"trace[1665669998] linearizableReadLoop","detail":"{readStateIndex:1386; appliedIndex:1385; }","duration":"295.954562ms","start":"2026-07-21T06:44:48.467278Z","end":"2026-07-21T06:44:48.763232Z","steps":["trace[1665669998] 'read index received' (duration: 208.194984ms)","trace[1665669998] 'applied index is now lower than readState.Index' (duration: 87.757118ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:44:48.76358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.081922294s","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-07-21T06:44:48.763647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.083694823s","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-07-21T06:44:48.763659Z","caller":"traceutil/trace.go:171","msg":"trace[379858930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1327; }","duration":"1.082045567s","start":"2026-07-21T06:44:47.681596Z","end":"2026-07-21T06:44:48.763642Z","steps":["trace[379858930] 'agreement among raft nodes before linearized reading' (duration: 1.081853791s)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:44:48.763669Z","caller":"traceutil/trace.go:171","msg":"trace[1633059932] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1327; }","duration":"1.083759515s","start":"2026-07-21T06:44:47.679903Z","end":"2026-07-21T06:44:48.763663Z","steps":["trace[1633059932] 'agreement among raft nodes before linearized reading' (duration: 1.083701993s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:48.763689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.630692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-07-21T06:44:48.76369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:44:47.679882Z","time spent":"1.083803607s","remote":"127.0.0.1:37118","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-07-21T06:44:48.763712Z","caller":"traceutil/trace.go:171","msg":"trace[1421117946] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1327; }","duration":"482.675923ms","start":"2026-07-21T06:44:48.281028Z","end":"2026-07-21T06:44:48.763704Z","steps":["trace[1421117946] 'agreement among raft nodes before linearized reading' (duration: 482.618512ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:48.763709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:44:47.681576Z","time spent":"1.08212206s","remote":"127.0.0.1:37118","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-07-21T06:44:48.763624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.292476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T06:44:48.763733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:44:48.281014Z","time spent":"482.713915ms","remote":"127.0.0.1:37118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-21T06:44:48.763754Z","caller":"traceutil/trace.go:171","msg":"trace[1493962317] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1327; }","duration":"180.452152ms","start":"2026-07-21T06:44:48.583294Z","end":"2026-07-21T06:44:48.763746Z","steps":["trace[1493962317] 'agreement among raft nodes before linearized reading' (duration: 180.306727ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:48.763778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.527232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T06:44:48.763867Z","caller":"traceutil/trace.go:171","msg":"trace[95352931] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1327; }","duration":"633.660985ms","start":"2026-07-21T06:44:48.130193Z","end":"2026-07-21T06:44:48.763854Z","steps":["trace[95352931] 'agreement among raft nodes before linearized reading' (duration: 633.49395ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:48.763902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:44:48.130176Z","time spent":"633.719128ms","remote":"127.0.0.1:37168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":17,"response size":29,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-07-21T06:45:44.243958Z","caller":"traceutil/trace.go:171","msg":"trace[1124464348] transaction","detail":"{read_only:false; response_revision:1602; number_of_response:1; }","duration":"135.14194ms","start":"2026-07-21T06:45:44.108561Z","end":"2026-07-21T06:45:44.243703Z","steps":["trace[1124464348] 'process raft request' (duration: 134.988995ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:45:54.37632Z","caller":"traceutil/trace.go:171","msg":"trace[1856768805] transaction","detail":"{read_only:false; response_revision:1648; number_of_response:1; }","duration":"112.44584ms","start":"2026-07-21T06:45:54.263853Z","end":"2026-07-21T06:45:54.376298Z","steps":["trace[1856768805] 'process raft request' (duration: 112.327266ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:45:54.810106Z","caller":"traceutil/trace.go:171","msg":"trace[1574442466] transaction","detail":"{read_only:false; response_revision:1649; number_of_response:1; }","duration":"126.760114ms","start":"2026-07-21T06:45:54.683316Z","end":"2026-07-21T06:45:54.810076Z","steps":["trace[1574442466] 'process raft request' (duration: 126.603089ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:45:54.819694Z","caller":"traceutil/trace.go:171","msg":"trace[500029523] linearizableReadLoop","detail":"{readStateIndex:1723; appliedIndex:1721; }","duration":"102.838373ms","start":"2026-07-21T06:45:54.716842Z","end":"2026-07-21T06:45:54.81968Z","steps":["trace[500029523] 'read index received' (duration: 93.062979ms)","trace[500029523] 'applied index is now lower than readState.Index' (duration: 9.774733ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:45:54.81981Z","caller":"traceutil/trace.go:171","msg":"trace[1930977435] transaction","detail":"{read_only:false; response_revision:1650; number_of_response:1; }","duration":"109.72251ms","start":"2026-07-21T06:45:54.710075Z","end":"2026-07-21T06:45:54.819798Z","steps":["trace[1930977435] 'process raft request' (duration: 109.519084ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:45:54.819882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.002838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-21T06:45:54.81991Z","caller":"traceutil/trace.go:171","msg":"trace[1598785688] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1650; }","duration":"103.092881ms","start":"2026-07-21T06:45:54.716807Z","end":"2026-07-21T06:45:54.8199Z","steps":["trace[1598785688] 'agreement among raft nodes before linearized reading' (duration: 102.927995ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:45:55.289559Z","caller":"traceutil/trace.go:171","msg":"trace[124950457] transaction","detail":"{read_only:false; response_revision:1652; number_of_response:1; }","duration":"129.176703ms","start":"2026-07-21T06:45:55.16033Z","end":"2026-07-21T06:45:55.289507Z","steps":["trace[124950457] 'process raft request' (duration: 103.741532ms)","trace[124950457] 'compare' (duration: 25.071049ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:45:55.446857Z","caller":"traceutil/trace.go:171","msg":"trace[379367517] transaction","detail":"{read_only:false; response_revision:1654; number_of_response:1; }","duration":"152.507736ms","start":"2026-07-21T06:45:55.294324Z","end":"2026-07-21T06:45:55.446832Z","steps":["trace[379367517] 'process raft request' (duration: 152.34281ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:45:55.44716Z","caller":"traceutil/trace.go:171","msg":"trace[723930883] transaction","detail":"{read_only:false; response_revision:1655; number_of_response:1; }","duration":"152.767314ms","start":"2026-07-21T06:45:55.294382Z","end":"2026-07-21T06:45:55.447149Z","steps":["trace[723930883] 'process raft request' (duration: 152.414892ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:45:55.447512Z","caller":"traceutil/trace.go:171","msg":"trace[1672802021] transaction","detail":"{read_only:false; response_revision:1656; number_of_response:1; }","duration":"151.123029ms","start":"2026-07-21T06:45:55.29636Z","end":"2026-07-21T06:45:55.447483Z","steps":["trace[1672802021] 'process raft request' (duration: 150.722716ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:47.383174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.330099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-07-21T06:46:47.383247Z","caller":"traceutil/trace.go:171","msg":"trace[415065253] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1890; }","duration":"105.434452ms","start":"2026-07-21T06:46:47.277792Z","end":"2026-07-21T06:46:47.383226Z","steps":["trace[415065253] 'range keys from in-memory index tree' (duration: 105.163293ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:46:49.928828Z","caller":"traceutil/trace.go:171","msg":"trace[1351008205] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"123.354269ms","start":"2026-07-21T06:46:49.805439Z","end":"2026-07-21T06:46:49.928793Z","steps":["trace[1351008205] 'process raft request' (duration: 123.262636ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:46:49.928846Z","caller":"traceutil/trace.go:171","msg":"trace[2092531184] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"124.847989ms","start":"2026-07-21T06:46:49.80397Z","end":"2026-07-21T06:46:49.928818Z","steps":["trace[2092531184] 'process raft request' (duration: 51.202656ms)","trace[2092531184] 'compare' (duration: 73.349453ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:46:51.879503Z","caller":"traceutil/trace.go:171","msg":"trace[482548694] transaction","detail":"{read_only:false; response_revision:1910; number_of_response:1; }","duration":"124.885151ms","start":"2026-07-21T06:46:51.754597Z","end":"2026-07-21T06:46:51.879482Z","steps":["trace[482548694] 'process raft request' (duration: 124.427725ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:47:44.407104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.880214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-21T06:47:44.407154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.078814ms","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-07-21T06:47:44.40718Z","caller":"traceutil/trace.go:171","msg":"trace[59232191] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2148; }","duration":"114.979288ms","start":"2026-07-21T06:47:44.292183Z","end":"2026-07-21T06:47:44.407162Z","steps":["trace[59232191] 'range keys from in-memory index tree' (duration: 114.765671ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:47:44.407215Z","caller":"traceutil/trace.go:171","msg":"trace[1038705095] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2148; }","duration":"192.193728ms","start":"2026-07-21T06:47:44.215007Z","end":"2026-07-21T06:47:44.407201Z","steps":["trace[1038705095] 'range keys from in-memory index tree' (duration: 191.918319ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:47:44.407085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.113419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-07-21T06:47:44.407257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.398746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-21T06:47:44.407291Z","caller":"traceutil/trace.go:171","msg":"trace[1181324556] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2148; }","duration":"114.389168ms","start":"2026-07-21T06:47:44.292882Z","end":"2026-07-21T06:47:44.407271Z","steps":["trace[1181324556] 'range keys from in-memory index tree' (duration: 113.949294ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:47:44.407333Z","caller":"traceutil/trace.go:171","msg":"trace[1484785874] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2148; }","duration":"179.51209ms","start":"2026-07-21T06:47:44.227801Z","end":"2026-07-21T06:47:44.407313Z","steps":["trace[1484785874] 'range keys from in-memory index tree' (duration: 179.238041ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:47:44.407779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.146166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"warn","ts":"2026-07-21T06:47:44.407785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.761188ms","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-07-21T06:47:44.408602Z","caller":"traceutil/trace.go:171","msg":"trace[1183557601] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2148; }","duration":"210.591146ms","start":"2026-07-21T06:47:44.197967Z","end":"2026-07-21T06:47:44.408558Z","steps":["trace[1183557601] 'range keys from in-memory index tree' (duration: 209.19075ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:47:44.40783Z","caller":"traceutil/trace.go:171","msg":"trace[795332334] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2148; }","duration":"116.25381ms","start":"2026-07-21T06:47:44.291562Z","end":"2026-07-21T06:47:44.407816Z","steps":["trace[795332334] 'range keys from in-memory index tree' (duration: 115.329229ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:47:44.6578Z","caller":"traceutil/trace.go:171","msg":"trace[640895949] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"246.482872ms","start":"2026-07-21T06:47:44.411299Z","end":"2026-07-21T06:47:44.657782Z","steps":["trace[640895949] 'process raft request' (duration: 246.350908ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:47:45.002204Z","caller":"traceutil/trace.go:171","msg":"trace[1350072581] transaction","detail":"{read_only:false; response_revision:2150; number_of_response:1; }","duration":"590.824006ms","start":"2026-07-21T06:47:44.411348Z","end":"2026-07-21T06:47:45.002172Z","steps":["trace[1350072581] 'process raft request' (duration: 590.681091ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:47:45.002215Z","caller":"traceutil/trace.go:171","msg":"trace[269599949] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"588.473418ms","start":"2026-07-21T06:47:44.413721Z","end":"2026-07-21T06:47:45.002194Z","steps":["trace[269599949] 'process raft request' (duration: 588.408346ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:47:45.002375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:47:44.411331Z","time spent":"590.95707ms","remote":"127.0.0.1:37118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:47:45.002491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:47:44.413706Z","time spent":"588.707546ms","remote":"127.0.0.1:37118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:47:45.002543Z","caller":"traceutil/trace.go:171","msg":"trace[1700345475] transaction","detail":"{read_only:false; response_revision:2152; number_of_response:1; }","duration":"588.712176ms","start":"2026-07-21T06:47:44.41381Z","end":"2026-07-21T06:47:45.002522Z","steps":["trace[1700345475] 'process raft request' (duration: 588.350604ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:47:45.002634Z","caller":"traceutil/trace.go:171","msg":"trace[66070813] transaction","detail":"{read_only:false; response_revision:2153; number_of_response:1; }","duration":"587.817066ms","start":"2026-07-21T06:47:44.414802Z","end":"2026-07-21T06:47:45.002619Z","steps":["trace[66070813] 'process raft request' (duration: 587.557388ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:47:45.002726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:47:44.414787Z","time spent":"587.90163ms","remote":"127.0.0.1:37118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:47:45.002654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:47:44.4138Z","time spent":"588.794589ms","remote":"127.0.0.1:37118","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-07-21T06:47:45.002834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.097419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-07-21T06:47:45.002941Z","caller":"traceutil/trace.go:171","msg":"trace[44736907] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2153; }","duration":"276.235414ms","start":"2026-07-21T06:47:44.726683Z","end":"2026-07-21T06:47:45.002919Z","steps":["trace[44736907] 'agreement among raft nodes before linearized reading' (duration: 276.066899ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:47:45.002977Z","caller":"traceutil/trace.go:171","msg":"trace[1361835307] linearizableReadLoop","detail":"{readStateIndex:2248; appliedIndex:2244; }","duration":"275.767899ms","start":"2026-07-21T06:47:44.726751Z","end":"2026-07-21T06:47:45.002519Z","steps":["trace[1361835307] 'read index received' (duration: 275.236321ms)","trace[1361835307] 'applied index is now lower than readState.Index' (duration: 530.748µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:48:13.038751Z","caller":"traceutil/trace.go:171","msg":"trace[706476963] transaction","detail":"{read_only:false; response_revision:2280; number_of_response:1; }","duration":"224.504396ms","start":"2026-07-21T06:48:12.814219Z","end":"2026-07-21T06:48:13.038724Z","steps":["trace[706476963] 'process raft request' (duration: 224.360811ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:48:20.832005Z","caller":"traceutil/trace.go:171","msg":"trace[1621932129] linearizableReadLoop","detail":"{readStateIndex:2417; appliedIndex:2416; }","duration":"339.024549ms","start":"2026-07-21T06:48:20.492958Z","end":"2026-07-21T06:48:20.831983Z","steps":["trace[1621932129] 'read index received' (duration: 335.092709ms)","trace[1621932129] 'applied index is now lower than readState.Index' (duration: 3.93056ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:48:20.832175Z","caller":"traceutil/trace.go:171","msg":"trace[820274364] transaction","detail":"{read_only:false; response_revision:2315; number_of_response:1; }","duration":"339.615838ms","start":"2026-07-21T06:48:20.492518Z","end":"2026-07-21T06:48:20.832134Z","steps":["trace[820274364] 'process raft request' (duration: 335.589145ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:20.832304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:20.492487Z","time spent":"339.746793ms","remote":"127.0.0.1:37118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:48:20.832279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.294858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-21T06:48:20.833203Z","caller":"traceutil/trace.go:171","msg":"trace[1693010339] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2315; }","duration":"340.318702ms","start":"2026-07-21T06:48:20.49287Z","end":"2026-07-21T06:48:20.833188Z","steps":["trace[1693010339] 'agreement among raft nodes before linearized reading' (duration: 339.273378ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:20.833236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:20.492838Z","time spent":"340.386144ms","remote":"127.0.0.1:37118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-21T06:48:35.226084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.484324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-21T06:48:35.226148Z","caller":"traceutil/trace.go:171","msg":"trace[1645208462] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2378; }","duration":"127.590697ms","start":"2026-07-21T06:48:35.098542Z","end":"2026-07-21T06:48:35.226133Z","steps":["trace[1645208462] 'range keys from in-memory index tree' (duration: 127.05039ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:35.62553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.064594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-07-21T06:48:35.625606Z","caller":"traceutil/trace.go:171","msg":"trace[455034084] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2383; }","duration":"114.190148ms","start":"2026-07-21T06:48:35.511398Z","end":"2026-07-21T06:48:35.625588Z","steps":["trace[455034084] 'range keys from in-memory index tree' (duration: 113.875348ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:48:51.879584Z","caller":"traceutil/trace.go:171","msg":"trace[624484430] transaction","detail":"{read_only:false; response_revision:2460; number_of_response:1; }","duration":"102.704301ms","start":"2026-07-21T06:48:51.776848Z","end":"2026-07-21T06:48:51.879552Z","steps":["trace[624484430] 'process raft request' (duration: 94.065948ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:55.214801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.385704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-21T06:48:55.21492Z","caller":"traceutil/trace.go:171","msg":"trace[1247655686] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2469; }","duration":"103.620812ms","start":"2026-07-21T06:48:55.111273Z","end":"2026-07-21T06:48:55.214893Z","steps":["trace[1247655686] 'range keys from in-memory index tree' (duration: 103.178727ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:49:04.03357Z","caller":"traceutil/trace.go:171","msg":"trace[7286944] transaction","detail":"{read_only:false; response_revision:2523; number_of_response:1; }","duration":"156.031661ms","start":"2026-07-21T06:49:03.877506Z","end":"2026-07-21T06:49:04.033537Z","steps":["trace[7286944] 'process raft request' (duration: 155.864936ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:49:04.044657Z","caller":"traceutil/trace.go:171","msg":"trace[1516173549] transaction","detail":"{read_only:false; response_revision:2524; number_of_response:1; }","duration":"107.897322ms","start":"2026-07-21T06:49:03.936743Z","end":"2026-07-21T06:49:04.04464Z","steps":["trace[1516173549] 'process raft request' (duration: 107.614233ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:49:05.904534Z","caller":"traceutil/trace.go:171","msg":"trace[1608027651] linearizableReadLoop","detail":"{readStateIndex:2649; appliedIndex:2648; }","duration":"115.77233ms","start":"2026-07-21T06:49:05.788737Z","end":"2026-07-21T06:49:05.904509Z","steps":["trace[1608027651] 'read index received' (duration: 115.741619ms)","trace[1608027651] 'applied index is now lower than readState.Index' (duration: 29.851µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:49:05.904658Z","caller":"traceutil/trace.go:171","msg":"trace[1468462823] transaction","detail":"{read_only:false; response_revision:2537; number_of_response:1; }","duration":"117.416724ms","start":"2026-07-21T06:49:05.787204Z","end":"2026-07-21T06:49:05.90462Z","steps":["trace[1468462823] 'process raft request' (duration: 117.159356ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:49:05.904788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.024018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-07-21T06:49:05.904835Z","caller":"traceutil/trace.go:171","msg":"trace[1484970086] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2537; }","duration":"116.120692ms","start":"2026-07-21T06:49:05.7887Z","end":"2026-07-21T06:49:05.90482Z","steps":["trace[1484970086] 'agreement among raft nodes before linearized reading' (duration: 115.944766ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:49:05.946714Z","caller":"traceutil/trace.go:171","msg":"trace[838465231] transaction","detail":"{read_only:false; response_revision:2538; number_of_response:1; }","duration":"157.931714ms","start":"2026-07-21T06:49:05.788753Z","end":"2026-07-21T06:49:05.946684Z","steps":["trace[838465231] 'process raft request' (duration: 157.761448ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:49:09.856361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.487482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-21T06:49:09.856435Z","caller":"traceutil/trace.go:171","msg":"trace[1311030737] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2558; }","duration":"151.614426ms","start":"2026-07-21T06:49:09.704807Z","end":"2026-07-21T06:49:09.856421Z","steps":["trace[1311030737] 'range keys from in-memory index tree' (duration: 151.274565ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:49:09.856464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.17182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-21T06:49:09.856563Z","caller":"traceutil/trace.go:171","msg":"trace[1109402384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2558; }","duration":"338.377367ms","start":"2026-07-21T06:49:09.518158Z","end":"2026-07-21T06:49:09.856536Z","steps":["trace[1109402384] 'range keys from in-memory index tree' (duration: 337.917961ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:49:09.856619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:49:09.517666Z","time spent":"338.941665ms","remote":"127.0.0.1:37118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-21T06:49:15.474144Z","caller":"traceutil/trace.go:171","msg":"trace[1555371471] transaction","detail":"{read_only:false; response_revision:2588; number_of_response:1; }","duration":"200.292193ms","start":"2026-07-21T06:49:15.27382Z","end":"2026-07-21T06:49:15.474112Z","steps":["trace[1555371471] 'process raft request' (duration: 200.113387ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:49:16.062655Z","caller":"traceutil/trace.go:171","msg":"trace[297365597] transaction","detail":"{read_only:false; response_revision:2591; number_of_response:1; }","duration":"165.923716ms","start":"2026-07-21T06:49:15.896708Z","end":"2026-07-21T06:49:16.062632Z","steps":["trace[297365597] 'process raft request' (duration: 165.44487ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:49:16.241904Z","caller":"traceutil/trace.go:171","msg":"trace[1167341748] linearizableReadLoop","detail":"{readStateIndex:2706; appliedIndex:2704; }","duration":"293.70935ms","start":"2026-07-21T06:49:15.948168Z","end":"2026-07-21T06:49:16.241877Z","steps":["trace[1167341748] 'read index received' (duration: 114.179818ms)","trace[1167341748] 'applied index is now lower than readState.Index' (duration: 179.528352ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:49:16.24197Z","caller":"traceutil/trace.go:171","msg":"trace[70409333] transaction","detail":"{read_only:false; response_revision:2592; number_of_response:1; }","duration":"342.657867ms","start":"2026-07-21T06:49:15.899281Z","end":"2026-07-21T06:49:16.241939Z","steps":["trace[70409333] 'process raft request' (duration: 342.402739ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:49:16.242101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.914497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-21T06:49:16.242116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:49:15.899264Z","time spent":"342.76955ms","remote":"127.0.0.1:37118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:49:16.242165Z","caller":"traceutil/trace.go:171","msg":"trace[1402836375] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2592; }","duration":"294.023241ms","start":"2026-07-21T06:49:15.94812Z","end":"2026-07-21T06:49:16.242143Z","steps":["trace[1402836375] 'agreement among raft nodes before linearized reading' (duration: 293.857645ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:49:16.297742Z","caller":"traceutil/trace.go:171","msg":"trace[2007235889] transaction","detail":"{read_only:false; response_revision:2593; number_of_response:1; }","duration":"196.51604ms","start":"2026-07-21T06:49:16.101199Z","end":"2026-07-21T06:49:16.297715Z","steps":["trace[2007235889] 'process raft request' (duration: 196.381356ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:49:16.298127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.328303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-07-21T06:49:16.298235Z","caller":"traceutil/trace.go:171","msg":"trace[694968374] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2593; }","duration":"309.460547ms","start":"2026-07-21T06:49:15.988736Z","end":"2026-07-21T06:49:16.298197Z","steps":["trace[694968374] 'agreement among raft nodes before linearized reading' (duration: 309.166877ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:49:16.298333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.001238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-07-21T06:49:16.298341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:49:15.988722Z","time spent":"309.602672ms","remote":"127.0.0.1:37118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-21T06:49:16.298391Z","caller":"traceutil/trace.go:171","msg":"trace[99414488] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2593; }","duration":"311.145863ms","start":"2026-07-21T06:49:15.987229Z","end":"2026-07-21T06:49:16.298375Z","steps":["trace[99414488] 'agreement among raft nodes before linearized reading' (duration: 310.855763ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:49:16.298442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:49:15.987218Z","time spent":"311.207285ms","remote":"127.0.0.1:37118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-21T06:49:16.298591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.626171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-07-21T06:49:16.299002Z","caller":"traceutil/trace.go:171","msg":"trace[1726759100] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2593; }","duration":"320.027914ms","start":"2026-07-21T06:49:15.978905Z","end":"2026-07-21T06:49:16.298933Z","steps":["trace[1726759100] 'agreement among raft nodes before linearized reading' (duration: 318.897107ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:49:16.299174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:49:15.978884Z","time spent":"320.234691ms","remote":"127.0.0.1:37118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-21T06:49:16.490385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.488156ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T06:49:16.490658Z","caller":"traceutil/trace.go:171","msg":"trace[11801571] transaction","detail":"{read_only:false; response_revision:2594; number_of_response:1; }","duration":"227.807367ms","start":"2026-07-21T06:49:16.262796Z","end":"2026-07-21T06:49:16.490603Z","steps":["trace[11801571] 'process raft request' (duration: 102.240216ms)","trace[11801571] 'compare' (duration: 124.360662ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:49:16.490785Z","caller":"traceutil/trace.go:171","msg":"trace[1024986265] transaction","detail":"{read_only:false; response_revision:2597; number_of_response:1; }","duration":"186.798411ms","start":"2026-07-21T06:49:16.303971Z","end":"2026-07-21T06:49:16.490769Z","steps":["trace[1024986265] 'process raft request' (duration: 186.638906ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:49:16.490735Z","caller":"traceutil/trace.go:171","msg":"trace[1857308426] transaction","detail":"{read_only:false; response_revision:2595; number_of_response:1; }","duration":"187.66324ms","start":"2026-07-21T06:49:16.303038Z","end":"2026-07-21T06:49:16.490702Z","steps":["trace[1857308426] 'process raft request' (duration: 187.464933ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:49:16.491174Z","caller":"traceutil/trace.go:171","msg":"trace[1158604611] transaction","detail":"{read_only:false; response_revision:2596; number_of_response:1; }","duration":"187.408091ms","start":"2026-07-21T06:49:16.303503Z","end":"2026-07-21T06:49:16.490911Z","steps":["trace[1158604611] 'process raft request' (duration: 187.06571ms)"],"step_count":1}