{"level":"warn","ts":"2026-03-06T21:17:14.125758Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-06T21:17:14.125854Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.53: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.53:2380","--initial-cluster=instance=https://199.204.45.53:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.53:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.53:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-06T21:17:14.125918Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-06T21:17:14.125931Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-03-06T21:17:14.125946Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-06T21:17:14.126691Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"]} {"level":"info","ts":"2026-03-06T21:17:14.126767Z","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.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.53:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-06T21:17:14.129831Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.827598ms"} {"level":"info","ts":"2026-03-06T21:17:14.136948Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c47ca1a7b1fe506b","cluster-id":"95bd21e158fabf07"} {"level":"info","ts":"2026-03-06T21:17:14.137032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=()"} {"level":"info","ts":"2026-03-06T21:17:14.137057Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 0"} {"level":"info","ts":"2026-03-06T21:17:14.137071Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c47ca1a7b1fe506b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-06T21:17:14.137084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 1"} {"level":"info","ts":"2026-03-06T21:17:14.137123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"warn","ts":"2026-03-06T21:17:14.142559Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-06T21:17:14.144498Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-06T21:17:14.148815Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-06T21:17:14.151268Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c47ca1a7b1fe506b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-06T21:17:14.152488Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c47ca1a7b1fe506b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-06T21:17:14.153223Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-06T21:17:14.153281Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-06T21:17:14.153298Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-06T21:17:14.153659Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"info","ts":"2026-03-06T21:17:14.153953Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","added-peer-id":"c47ca1a7b1fe506b","added-peer-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-03-06T21:17:14.155635Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-06T21:17:14.155751Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-03-06T21:17:14.155816Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-03-06T21:17:14.156085Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c47ca1a7b1fe506b","initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-06T21:17:14.156162Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-06T21:17:14.237859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b is starting a new election at term 1"} {"level":"info","ts":"2026-03-06T21:17:14.237927Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became pre-candidate at term 1"} {"level":"info","ts":"2026-03-06T21:17:14.237961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgPreVoteResp from c47ca1a7b1fe506b at term 1"} {"level":"info","ts":"2026-03-06T21:17:14.237986Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became candidate at term 2"} {"level":"info","ts":"2026-03-06T21:17:14.238002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgVoteResp from c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-03-06T21:17:14.238022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became leader at term 2"} {"level":"info","ts":"2026-03-06T21:17:14.238041Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c47ca1a7b1fe506b elected leader c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-03-06T21:17:14.23885Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-06T21:17:14.239525Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c47ca1a7b1fe506b","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.53:2379]}","request-path":"/0/members/c47ca1a7b1fe506b/attributes","cluster-id":"95bd21e158fabf07","publish-timeout":"7s"} {"level":"info","ts":"2026-03-06T21:17:14.239644Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-06T21:17:14.239688Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-06T21:17:14.239831Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-06T21:17:14.239859Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-06T21:17:14.241818Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-06T21:17:14.242585Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.53:2379"} {"level":"info","ts":"2026-03-06T21:17:14.245946Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","cluster-version":"3.5"} {"level":"info","ts":"2026-03-06T21:17:14.246021Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-06T21:17:14.246042Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-06T21:17:16.29344Z","caller":"traceutil/trace.go:171","msg":"trace[1144156167] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"294.494455ms","start":"2026-03-06T21:17:15.998912Z","end":"2026-03-06T21:17:16.293406Z","steps":["trace[1144156167] 'process raft request' (duration: 294.208428ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:17:16.888383Z","caller":"traceutil/trace.go:171","msg":"trace[304033602] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"887.677363ms","start":"2026-03-06T21:17:16.000684Z","end":"2026-03-06T21:17:16.888362Z","steps":["trace[304033602] 'read index received' (duration: 292.592314ms)","trace[304033602] 'applied index is now lower than readState.Index' (duration: 595.084189ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T21:17:16.888487Z","caller":"traceutil/trace.go:171","msg":"trace[398054965] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"816.993772ms","start":"2026-03-06T21:17:16.071479Z","end":"2026-03-06T21:17:16.888472Z","steps":["trace[398054965] 'process raft request' (duration: 816.616812ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:17:16.888504Z","caller":"traceutil/trace.go:171","msg":"trace[533551838] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"889.438931ms","start":"2026-03-06T21:17:15.999037Z","end":"2026-03-06T21:17:16.888476Z","steps":["trace[533551838] 'process raft request' (duration: 888.892537ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:17:16.888763Z","caller":"traceutil/trace.go:171","msg":"trace[35146547] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"815.865892ms","start":"2026-03-06T21:17:16.07288Z","end":"2026-03-06T21:17:16.888746Z","steps":["trace[35146547] 'process raft request' (duration: 815.250215ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:17:16.889904Z","caller":"traceutil/trace.go:171","msg":"trace[1711770304] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"801.954439ms","start":"2026-03-06T21:17:16.087869Z","end":"2026-03-06T21:17:16.889824Z","steps":["trace[1711770304] 'process raft request' (duration: 800.438208ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:17:16.890373Z","caller":"traceutil/trace.go:171","msg":"trace[6463213] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"817.073395ms","start":"2026-03-06T21:17:16.072981Z","end":"2026-03-06T21:17:16.890054Z","steps":["trace[6463213] 'process raft request' (duration: 815.189613ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:17:16.890482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T21:17:16.071456Z","time spent":"817.080225ms","remote":"127.0.0.1:43726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-06T21:17:16.890767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T21:17:16.072967Z","time spent":"817.524828ms","remote":"127.0.0.1:43798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-06T21:17:16.89069Z","caller":"traceutil/trace.go:171","msg":"trace[2100732396] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"817.076055ms","start":"2026-03-06T21:17:16.073087Z","end":"2026-03-06T21:17:16.890163Z","steps":["trace[2100732396] 'process raft request' (duration: 815.104141ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:17:16.890991Z","caller":"traceutil/trace.go:171","msg":"trace[1569620274] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"817.546127ms","start":"2026-03-06T21:17:16.073108Z","end":"2026-03-06T21:17:16.890654Z","steps":["trace[1569620274] 'process raft request' (duration: 815.10138ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:17:16.891268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T21:17:16.0731Z","time spent":"818.070351ms","remote":"127.0.0.1:43798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-06T21:17:16.891289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T21:17:16.073072Z","time spent":"817.944498ms","remote":"127.0.0.1:43798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-06T21:17:16.89169Z","caller":"traceutil/trace.go:171","msg":"trace[424978077] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"817.461625ms","start":"2026-03-06T21:17:16.074133Z","end":"2026-03-06T21:17:16.891594Z","steps":["trace[424978077] 'process raft request' (duration: 814.100413ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:17:16.891766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T21:17:16.074121Z","time spent":"817.61224ms","remote":"127.0.0.1:43798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-06T21:17:16.892159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T21:17:16.072865Z","time spent":"815.929513ms","remote":"127.0.0.1:43798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-06T21:17:16.892657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T21:17:15.99903Z","time spent":"889.556934ms","remote":"127.0.0.1:45932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T21:17:16.893372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.557027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-06T21:17:16.893558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.671174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-06T21:17:16.893561Z","caller":"traceutil/trace.go:171","msg":"trace[382999130] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"810.791603ms","start":"2026-03-06T21:17:16.082749Z","end":"2026-03-06T21:17:16.893541Z","steps":["trace[382999130] 'process raft request' (duration: 805.528858ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:17:16.893582Z","caller":"traceutil/trace.go:171","msg":"trace[614261264] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:11; }","duration":"892.715911ms","start":"2026-03-06T21:17:16.000742Z","end":"2026-03-06T21:17:16.893458Z","steps":["trace[614261264] 'agreement among raft nodes before linearized reading' (duration: 891.402616ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:17:16.893592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.413202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-06T21:17:16.89363Z","caller":"traceutil/trace.go:171","msg":"trace[813762620] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"820.758797ms","start":"2026-03-06T21:17:16.072843Z","end":"2026-03-06T21:17:16.893602Z","steps":["trace[813762620] 'agreement among raft nodes before linearized reading' (duration: 820.649373ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:17:16.893652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T21:17:16.082729Z","time spent":"810.887796ms","remote":"127.0.0.1:43506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":845,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-06T21:17:16.893675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T21:17:16.072832Z","time spent":"820.832598ms","remote":"127.0.0.1:45984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-03-06T21:17:16.893675Z","caller":"traceutil/trace.go:171","msg":"trace[790951502] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"767.507645ms","start":"2026-03-06T21:17:16.126127Z","end":"2026-03-06T21:17:16.893634Z","steps":["trace[790951502] 'agreement among raft nodes before linearized reading' (duration: 767.409482ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:17:16.893716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T21:17:16.126102Z","time spent":"767.605706ms","remote":"127.0.0.1:45984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-03-06T21:17:16.893818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T21:17:16.000734Z","time spent":"892.964719ms","remote":"127.0.0.1:43518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-06T21:17:16.893872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.774033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-06T21:17:16.893919Z","caller":"traceutil/trace.go:171","msg":"trace[1603001598] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"841.838344ms","start":"2026-03-06T21:17:16.052056Z","end":"2026-03-06T21:17:16.893894Z","steps":["trace[1603001598] 'agreement among raft nodes before linearized reading' (duration: 841.784063ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:17:16.89394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.897432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-06T21:17:16.893958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T21:17:16.052034Z","time spent":"841.916326ms","remote":"127.0.0.1:45984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-03-06T21:17:16.893989Z","caller":"traceutil/trace.go:171","msg":"trace[1478801349] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"819.965544ms","start":"2026-03-06T21:17:16.074001Z","end":"2026-03-06T21:17:16.893966Z","steps":["trace[1478801349] 'agreement among raft nodes before linearized reading' (duration: 819.889972ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:17:16.894038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T21:17:16.07399Z","time spent":"820.034356ms","remote":"127.0.0.1:45960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-03-06T21:17:16.894398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.449477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.53\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-06T21:17:16.89446Z","caller":"traceutil/trace.go:171","msg":"trace[262931976] range","detail":"{range_begin:/registry/masterleases/199.204.45.53; range_end:; response_count:0; response_revision:11; }","duration":"824.53481ms","start":"2026-03-06T21:17:16.069901Z","end":"2026-03-06T21:17:16.894436Z","steps":["trace[262931976] 'agreement among raft nodes before linearized reading' (duration: 824.244231ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:17:16.894498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T21:17:16.069875Z","time spent":"824.615992ms","remote":"127.0.0.1:45908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/199.204.45.53\" "} {"level":"warn","ts":"2026-03-06T21:17:16.89929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.267135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-06T21:17:16.899365Z","caller":"traceutil/trace.go:171","msg":"trace[1091052436] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"891.20111ms","start":"2026-03-06T21:17:16.008135Z","end":"2026-03-06T21:17:16.899337Z","steps":["trace[1091052436] 'agreement among raft nodes before linearized reading' (duration: 886.280345ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:17:16.899903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T21:17:16.008115Z","time spent":"891.755556ms","remote":"127.0.0.1:43670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-03-06T21:17:16.89982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"899.125857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-06T21:17:16.900131Z","caller":"traceutil/trace.go:171","msg":"trace[898111614] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:11; }","duration":"899.406925ms","start":"2026-03-06T21:17:16.000672Z","end":"2026-03-06T21:17:16.900078Z","steps":["trace[898111614] 'agreement among raft nodes before linearized reading' (duration: 889.200084ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:17:16.900231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T21:17:16.000666Z","time spent":"899.502107ms","remote":"127.0.0.1:43518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-06T21:17:16.901137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T21:17:16.087857Z","time spent":"811.549443ms","remote":"127.0.0.1:43408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-06T21:17:52.101399Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794897715515104572,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-06T21:17:52.314778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"998.979636ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T21:17:52.31496Z","caller":"traceutil/trace.go:171","msg":"trace[826037849] linearizableReadLoop","detail":"{readStateIndex:508; appliedIndex:506; }","duration":"713.935473ms","start":"2026-03-06T21:17:51.601009Z","end":"2026-03-06T21:17:52.314944Z","steps":["trace[826037849] 'read index received' (duration: 640.070004ms)","trace[826037849] 'applied index is now lower than readState.Index' (duration: 73.864679ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T21:17:52.315041Z","caller":"traceutil/trace.go:171","msg":"trace[944684374] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"1.114491476s","start":"2026-03-06T21:17:51.20054Z","end":"2026-03-06T21:17:52.315031Z","steps":["trace[944684374] 'process raft request' (duration: 1.114330662s)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:17:52.31504Z","caller":"traceutil/trace.go:171","msg":"trace[1180473822] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"1.11505073s","start":"2026-03-06T21:17:51.199949Z","end":"2026-03-06T21:17:52.315Z","steps":["trace[1180473822] 'process raft request' (duration: 115.488559ms)","trace[1180473822] 'compare' (duration: 998.656698ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T21:17:52.315095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T21:17:51.200527Z","time spent":"1.114538537s","remote":"127.0.0.1:43518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T21:17:52.315115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.174739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T21:17:52.315149Z","caller":"traceutil/trace.go:171","msg":"trace[1287969015] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:492; }","duration":"133.24266ms","start":"2026-03-06T21:17:52.181896Z","end":"2026-03-06T21:17:52.315139Z","steps":["trace[1287969015] 'agreement among raft nodes before linearized reading' (duration: 133.185299ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:17:52.315178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T21:17:51.199928Z","time spent":"1.115178444s","remote":"127.0.0.1:43518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T21:17:52.315258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.25749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-q7d6v\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-03-06T21:17:52.315295Z","caller":"traceutil/trace.go:171","msg":"trace[1314616825] range","detail":"{range_begin:/registry/pods/kube-system/cilium-q7d6v; range_end:; response_count:1; response_revision:492; }","duration":"714.341412ms","start":"2026-03-06T21:17:51.600942Z","end":"2026-03-06T21:17:52.315283Z","steps":["trace[1314616825] 'agreement among raft nodes before linearized reading' (duration: 714.133337ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:17:52.315314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T21:17:51.600917Z","time spent":"714.392534ms","remote":"127.0.0.1:43420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-q7d6v\" "} {"level":"warn","ts":"2026-03-06T21:18:11.298502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.477728ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T21:18:11.298636Z","caller":"traceutil/trace.go:171","msg":"trace[851760990] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"410.733018ms","start":"2026-03-06T21:18:10.887885Z","end":"2026-03-06T21:18:11.298618Z","steps":["trace[851760990] 'process raft request' (duration: 44.062404ms)","trace[851760990] 'compare' (duration: 366.366396ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T21:18:11.298748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T21:18:10.887861Z","time spent":"410.856281ms","remote":"127.0.0.1:43756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14465,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T21:18:11.299826Z","caller":"traceutil/trace.go:171","msg":"trace[230443429] linearizableReadLoop","detail":"{readStateIndex:699; appliedIndex:698; }","duration":"119.531794ms","start":"2026-03-06T21:18:11.180262Z","end":"2026-03-06T21:18:11.299794Z","steps":["trace[230443429] 'read index received' (duration: 119.306089ms)","trace[230443429] 'applied index is now lower than readState.Index' (duration: 224.695µs)"],"step_count":2} {"level":"warn","ts":"2026-03-06T21:18:11.299967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.687489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T21:18:11.299963Z","caller":"traceutil/trace.go:171","msg":"trace[634114678] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"397.875134ms","start":"2026-03-06T21:18:10.902039Z","end":"2026-03-06T21:18:11.299914Z","steps":["trace[634114678] 'process raft request' (duration: 397.638808ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:18:11.300048Z","caller":"traceutil/trace.go:171","msg":"trace[1493402184] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:678; }","duration":"119.799032ms","start":"2026-03-06T21:18:11.180229Z","end":"2026-03-06T21:18:11.300028Z","steps":["trace[1493402184] 'agreement among raft nodes before linearized reading' (duration: 119.670028ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:18:11.300245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T21:18:10.902003Z","time spent":"398.10518ms","remote":"127.0.0.1:43408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4793,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T21:18:11.844581Z","caller":"traceutil/trace.go:171","msg":"trace[296341844] linearizableReadLoop","detail":"{readStateIndex:700; appliedIndex:699; }","duration":"253.388041ms","start":"2026-03-06T21:18:11.591154Z","end":"2026-03-06T21:18:11.844542Z","steps":["trace[296341844] 'read index received' (duration: 167.282465ms)","trace[296341844] 'applied index is now lower than readState.Index' (duration: 86.103876ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T21:18:11.844685Z","caller":"traceutil/trace.go:171","msg":"trace[1689692746] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"539.331409ms","start":"2026-03-06T21:18:11.305319Z","end":"2026-03-06T21:18:11.84465Z","steps":["trace[1689692746] 'process raft request' (duration: 453.119639ms)","trace[1689692746] 'compare' (duration: 85.965033ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T21:18:11.844848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T21:18:11.305288Z","time spent":"539.463171ms","remote":"127.0.0.1:43518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T21:18:11.844859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.677509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-06T21:18:11.844907Z","caller":"traceutil/trace.go:171","msg":"trace[504235054] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:679; }","duration":"253.797442ms","start":"2026-03-06T21:18:11.591097Z","end":"2026-03-06T21:18:11.844894Z","steps":["trace[504235054] 'agreement among raft nodes before linearized reading' (duration: 253.582486ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:18:36.861106Z","caller":"traceutil/trace.go:171","msg":"trace[1033274217] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"198.721722ms","start":"2026-03-06T21:18:36.662373Z","end":"2026-03-06T21:18:36.861094Z","steps":["trace[1033274217] 'process raft request' (duration: 198.496485ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:18:36.86103Z","caller":"traceutil/trace.go:171","msg":"trace[1920282129] linearizableReadLoop","detail":"{readStateIndex:780; appliedIndex:779; }","duration":"192.980131ms","start":"2026-03-06T21:18:36.668025Z","end":"2026-03-06T21:18:36.861005Z","steps":["trace[1920282129] 'read index received' (duration: 192.736475ms)","trace[1920282129] 'applied index is now lower than readState.Index' (duration: 242.586µs)"],"step_count":2} {"level":"warn","ts":"2026-03-06T21:18:36.862262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.238574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-06T21:18:36.86238Z","caller":"traceutil/trace.go:171","msg":"trace[1237641579] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:753; }","duration":"172.394848ms","start":"2026-03-06T21:18:36.689971Z","end":"2026-03-06T21:18:36.862366Z","steps":["trace[1237641579] 'agreement among raft nodes before linearized reading' (duration: 172.198973ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:18:36.862277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.602557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-06T21:18:36.862497Z","caller":"traceutil/trace.go:171","msg":"trace[742962683] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:753; }","duration":"194.508321ms","start":"2026-03-06T21:18:36.66797Z","end":"2026-03-06T21:18:36.862479Z","steps":["trace[742962683] 'agreement among raft nodes before linearized reading' (duration: 193.455414ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:18:37.000832Z","caller":"traceutil/trace.go:171","msg":"trace[28366254] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"134.210196ms","start":"2026-03-06T21:18:36.866598Z","end":"2026-03-06T21:18:37.000808Z","steps":["trace[28366254] 'process raft request' (duration: 134.044661ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:18:47.171763Z","caller":"traceutil/trace.go:171","msg":"trace[287785272] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"124.986078ms","start":"2026-03-06T21:18:47.046753Z","end":"2026-03-06T21:18:47.171739Z","steps":["trace[287785272] 'process raft request' (duration: 124.836554ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:19:05.587542Z","caller":"traceutil/trace.go:171","msg":"trace[788893851] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"234.499822ms","start":"2026-03-06T21:19:05.353023Z","end":"2026-03-06T21:19:05.587523Z","steps":["trace[788893851] 'process raft request' (duration: 234.339858ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:19:06.775406Z","caller":"traceutil/trace.go:171","msg":"trace[530723749] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"287.685293ms","start":"2026-03-06T21:19:06.487696Z","end":"2026-03-06T21:19:06.775381Z","steps":["trace[530723749] 'process raft request' (duration: 287.495509ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:19:06.775383Z","caller":"traceutil/trace.go:171","msg":"trace[613774754] linearizableReadLoop","detail":"{readStateIndex:951; appliedIndex:950; }","duration":"212.564772ms","start":"2026-03-06T21:19:06.562784Z","end":"2026-03-06T21:19:06.775349Z","steps":["trace[613774754] 'read index received' (duration: 212.400848ms)","trace[613774754] 'applied index is now lower than readState.Index' (duration: 162.724µs)"],"step_count":2} {"level":"warn","ts":"2026-03-06T21:19:06.775589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.788228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T21:19:06.775664Z","caller":"traceutil/trace.go:171","msg":"trace[1865795657] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:916; }","duration":"212.897701ms","start":"2026-03-06T21:19:06.562749Z","end":"2026-03-06T21:19:06.775647Z","steps":["trace[1865795657] 'agreement among raft nodes before linearized reading' (duration: 212.694546ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:19:07.332751Z","caller":"traceutil/trace.go:171","msg":"trace[1198538824] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"482.050484ms","start":"2026-03-06T21:19:06.850678Z","end":"2026-03-06T21:19:07.332729Z","steps":["trace[1198538824] 'process raft request' (duration: 481.859308ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:19:07.332888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T21:19:06.850442Z","time spent":"482.380442ms","remote":"127.0.0.1:45950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":868,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-06T21:19:07.369743Z","caller":"traceutil/trace.go:171","msg":"trace[2121525857] linearizableReadLoop","detail":"{readStateIndex:955; appliedIndex:952; }","duration":"347.447676ms","start":"2026-03-06T21:19:07.022267Z","end":"2026-03-06T21:19:07.369714Z","steps":["trace[2121525857] 'read index received' (duration: 310.366043ms)","trace[2121525857] 'applied index is now lower than readState.Index' (duration: 37.076623ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T21:19:07.36984Z","caller":"traceutil/trace.go:171","msg":"trace[1340470805] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"515.466842ms","start":"2026-03-06T21:19:06.854331Z","end":"2026-03-06T21:19:07.369798Z","steps":["trace[1340470805] 'process raft request' (duration: 515.302087ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:19:07.370072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.781616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-03-06T21:19:07.370326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.879049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-06T21:19:07.370357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.85961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-03-06T21:19:07.370388Z","caller":"traceutil/trace.go:171","msg":"trace[1640388349] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:920; }","duration":"190.994161ms","start":"2026-03-06T21:19:07.179375Z","end":"2026-03-06T21:19:07.37037Z","steps":["trace[1640388349] 'agreement among raft nodes before linearized reading' (duration: 190.874168ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:19:07.370421Z","caller":"traceutil/trace.go:171","msg":"trace[47766644] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:920; }","duration":"348.145015ms","start":"2026-03-06T21:19:07.022217Z","end":"2026-03-06T21:19:07.370362Z","steps":["trace[47766644] 'agreement among raft nodes before linearized reading' (duration: 347.655533ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:19:07.370431Z","caller":"traceutil/trace.go:171","msg":"trace[1947802174] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:920; }","duration":"314.947931ms","start":"2026-03-06T21:19:07.055462Z","end":"2026-03-06T21:19:07.37041Z","steps":["trace[1947802174] 'agreement among raft nodes before linearized reading' (duration: 314.778817ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:19:07.370501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T21:19:07.055447Z","time spent":"315.040815ms","remote":"127.0.0.1:43518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-06T21:19:07.370586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T21:19:07.022198Z","time spent":"348.31132ms","remote":"127.0.0.1:43518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-06T21:19:07.370685Z","caller":"traceutil/trace.go:171","msg":"trace[792212945] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"519.405064ms","start":"2026-03-06T21:19:06.850757Z","end":"2026-03-06T21:19:07.370162Z","steps":["trace[792212945] 'process raft request' (duration: 518.679536ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T21:19:07.370812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T21:19:06.850749Z","time spent":"520.00661ms","remote":"127.0.0.1:45950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":779,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-06T21:19:07.371291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T21:19:06.854322Z","time spent":"515.895954ms","remote":"127.0.0.1:43518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T21:19:07.483834Z","caller":"traceutil/trace.go:171","msg":"trace[1089927186] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"108.512989ms","start":"2026-03-06T21:19:07.375291Z","end":"2026-03-06T21:19:07.483804Z","steps":["trace[1089927186] 'process raft request' (duration: 74.522337ms)","trace[1089927186] 'compare' (duration: 33.835728ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T21:19:07.921251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.890147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.53\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-06T21:19:07.921308Z","caller":"traceutil/trace.go:171","msg":"trace[1457238550] range","detail":"{range_begin:/registry/masterleases/199.204.45.53; range_end:; response_count:1; response_revision:924; }","duration":"198.998599ms","start":"2026-03-06T21:19:07.722297Z","end":"2026-03-06T21:19:07.921295Z","steps":["trace[1457238550] 'range keys from in-memory index tree' (duration: 198.708953ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T21:19:08.28248Z","caller":"traceutil/trace.go:171","msg":"trace[1747342555] linearizableReadLoop","detail":"{readStateIndex:960; appliedIndex:959; }","duration":"185.416657ms","start":"2026-03-06T21:19:08.097034Z","end":"2026-03-06T21:19:08.282451Z","steps":["trace[1747342555] 'read index received' (duration: 185.260463ms)","trace[1747342555] 'applied index is now lower than readState.Index' (duration: 152.264µs)"],"step_count":2} {"level":"warn","ts":"2026-03-06T21:19:08.282503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T21:19:07.921996Z","time spent":"360.498915ms","remote":"127.0.0.1:45908","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-06T21:19:08.282713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.665033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-06T21:19:08.282766Z","caller":"traceutil/trace.go:171","msg":"trace[421794480] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:924; }","duration":"185.767157ms","start":"2026-03-06T21:19:08.096987Z","end":"2026-03-06T21:19:08.282754Z","steps":["trace[421794480] 'agreement among raft nodes before linearized reading' (duration: 185.591371ms)"],"step_count":1}