{"level":"warn","ts":"2026-03-11T19:25:30.273836Z","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-11T19:25:30.273932Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.52: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://162.253.55.52:2380","--initial-cluster=instance=https://162.253.55.52:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.52:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.52: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-11T19:25:30.273996Z","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-11T19:25:30.274013Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.52:2380"]}
{"level":"info","ts":"2026-03-11T19:25:30.274033Z","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-11T19:25:30.274652Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"]}
{"level":"info","ts":"2026-03-11T19:25:30.274718Z","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://162.253.55.52:2380"],"listen-peer-urls":["https://162.253.55.52:2380"],"advertise-client-urls":["https://162.253.55.52:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.52: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-11T19:25:30.616288Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"341.363914ms"}
{"level":"info","ts":"2026-03-11T19:25:30.650431Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b11e7fc5714be64b","cluster-id":"9349e4f32a52a90a"}
{"level":"info","ts":"2026-03-11T19:25:30.650531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=()"}
{"level":"info","ts":"2026-03-11T19:25:30.650556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became follower at term 0"}
{"level":"info","ts":"2026-03-11T19:25:30.650571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b11e7fc5714be64b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-03-11T19:25:30.650587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became follower at term 1"}
{"level":"info","ts":"2026-03-11T19:25:30.65063Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=(12762778880000648779)"}
{"level":"warn","ts":"2026-03-11T19:25:30.661048Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-03-11T19:25:30.662393Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-03-11T19:25:30.663553Z","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-11T19:25:30.666111Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b11e7fc5714be64b","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-03-11T19:25:30.666313Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b11e7fc5714be64b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-03-11T19:25:30.666524Z","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-11T19:25:30.668293Z","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-11T19:25:30.668335Z","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-11T19:25:30.670731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=(12762778880000648779)"}
{"level":"info","ts":"2026-03-11T19:25:30.670908Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9349e4f32a52a90a","local-member-id":"b11e7fc5714be64b","added-peer-id":"b11e7fc5714be64b","added-peer-peer-urls":["https://162.253.55.52:2380"]}
{"level":"info","ts":"2026-03-11T19:25:30.673239Z","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-11T19:25:30.673343Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.52:2380"}
{"level":"info","ts":"2026-03-11T19:25:30.67339Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.52:2380"}
{"level":"info","ts":"2026-03-11T19:25:30.673616Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b11e7fc5714be64b","initial-advertise-peer-urls":["https://162.253.55.52:2380"],"listen-peer-urls":["https://162.253.55.52:2380"],"advertise-client-urls":["https://162.253.55.52:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-03-11T19:25:30.673691Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-03-11T19:25:31.051559Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b is starting a new election at term 1"}
{"level":"info","ts":"2026-03-11T19:25:31.051613Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became pre-candidate at term 1"}
{"level":"info","ts":"2026-03-11T19:25:31.051648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b received MsgPreVoteResp from b11e7fc5714be64b at term 1"}
{"level":"info","ts":"2026-03-11T19:25:31.051663Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became candidate at term 2"}
{"level":"info","ts":"2026-03-11T19:25:31.05167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b received MsgVoteResp from b11e7fc5714be64b at term 2"}
{"level":"info","ts":"2026-03-11T19:25:31.051681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became leader at term 2"}
{"level":"info","ts":"2026-03-11T19:25:31.051691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b11e7fc5714be64b elected leader b11e7fc5714be64b at term 2"}
{"level":"info","ts":"2026-03-11T19:25:31.06012Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-11T19:25:31.060917Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b11e7fc5714be64b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.52:2379]}","request-path":"/0/members/b11e7fc5714be64b/attributes","cluster-id":"9349e4f32a52a90a","publish-timeout":"7s"}
{"level":"info","ts":"2026-03-11T19:25:31.060928Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-03-11T19:25:31.061041Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-03-11T19:25:31.061279Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9349e4f32a52a90a","local-member-id":"b11e7fc5714be64b","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-11T19:25:31.061288Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-03-11T19:25:31.061417Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-03-11T19:25:31.061369Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-11T19:25:31.061506Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-11T19:25:31.06344Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-03-11T19:25:31.065088Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.52:2379"}
{"level":"info","ts":"2026-03-11T19:25:32.526215Z","caller":"traceutil/trace.go:171","msg":"trace[671745042] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"149.165439ms","start":"2026-03-11T19:25:32.377029Z","end":"2026-03-11T19:25:32.526194Z","steps":["trace[671745042] 'read index received'  (duration: 149.009666ms)","trace[671745042] 'applied index is now lower than readState.Index'  (duration: 154.553µs)"],"step_count":2}
{"level":"info","ts":"2026-03-11T19:25:32.526323Z","caller":"traceutil/trace.go:171","msg":"trace[534525345] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"149.335572ms","start":"2026-03-11T19:25:32.376978Z","end":"2026-03-11T19:25:32.526313Z","steps":["trace[534525345] 'process raft request'  (duration: 149.048776ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-11T19:25:32.526548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.481345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:0 size:4"}
{"level":"info","ts":"2026-03-11T19:25:32.52664Z","caller":"traceutil/trace.go:171","msg":"trace[133236680] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:0; response_revision:2; }","duration":"149.607777ms","start":"2026-03-11T19:25:32.377014Z","end":"2026-03-11T19:25:32.526622Z","steps":["trace[133236680] 'agreement among raft nodes before linearized reading'  (duration: 149.386483ms)"],"step_count":1}
{"level":"info","ts":"2026-03-11T19:25:32.977203Z","caller":"traceutil/trace.go:171","msg":"trace[572885027] linearizableReadLoop","detail":"{readStateIndex:12; appliedIndex:5; }","duration":"450.890178ms","start":"2026-03-11T19:25:32.526291Z","end":"2026-03-11T19:25:32.977182Z","steps":["trace[572885027] 'read index received'  (duration: 450.677214ms)","trace[572885027] 'applied index is now lower than readState.Index'  (duration: 211.834µs)"],"step_count":2}
{"level":"info","ts":"2026-03-11T19:25:32.977301Z","caller":"traceutil/trace.go:171","msg":"trace[756126807] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"506.472285ms","start":"2026-03-11T19:25:32.47082Z","end":"2026-03-11T19:25:32.977293Z","steps":["trace[756126807] 'process raft request'  (duration: 506.319072ms)"],"step_count":1}
{"level":"info","ts":"2026-03-11T19:25:32.977388Z","caller":"traceutil/trace.go:171","msg":"trace[1640018803] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"520.804477ms","start":"2026-03-11T19:25:32.456553Z","end":"2026-03-11T19:25:32.977358Z","steps":["trace[1640018803] 'process raft request'  (duration: 520.288137ms)"],"step_count":1}
{"level":"info","ts":"2026-03-11T19:25:32.977632Z","caller":"traceutil/trace.go:171","msg":"trace[777331501] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"519.233468ms","start":"2026-03-11T19:25:32.458361Z","end":"2026-03-11T19:25:32.977594Z","steps":["trace[777331501] 'process raft request'  (duration: 518.724179ms)"],"step_count":1}
{"level":"info","ts":"2026-03-11T19:25:32.977759Z","caller":"traceutil/trace.go:171","msg":"trace[447137066] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"520.352659ms","start":"2026-03-11T19:25:32.457396Z","end":"2026-03-11T19:25:32.977749Z","steps":["trace[447137066] 'process raft request'  (duration: 519.602146ms)"],"step_count":1}
{"level":"info","ts":"2026-03-11T19:25:32.977849Z","caller":"traceutil/trace.go:171","msg":"trace[1144466369] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"519.581125ms","start":"2026-03-11T19:25:32.45826Z","end":"2026-03-11T19:25:32.977841Z","steps":["trace[1144466369] 'process raft request'  (duration: 518.791961ms)"],"step_count":1}
{"level":"info","ts":"2026-03-11T19:25:32.977879Z","caller":"traceutil/trace.go:171","msg":"trace[1724563997] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"520.185586ms","start":"2026-03-11T19:25:32.45766Z","end":"2026-03-11T19:25:32.977846Z","steps":["trace[1724563997] 'process raft request'  (duration: 519.367591ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-11T19:25:32.977957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:25:32.470809Z","time spent":"506.518107ms","remote":"127.0.0.1:45928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/minions/instance\" mod_revision:0 > success:<request_put:<key:\"/registry/minions/instance\" value_size:3124 >> failure:<>"}
{"level":"warn","ts":"2026-03-11T19:25:32.978058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:25:32.45765Z","time spent":"520.313598ms","remote":"127.0.0.1:46268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/apiregistration.k8s.io/apiservices/v1.\" mod_revision:0 > success:<request_put:<key:\"/registry/apiregistration.k8s.io/apiservices/v1.\" value_size:827 >> failure:<>"}
{"level":"warn","ts":"2026-03-11T19:25:32.978082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.428136ms","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-11T19:25:32.978123Z","caller":"traceutil/trace.go:171","msg":"trace[1079121972] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"546.493437ms","start":"2026-03-11T19:25:32.43162Z","end":"2026-03-11T19:25:32.978113Z","steps":["trace[1079121972] 'agreement among raft nodes before linearized reading'  (duration: 546.320194ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-11T19:25:32.978164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:25:32.431577Z","time spent":"546.579368ms","remote":"127.0.0.1:45856","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-11T19:25:32.978256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.749472ms","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-11T19:25:32.978226Z","caller":"traceutil/trace.go:171","msg":"trace[974394856] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"519.750788ms","start":"2026-03-11T19:25:32.45843Z","end":"2026-03-11T19:25:32.978181Z","steps":["trace[974394856] 'process raft request'  (duration: 518.676089ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-11T19:25:32.978285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.126204ms","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":"info","ts":"2026-03-11T19:25:32.978307Z","caller":"traceutil/trace.go:171","msg":"trace[1432173415] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"546.828383ms","start":"2026-03-11T19:25:32.43146Z","end":"2026-03-11T19:25:32.978288Z","steps":["trace[1432173415] 'agreement among raft nodes before linearized reading'  (duration: 546.755762ms)"],"step_count":1}
{"level":"info","ts":"2026-03-11T19:25:32.978324Z","caller":"traceutil/trace.go:171","msg":"trace[1659040299] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:9; }","duration":"520.210926ms","start":"2026-03-11T19:25:32.4581Z","end":"2026-03-11T19:25:32.978311Z","steps":["trace[1659040299] 'agreement among raft nodes before linearized reading'  (duration: 520.137805ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-11T19:25:32.978343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:25:32.431413Z","time spent":"546.923625ms","remote":"127.0.0.1:45856","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-11T19:25:32.978366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:25:32.458091Z","time spent":"520.265628ms","remote":"127.0.0.1:45840","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-11T19:25:32.978395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:25:32.458423Z","time spent":"519.89417ms","remote":"127.0.0.1:46268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/apiregistration.k8s.io/apiservices/v1.apps\" mod_revision:0 > success:<request_put:<key:\"/registry/apiregistration.k8s.io/apiservices/v1.apps\" value_size:860 >> failure:<>"}
{"level":"warn","ts":"2026-03-11T19:25:32.978424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.014858ms","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-11T19:25:32.978448Z","caller":"traceutil/trace.go:171","msg":"trace[2145753947] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"547.114389ms","start":"2026-03-11T19:25:32.431326Z","end":"2026-03-11T19:25:32.978441Z","steps":["trace[2145753947] 'agreement among raft nodes before linearized reading'  (duration: 547.079859ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-11T19:25:32.97848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:25:32.431315Z","time spent":"547.16078ms","remote":"127.0.0.1:45856","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-11T19:25:32.978505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.761334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"}
{"level":"warn","ts":"2026-03-11T19:25:32.978553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.071078ms","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-11T19:25:32.97857Z","caller":"traceutil/trace.go:171","msg":"trace[1857780307] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"522.832585ms","start":"2026-03-11T19:25:32.455718Z","end":"2026-03-11T19:25:32.978551Z","steps":["trace[1857780307] 'agreement among raft nodes before linearized reading'  (duration: 522.758574ms)"],"step_count":1}
{"level":"info","ts":"2026-03-11T19:25:32.978591Z","caller":"traceutil/trace.go:171","msg":"trace[107744482] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"471.13185ms","start":"2026-03-11T19:25:32.50745Z","end":"2026-03-11T19:25:32.978582Z","steps":["trace[107744482] 'agreement among raft nodes before linearized reading'  (duration: 471.072899ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-11T19:25:32.978604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.20655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.52\" ","response":"range_response_count:0 size:4"}
{"level":"warn","ts":"2026-03-11T19:25:32.978626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:25:32.507407Z","time spent":"471.21416ms","remote":"127.0.0.1:45856","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-11T19:25:32.97864Z","caller":"traceutil/trace.go:171","msg":"trace[1684205781] range","detail":"{range_begin:/registry/masterleases/162.253.55.52; range_end:; response_count:0; response_revision:9; }","duration":"524.273771ms","start":"2026-03-11T19:25:32.454356Z","end":"2026-03-11T19:25:32.97863Z","steps":["trace[1684205781] 'agreement among raft nodes before linearized reading'  (duration: 524.2068ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-11T19:25:32.978648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.60449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:0 size:4"}
{"level":"info","ts":"2026-03-11T19:25:32.978677Z","caller":"traceutil/trace.go:171","msg":"trace[294053095] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:9; }","duration":"599.63389ms","start":"2026-03-11T19:25:32.379035Z","end":"2026-03-11T19:25:32.978669Z","steps":["trace[294053095] 'agreement among raft nodes before linearized reading'  (duration: 599.581609ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-11T19:25:32.978679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:25:32.454342Z","time spent":"524.329872ms","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/162.253.55.52\" "}
{"level":"warn","ts":"2026-03-11T19:25:32.978714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:25:32.379023Z","time spent":"599.684931ms","remote":"127.0.0.1:45952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" "}
{"level":"warn","ts":"2026-03-11T19:25:32.978599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:25:32.455709Z","time spent":"522.884436ms","remote":"127.0.0.1:45856","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-11T19:25:32.978858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.355552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3170"}
{"level":"info","ts":"2026-03-11T19:25:32.978885Z","caller":"traceutil/trace.go:171","msg":"trace[719568600] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:9; }","duration":"600.389164ms","start":"2026-03-11T19:25:32.378488Z","end":"2026-03-11T19:25:32.978877Z","steps":["trace[719568600] 'agreement among raft nodes before linearized reading'  (duration: 600.331043ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-11T19:25:32.978895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:25:32.456526Z","time spent":"520.94961ms","remote":"127.0.0.1:46202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/prioritylevelconfigurations/system\" mod_revision:0 > success:<request_put:<key:\"/registry/prioritylevelconfigurations/system\" value_size:646 >> failure:<>"}
{"level":"warn","ts":"2026-03-11T19:25:32.978911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:25:32.378459Z","time spent":"600.445974ms","remote":"127.0.0.1:45928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3194,"request content":"key:\"/registry/minions/instance\" "}
{"level":"warn","ts":"2026-03-11T19:25:32.979065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:25:32.45738Z","time spent":"520.407441ms","remote":"127.0.0.1:46268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/apiregistration.k8s.io/apiservices/v1.admissionregistration.k8s.io\" mod_revision:0 > success:<request_put:<key:\"/registry/apiregistration.k8s.io/apiservices/v1.admissionregistration.k8s.io\" value_size:908 >> failure:<>"}
{"level":"warn","ts":"2026-03-11T19:25:32.979125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:25:32.458251Z","time spent":"519.624135ms","remote":"127.0.0.1:46268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/apiregistration.k8s.io/apiservices/v1.authentication.k8s.io\" mod_revision:0 > success:<request_put:<key:\"/registry/apiregistration.k8s.io/apiservices/v1.authentication.k8s.io\" value_size:894 >> failure:<>"}
{"level":"warn","ts":"2026-03-11T19:25:32.97917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:25:32.458347Z","time spent":"519.365121ms","remote":"127.0.0.1:46268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/apiregistration.k8s.io/apiservices/v1.apiextensions.k8s.io\" mod_revision:0 > success:<request_put:<key:\"/registry/apiregistration.k8s.io/apiservices/v1.apiextensions.k8s.io\" value_size:892 >> failure:<>"}
{"level":"info","ts":"2026-03-11T19:25:32.989326Z","caller":"traceutil/trace.go:171","msg":"trace[324572835] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"461.706105ms","start":"2026-03-11T19:25:32.527602Z","end":"2026-03-11T19:25:32.989308Z","steps":["trace[324572835] 'process raft request'  (duration: 461.542732ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-11T19:25:32.989604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.046996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-874tn\" ","response":"range_response_count:1 size:861"}
{"level":"info","ts":"2026-03-11T19:25:32.989805Z","caller":"traceutil/trace.go:171","msg":"trace[81486678] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-874tn; range_end:; response_count:1; response_revision:10; }","duration":"460.310281ms","start":"2026-03-11T19:25:32.52947Z","end":"2026-03-11T19:25:32.98978Z","steps":["trace[81486678] 'agreement among raft nodes before linearized reading'  (duration: 459.849601ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-11T19:25:32.989902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:25:32.52946Z","time spent":"460.429143ms","remote":"127.0.0.1:46004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":885,"request content":"key:\"/registry/certificatesigningrequests/csr-874tn\" "}
{"level":"warn","ts":"2026-03-11T19:25:32.99005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:25:32.527588Z","time spent":"461.796537ms","remote":"127.0.0.1:45820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/ranges/servicenodeports\" mod_revision:0 > success:<request_put:<key:\"/registry/ranges/servicenodeports\" value_size:66 >> failure:<request_range:<key:\"/registry/ranges/servicenodeports\" > >"}
{"level":"info","ts":"2026-03-11T19:25:33.267049Z","caller":"traceutil/trace.go:171","msg":"trace[1143695596] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"285.930971ms","start":"2026-03-11T19:25:32.981078Z","end":"2026-03-11T19:25:33.267009Z","steps":["trace[1143695596] 'process raft request'  (duration: 277.352744ms)"],"step_count":1}
{"level":"info","ts":"2026-03-11T19:25:33.26726Z","caller":"traceutil/trace.go:171","msg":"trace[2189182] transaction","detail":"{read_only:false; number_of_response:0; response_revision:11; }","duration":"282.04078ms","start":"2026-03-11T19:25:32.985035Z","end":"2026-03-11T19:25:33.267076Z","steps":["trace[2189182] 'process raft request'  (duration: 281.889128ms)"],"step_count":1}
{"level":"info","ts":"2026-03-11T19:25:33.267339Z","caller":"traceutil/trace.go:171","msg":"trace[1848650817] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"280.631414ms","start":"2026-03-11T19:25:32.986675Z","end":"2026-03-11T19:25:33.267307Z","steps":["trace[1848650817] 'process raft request'  (duration: 280.40667ms)"],"step_count":1}
{"level":"info","ts":"2026-03-11T19:25:33.26752Z","caller":"traceutil/trace.go:171","msg":"trace[1301470522] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"281.134334ms","start":"2026-03-11T19:25:32.986347Z","end":"2026-03-11T19:25:33.267481Z","steps":["trace[1301470522] 'process raft request'  (duration: 280.664325ms)"],"step_count":1}
{"level":"info","ts":"2026-03-11T19:25:33.267782Z","caller":"traceutil/trace.go:171","msg":"trace[1283211892] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"281.275556ms","start":"2026-03-11T19:25:32.986491Z","end":"2026-03-11T19:25:33.267767Z","steps":["trace[1283211892] 'process raft request'  (duration: 280.552043ms)"],"step_count":1}
{"level":"info","ts":"2026-03-11T19:25:33.267851Z","caller":"traceutil/trace.go:171","msg":"trace[1313914492] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"281.031091ms","start":"2026-03-11T19:25:32.986791Z","end":"2026-03-11T19:25:33.267822Z","steps":["trace[1313914492] 'process raft request'  (duration: 280.366409ms)"],"step_count":1}
{"level":"info","ts":"2026-03-11T19:25:33.267798Z","caller":"traceutil/trace.go:171","msg":"trace[1721316500] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"281.055261ms","start":"2026-03-11T19:25:32.986733Z","end":"2026-03-11T19:25:33.267788Z","steps":["trace[1721316500] 'process raft request'  (duration: 280.399719ms)"],"step_count":1}
{"level":"info","ts":"2026-03-11T19:25:33.267991Z","caller":"traceutil/trace.go:171","msg":"trace[145237858] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"281.698923ms","start":"2026-03-11T19:25:32.986279Z","end":"2026-03-11T19:25:33.267978Z","steps":["trace[145237858] 'process raft request'  (duration: 280.674485ms)"],"step_count":1}
{"level":"info","ts":"2026-03-11T19:25:33.26811Z","caller":"traceutil/trace.go:171","msg":"trace[909149046] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"280.056984ms","start":"2026-03-11T19:25:32.988007Z","end":"2026-03-11T19:25:33.268064Z","steps":["trace[909149046] 'process raft request'  (duration: 279.184928ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-11T19:25:33.268308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.063984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:0 size:4"}
{"level":"info","ts":"2026-03-11T19:25:33.268369Z","caller":"traceutil/trace.go:171","msg":"trace[675436576] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:0; response_revision:18; }","duration":"286.194126ms","start":"2026-03-11T19:25:32.982165Z","end":"2026-03-11T19:25:33.268359Z","steps":["trace[675436576] 'agreement among raft nodes before linearized reading'  (duration: 286.010133ms)"],"step_count":1}
{"level":"info","ts":"2026-03-11T19:25:33.268155Z","caller":"traceutil/trace.go:171","msg":"trace[354862347] linearizableReadLoop","detail":"{readStateIndex:22; appliedIndex:13; }","duration":"278.854051ms","start":"2026-03-11T19:25:32.98929Z","end":"2026-03-11T19:25:33.268144Z","steps":["trace[354862347] 'read index received'  (duration: 269.164885ms)","trace[354862347] 'applied index is now lower than readState.Index'  (duration: 9.687846ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-11T19:25:33.268761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.22338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"}
{"level":"info","ts":"2026-03-11T19:25:33.268798Z","caller":"traceutil/trace.go:171","msg":"trace[1512678433] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18; }","duration":"284.30426ms","start":"2026-03-11T19:25:32.984487Z","end":"2026-03-11T19:25:33.268791Z","steps":["trace[1512678433] 'agreement among raft nodes before linearized reading'  (duration: 284.055426ms)"],"step_count":1}
{"level":"info","ts":"2026-03-11T19:25:33.270985Z","caller":"traceutil/trace.go:171","msg":"trace[531662787] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"277.474807ms","start":"2026-03-11T19:25:32.993486Z","end":"2026-03-11T19:25:33.270961Z","steps":["trace[531662787] 'process raft request'  (duration: 273.754958ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-11T19:25:33.271246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.339097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"}
{"level":"info","ts":"2026-03-11T19:25:33.2713Z","caller":"traceutil/trace.go:171","msg":"trace[1137646023] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:19; }","duration":"228.432508ms","start":"2026-03-11T19:25:33.042847Z","end":"2026-03-11T19:25:33.27128Z","steps":["trace[1137646023] 'agreement among raft nodes before linearized reading'  (duration: 228.313926ms)"],"step_count":1}
{"level":"info","ts":"2026-03-11T19:26:09.429813Z","caller":"traceutil/trace.go:171","msg":"trace[13158141] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"117.901021ms","start":"2026-03-11T19:26:09.311889Z","end":"2026-03-11T19:26:09.42979Z","steps":["trace[13158141] 'process raft request'  (duration: 117.701788ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-11T19:26:09.97319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.092331ms","expected-duration":"100ms","prefix":"","request":"header:<ID:16594529730825461635 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:525 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:370 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >>","response":"size:16"}
{"level":"info","ts":"2026-03-11T19:26:09.973302Z","caller":"traceutil/trace.go:171","msg":"trace[1189424085] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:551; }","duration":"404.887893ms","start":"2026-03-11T19:26:09.568398Z","end":"2026-03-11T19:26:09.973285Z","steps":["trace[1189424085] 'read index received'  (duration: 216.345843ms)","trace[1189424085] 'applied index is now lower than readState.Index'  (duration: 188.54051ms)"],"step_count":2}
{"level":"info","ts":"2026-03-11T19:26:09.973362Z","caller":"traceutil/trace.go:171","msg":"trace[1912866705] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"540.023154ms","start":"2026-03-11T19:26:09.433267Z","end":"2026-03-11T19:26:09.97329Z","steps":["trace[1912866705] 'process raft request'  (duration: 351.562116ms)","trace[1912866705] 'compare'  (duration: 187.816406ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-11T19:26:09.973466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.075326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"warn","ts":"2026-03-11T19:26:09.973477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:26:09.433251Z","time spent":"540.159576ms","remote":"127.0.0.1:46008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:525 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:370 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"info","ts":"2026-03-11T19:26:09.973501Z","caller":"traceutil/trace.go:171","msg":"trace[1722228161] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:533; }","duration":"405.139906ms","start":"2026-03-11T19:26:09.568352Z","end":"2026-03-11T19:26:09.973492Z","steps":["trace[1722228161] 'agreement among raft nodes before linearized reading'  (duration: 404.990824ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-11T19:26:09.973537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:26:09.568334Z","time spent":"405.195938ms","remote":"127.0.0.1:46008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"info","ts":"2026-03-11T19:26:11.557209Z","caller":"traceutil/trace.go:171","msg":"trace[247384731] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"107.268295ms","start":"2026-03-11T19:26:11.449919Z","end":"2026-03-11T19:26:11.557187Z","steps":["trace[247384731] 'process raft request'  (duration: 107.133892ms)"],"step_count":1}
{"level":"info","ts":"2026-03-11T19:26:26.639873Z","caller":"traceutil/trace.go:171","msg":"trace[348438897] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"228.976328ms","start":"2026-03-11T19:26:26.410868Z","end":"2026-03-11T19:26:26.639845Z","steps":["trace[348438897] 'process raft request'  (duration: 228.817674ms)"],"step_count":1}
{"level":"info","ts":"2026-03-11T19:26:51.399637Z","caller":"traceutil/trace.go:171","msg":"trace[706107544] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"163.073127ms","start":"2026-03-11T19:26:51.236535Z","end":"2026-03-11T19:26:51.399608Z","steps":["trace[706107544] 'process raft request'  (duration: 162.824583ms)"],"step_count":1}
{"level":"info","ts":"2026-03-11T19:26:51.399592Z","caller":"traceutil/trace.go:171","msg":"trace[982354066] linearizableReadLoop","detail":"{readStateIndex:815; appliedIndex:814; }","duration":"144.470614ms","start":"2026-03-11T19:26:51.255093Z","end":"2026-03-11T19:26:51.399564Z","steps":["trace[982354066] 'read index received'  (duration: 144.24047ms)","trace[982354066] 'applied index is now lower than readState.Index'  (duration: 228.474µs)"],"step_count":2}
{"level":"warn","ts":"2026-03-11T19:26:51.399842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.728019ms","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-11T19:26:51.399972Z","caller":"traceutil/trace.go:171","msg":"trace[1296217660] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:786; }","duration":"144.858461ms","start":"2026-03-11T19:26:51.255067Z","end":"2026-03-11T19:26:51.399925Z","steps":["trace[1296217660] 'agreement among raft nodes before linearized reading'  (duration: 144.638116ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-11T19:27:03.962949Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529730825462374,"retry-timeout":"500ms"}
{"level":"info","ts":"2026-03-11T19:27:04.020345Z","caller":"traceutil/trace.go:171","msg":"trace[1113225625] linearizableReadLoop","detail":"{readStateIndex:903; appliedIndex:902; }","duration":"558.012477ms","start":"2026-03-11T19:27:03.46231Z","end":"2026-03-11T19:27:04.020322Z","steps":["trace[1113225625] 'read index received'  (duration: 557.901075ms)","trace[1113225625] 'applied index is now lower than readState.Index'  (duration: 110.392µs)"],"step_count":2}
{"level":"info","ts":"2026-03-11T19:27:04.020434Z","caller":"traceutil/trace.go:171","msg":"trace[1599532131] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"565.619328ms","start":"2026-03-11T19:27:03.454794Z","end":"2026-03-11T19:27:04.020414Z","steps":["trace[1599532131] 'process raft request'  (duration: 565.383014ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-11T19:27:04.020552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:27:03.454784Z","time spent":"565.696361ms","remote":"127.0.0.1:46008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:865 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"warn","ts":"2026-03-11T19:27:04.020568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.245452ms","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-03-11T19:27:04.020604Z","caller":"traceutil/trace.go:171","msg":"trace[1010462482] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:870; }","duration":"558.301064ms","start":"2026-03-11T19:27:03.462291Z","end":"2026-03-11T19:27:04.020592Z","steps":["trace[1010462482] 'agreement among raft nodes before linearized reading'  (duration: 558.17658ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-11T19:27:04.020634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:27:03.462284Z","time spent":"558.342914ms","remote":"127.0.0.1:46008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"warn","ts":"2026-03-11T19:27:04.020707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.750638ms","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-03-11T19:27:04.020796Z","caller":"traceutil/trace.go:171","msg":"trace[220820933] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:870; }","duration":"109.86064ms","start":"2026-03-11T19:27:03.910915Z","end":"2026-03-11T19:27:04.020776Z","steps":["trace[220820933] 'agreement among raft nodes before linearized reading'  (duration: 109.679007ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-11T19:27:04.021527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.412123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"}
{"level":"info","ts":"2026-03-11T19:27:04.021587Z","caller":"traceutil/trace.go:171","msg":"trace[1867942818] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:870; }","duration":"420.521315ms","start":"2026-03-11T19:27:03.601053Z","end":"2026-03-11T19:27:04.021574Z","steps":["trace[1867942818] 'agreement among raft nodes before linearized reading'  (duration: 419.527127ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-11T19:27:04.021619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:27:03.601034Z","time spent":"420.577006ms","remote":"127.0.0.1:46008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"info","ts":"2026-03-11T19:27:04.266247Z","caller":"traceutil/trace.go:171","msg":"trace[1620731792] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"241.894961ms","start":"2026-03-11T19:27:04.024328Z","end":"2026-03-11T19:27:04.266223Z","steps":["trace[1620731792] 'process raft request'  (duration: 241.724038ms)"],"step_count":1}
{"level":"info","ts":"2026-03-11T19:27:04.267147Z","caller":"traceutil/trace.go:171","msg":"trace[2024611888] linearizableReadLoop","detail":"{readStateIndex:905; appliedIndex:903; }","duration":"188.5454ms","start":"2026-03-11T19:27:04.078579Z","end":"2026-03-11T19:27:04.267124Z","steps":["trace[2024611888] 'read index received'  (duration: 187.49382ms)","trace[2024611888] 'applied index is now lower than readState.Index'  (duration: 1.05039ms)"],"step_count":2}
{"level":"info","ts":"2026-03-11T19:27:04.267183Z","caller":"traceutil/trace.go:171","msg":"trace[1676087091] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"241.603195ms","start":"2026-03-11T19:27:04.025556Z","end":"2026-03-11T19:27:04.267159Z","steps":["trace[1676087091] 'process raft request'  (duration: 241.442193ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-11T19:27:04.267371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.779785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.52\" ","response":"range_response_count:1 size:133"}
{"level":"info","ts":"2026-03-11T19:27:04.267412Z","caller":"traceutil/trace.go:171","msg":"trace[1651050979] range","detail":"{range_begin:/registry/masterleases/162.253.55.52; range_end:; response_count:1; response_revision:872; }","duration":"188.865316ms","start":"2026-03-11T19:27:04.078536Z","end":"2026-03-11T19:27:04.267401Z","steps":["trace[1651050979] 'agreement among raft nodes before linearized reading'  (duration: 188.702593ms)"],"step_count":1}
{"level":"info","ts":"2026-03-11T19:27:16.522428Z","caller":"traceutil/trace.go:171","msg":"trace[545223235] linearizableReadLoop","detail":"{readStateIndex:943; appliedIndex:941; }","duration":"173.682294ms","start":"2026-03-11T19:27:16.348713Z","end":"2026-03-11T19:27:16.522396Z","steps":["trace[545223235] 'read index received'  (duration: 9.406024ms)","trace[545223235] 'applied index is now lower than readState.Index'  (duration: 164.27519ms)"],"step_count":2}
{"level":"info","ts":"2026-03-11T19:27:16.522551Z","caller":"traceutil/trace.go:171","msg":"trace[342689286] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"174.277276ms","start":"2026-03-11T19:27:16.348265Z","end":"2026-03-11T19:27:16.522542Z","steps":["trace[342689286] 'process raft request'  (duration: 161.137681ms)","trace[342689286] 'compare'  (duration: 12.91498ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-11T19:27:16.522675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.386912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-03-11T19:27:16.522708Z","caller":"traceutil/trace.go:171","msg":"trace[1820046153] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:907; }","duration":"157.475984ms","start":"2026-03-11T19:27:16.365224Z","end":"2026-03-11T19:27:16.5227Z","steps":["trace[1820046153] 'agreement among raft nodes before linearized reading'  (duration: 157.356402ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-11T19:27:16.522833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.114502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"info","ts":"2026-03-11T19:27:16.522881Z","caller":"traceutil/trace.go:171","msg":"trace[1256495469] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:907; }","duration":"174.180544ms","start":"2026-03-11T19:27:16.348692Z","end":"2026-03-11T19:27:16.522873Z","steps":["trace[1256495469] 'agreement among raft nodes before linearized reading'  (duration: 174.085042ms)"],"step_count":1}
{"level":"info","ts":"2026-03-11T19:27:20.591671Z","caller":"traceutil/trace.go:171","msg":"trace[548705485] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"211.567197ms","start":"2026-03-11T19:27:20.380064Z","end":"2026-03-11T19:27:20.591631Z","steps":["trace[548705485] 'process raft request'  (duration: 211.255202ms)"],"step_count":1}
{"level":"info","ts":"2026-03-11T19:27:20.997604Z","caller":"traceutil/trace.go:171","msg":"trace[1549426941] linearizableReadLoop","detail":"{readStateIndex:982; appliedIndex:981; }","duration":"393.83106ms","start":"2026-03-11T19:27:20.603752Z","end":"2026-03-11T19:27:20.997583Z","steps":["trace[1549426941] 'read index received'  (duration: 393.665767ms)","trace[1549426941] 'applied index is now lower than readState.Index'  (duration: 164.473µs)"],"step_count":2}
{"level":"info","ts":"2026-03-11T19:27:20.997691Z","caller":"traceutil/trace.go:171","msg":"trace[1894503348] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"401.268469ms","start":"2026-03-11T19:27:20.596388Z","end":"2026-03-11T19:27:20.997657Z","steps":["trace[1894503348] 'process raft request'  (duration: 401.077045ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-11T19:27:20.997836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.070404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"warn","ts":"2026-03-11T19:27:20.997861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:27:20.596362Z","time spent":"401.413342ms","remote":"127.0.0.1:46008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:929 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:410 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"info","ts":"2026-03-11T19:27:20.997893Z","caller":"traceutil/trace.go:171","msg":"trace[116391013] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:945; }","duration":"394.145406ms","start":"2026-03-11T19:27:20.603736Z","end":"2026-03-11T19:27:20.997881Z","steps":["trace[116391013] 'agreement among raft nodes before linearized reading'  (duration: 393.967962ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-11T19:27:20.997924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:27:20.603679Z","time spent":"394.237208ms","remote":"127.0.0.1:46008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-03-11T19:27:20.999016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.372119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-03-11T19:27:20.9991Z","caller":"traceutil/trace.go:171","msg":"trace[1964121823] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:945; }","duration":"339.508252ms","start":"2026-03-11T19:27:20.659569Z","end":"2026-03-11T19:27:20.999077Z","steps":["trace[1964121823] 'agreement among raft nodes before linearized reading'  (duration: 339.263518ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-11T19:27:20.999139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:27:20.659546Z","time spent":"339.585874ms","remote":"127.0.0.1:46008","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-03-11T19:27:22.316971Z","caller":"traceutil/trace.go:171","msg":"trace[1609049391] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"204.707179ms","start":"2026-03-11T19:27:22.112239Z","end":"2026-03-11T19:27:22.316947Z","steps":["trace[1609049391] 'process raft request'  (duration: 204.573517ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-11T19:27:22.938896Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529730825462566,"retry-timeout":"500ms"}
{"level":"info","ts":"2026-03-11T19:27:23.070508Z","caller":"traceutil/trace.go:171","msg":"trace[2046878922] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"709.464798ms","start":"2026-03-11T19:27:22.361022Z","end":"2026-03-11T19:27:23.070487Z","steps":["trace[2046878922] 'process raft request'  (duration: 709.335266ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-11T19:27:23.070659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:27:22.360996Z","time spent":"709.587321ms","remote":"127.0.0.1:45832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":779,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/events/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-b6bht.189be0111a23c717\" mod_revision:0 > success:<request_put:<key:\"/registry/events/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-b6bht.189be0111a23c717\" value_size:675 lease:7371157693970686500 >> failure:<>"}
{"level":"info","ts":"2026-03-11T19:27:23.071295Z","caller":"traceutil/trace.go:171","msg":"trace[1093014589] linearizableReadLoop","detail":"{readStateIndex:987; appliedIndex:987; }","duration":"633.117671ms","start":"2026-03-11T19:27:22.438165Z","end":"2026-03-11T19:27:23.071282Z","steps":["trace[1093014589] 'read index received'  (duration: 633.109471ms)","trace[1093014589] 'applied index is now lower than readState.Index'  (duration: 7.08µs)"],"step_count":2}
{"level":"warn","ts":"2026-03-11T19:27:23.071639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.19971ms","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-03-11T19:27:23.071693Z","caller":"traceutil/trace.go:171","msg":"trace[1877391240] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:950; }","duration":"475.298751ms","start":"2026-03-11T19:27:22.596383Z","end":"2026-03-11T19:27:23.071682Z","steps":["trace[1877391240] 'agreement among raft nodes before linearized reading'  (duration: 474.976726ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-11T19:27:23.071724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:27:22.596363Z","time spent":"475.353563ms","remote":"127.0.0.1:46008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"warn","ts":"2026-03-11T19:27:23.071753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.565059ms","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-11T19:27:23.072008Z","caller":"traceutil/trace.go:171","msg":"trace[855807264] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:950; }","duration":"633.852445ms","start":"2026-03-11T19:27:22.438131Z","end":"2026-03-11T19:27:23.071984Z","steps":["trace[855807264] 'agreement among raft nodes before linearized reading'  (duration: 633.533499ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-11T19:27:23.07398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:27:22.438117Z","time spent":"635.821762ms","remote":"127.0.0.1:46008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "}
{"level":"info","ts":"2026-03-11T19:27:23.235178Z","caller":"traceutil/trace.go:171","msg":"trace[111479418] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"100.554437ms","start":"2026-03-11T19:27:23.134605Z","end":"2026-03-11T19:27:23.23516Z","steps":["trace[111479418] 'process raft request'  (duration: 100.521786ms)"],"step_count":1}
{"level":"info","ts":"2026-03-11T19:27:23.235322Z","caller":"traceutil/trace.go:171","msg":"trace[1527881778] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"151.106175ms","start":"2026-03-11T19:27:23.084186Z","end":"2026-03-11T19:27:23.235293Z","steps":["trace[1527881778] 'process raft request'  (duration: 79.208411ms)","trace[1527881778] 'compare'  (duration: 71.565508ms)"],"step_count":2}
{"level":"info","ts":"2026-03-11T19:27:23.235552Z","caller":"traceutil/trace.go:171","msg":"trace[48737542] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:989; }","duration":"128.380763ms","start":"2026-03-11T19:27:23.107133Z","end":"2026-03-11T19:27:23.235514Z","steps":["trace[48737542] 'read index received'  (duration: 20.064143ms)","trace[48737542] 'applied index is now lower than readState.Index'  (duration: 108.31482ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-11T19:27:23.235935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.035522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"}
{"level":"info","ts":"2026-03-11T19:27:23.235988Z","caller":"traceutil/trace.go:171","msg":"trace[442518400] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:957; }","duration":"103.122424ms","start":"2026-03-11T19:27:23.132846Z","end":"2026-03-11T19:27:23.235969Z","steps":["trace[442518400] 'agreement among raft nodes before linearized reading'  (duration: 102.827868ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-11T19:27:23.23596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.78044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-03-11T19:27:23.236087Z","caller":"traceutil/trace.go:171","msg":"trace[1643449482] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:957; }","duration":"128.958475ms","start":"2026-03-11T19:27:23.107098Z","end":"2026-03-11T19:27:23.236056Z","steps":["trace[1643449482] 'agreement among raft nodes before linearized reading'  (duration: 128.515676ms)"],"step_count":1}
{"level":"info","ts":"2026-03-11T19:27:24.388585Z","caller":"traceutil/trace.go:171","msg":"trace[1537550632] linearizableReadLoop","detail":"{readStateIndex:997; appliedIndex:996; }","duration":"274.495835ms","start":"2026-03-11T19:27:24.114066Z","end":"2026-03-11T19:27:24.388562Z","steps":["trace[1537550632] 'read index received'  (duration: 274.270861ms)","trace[1537550632] 'applied index is now lower than readState.Index'  (duration: 222.934µs)"],"step_count":2}
{"level":"warn","ts":"2026-03-11T19:27:24.388627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:27:24.081945Z","time spent":"306.671632ms","remote":"127.0.0.1:45770","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""}
{"level":"warn","ts":"2026-03-11T19:27:24.389279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.192847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"}
{"level":"info","ts":"2026-03-11T19:27:24.389359Z","caller":"traceutil/trace.go:171","msg":"trace[1110656272] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:959; }","duration":"275.296809ms","start":"2026-03-11T19:27:24.114043Z","end":"2026-03-11T19:27:24.38934Z","steps":["trace[1110656272] 'agreement among raft nodes before linearized reading'  (duration: 274.651617ms)"],"step_count":1}
{"level":"info","ts":"2026-03-11T19:27:24.58612Z","caller":"traceutil/trace.go:171","msg":"trace[901059556] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"194.686254ms","start":"2026-03-11T19:27:24.391376Z","end":"2026-03-11T19:27:24.586062Z","steps":["trace[901059556] 'process raft request'  (duration: 156.815831ms)","trace[901059556] 'compare'  (duration: 37.68895ms)"],"step_count":2}
{"level":"info","ts":"2026-03-11T19:27:24.651486Z","caller":"traceutil/trace.go:171","msg":"trace[370140246] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"256.799966ms","start":"2026-03-11T19:27:24.394653Z","end":"2026-03-11T19:27:24.651453Z","steps":["trace[370140246] 'process raft request'  (duration: 256.440069ms)"],"step_count":1}
{"level":"info","ts":"2026-03-11T19:27:24.652013Z","caller":"traceutil/trace.go:171","msg":"trace[79737042] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"254.591305ms","start":"2026-03-11T19:27:24.397392Z","end":"2026-03-11T19:27:24.651984Z","steps":["trace[79737042] 'process raft request'  (duration: 254.253329ms)"],"step_count":1}
{"level":"info","ts":"2026-03-11T19:27:32.141209Z","caller":"traceutil/trace.go:171","msg":"trace[183918012] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"181.229484ms","start":"2026-03-11T19:27:31.959955Z","end":"2026-03-11T19:27:32.141185Z","steps":["trace[183918012] 'process raft request'  (duration: 181.055591ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-11T19:27:33.599814Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529730825462698,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-03-11T19:27:34.101092Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529730825462698,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-03-11T19:27:34.497801Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.807265546s","expected-duration":"1s"}
{"level":"info","ts":"2026-03-11T19:27:34.498203Z","caller":"traceutil/trace.go:171","msg":"trace[1852987192] linearizableReadLoop","detail":"{readStateIndex:1055; appliedIndex:1054; }","duration":"1.399399064s","start":"2026-03-11T19:27:33.09878Z","end":"2026-03-11T19:27:34.498179Z","steps":["trace[1852987192] 'read index received'  (duration: 1.399219281s)","trace[1852987192] 'applied index is now lower than readState.Index'  (duration: 178.933µs)"],"step_count":2}
{"level":"info","ts":"2026-03-11T19:27:34.498332Z","caller":"traceutil/trace.go:171","msg":"trace[58984972] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"1.807887526s","start":"2026-03-11T19:27:32.690432Z","end":"2026-03-11T19:27:34.498319Z","steps":["trace[58984972] 'process raft request'  (duration: 1.807614991s)"],"step_count":1}
{"level":"warn","ts":"2026-03-11T19:27:34.498475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:27:32.690404Z","time spent":"1.807988719s","remote":"127.0.0.1:46008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:1008 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"warn","ts":"2026-03-11T19:27:34.498748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.322709592s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"}
{"level":"info","ts":"2026-03-11T19:27:34.498823Z","caller":"traceutil/trace.go:171","msg":"trace[167078591] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1016; }","duration":"1.322908545s","start":"2026-03-11T19:27:33.175902Z","end":"2026-03-11T19:27:34.498811Z","steps":["trace[167078591] 'agreement among raft nodes before linearized reading'  (duration: 1.322751952s)"],"step_count":1}
{"level":"warn","ts":"2026-03-11T19:27:34.498858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.788204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.52\" ","response":"range_response_count:1 size:133"}
{"level":"info","ts":"2026-03-11T19:27:34.49891Z","caller":"traceutil/trace.go:171","msg":"trace[1404946190] range","detail":"{range_begin:/registry/masterleases/162.253.55.52; range_end:; response_count:1; response_revision:1016; }","duration":"418.872095ms","start":"2026-03-11T19:27:34.080023Z","end":"2026-03-11T19:27:34.498895Z","steps":["trace[1404946190] 'agreement among raft nodes before linearized reading'  (duration: 418.774563ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-11T19:27:34.498766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.191199521s","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-03-11T19:27:34.498881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.910161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-11T19:27:34.499014Z","caller":"traceutil/trace.go:171","msg":"trace[1850369343] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1016; }","duration":"158.124165ms","start":"2026-03-11T19:27:34.340861Z","end":"2026-03-11T19:27:34.498985Z","steps":["trace[1850369343] 'agreement among raft nodes before linearized reading'  (duration: 157.862041ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-11T19:27:34.498915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.328126282s","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-11T19:27:34.499007Z","caller":"traceutil/trace.go:171","msg":"trace[876547336] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1016; }","duration":"1.191423665s","start":"2026-03-11T19:27:33.307528Z","end":"2026-03-11T19:27:34.498952Z","steps":["trace[876547336] 'agreement among raft nodes before linearized reading'  (duration: 1.191132529s)"],"step_count":1}
{"level":"info","ts":"2026-03-11T19:27:34.499079Z","caller":"traceutil/trace.go:171","msg":"trace[1626508359] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1016; }","duration":"1.328301866s","start":"2026-03-11T19:27:33.170766Z","end":"2026-03-11T19:27:34.499068Z","steps":["trace[1626508359] 'agreement among raft nodes before linearized reading'  (duration: 1.328092772s)"],"step_count":1}
{"level":"warn","ts":"2026-03-11T19:27:34.49907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.200810639s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"}
{"level":"warn","ts":"2026-03-11T19:27:34.499113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:27:33.170759Z","time spent":"1.328344826s","remote":"127.0.0.1:46008","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-03-11T19:27:34.498806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.329195112s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"}
{"level":"info","ts":"2026-03-11T19:27:34.499192Z","caller":"traceutil/trace.go:171","msg":"trace[1925495006] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1016; }","duration":"1.32962908s","start":"2026-03-11T19:27:33.169551Z","end":"2026-03-11T19:27:34.49918Z","steps":["trace[1925495006] 'agreement among raft nodes before linearized reading'  (duration: 1.32909924s)"],"step_count":1}
{"level":"warn","ts":"2026-03-11T19:27:34.499262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:27:33.169539Z","time spent":"1.329709622s","remote":"127.0.0.1:46008","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-03-11T19:27:34.499302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:27:33.307515Z","time spent":"1.191595417s","remote":"127.0.0.1:46008","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":"warn","ts":"2026-03-11T19:27:34.499506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.400731019s","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:530"}
{"level":"warn","ts":"2026-03-11T19:27:34.498882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:27:33.175877Z","time spent":"1.322995277s","remote":"127.0.0.1:46008","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-03-11T19:27:34.499553Z","caller":"traceutil/trace.go:171","msg":"trace[968781660] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1016; }","duration":"1.40081074s","start":"2026-03-11T19:27:33.098729Z","end":"2026-03-11T19:27:34.49954Z","steps":["trace[968781660] 'agreement among raft nodes before linearized reading'  (duration: 1.400148618s)"],"step_count":1}
{"level":"warn","ts":"2026-03-11T19:27:34.499584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:27:33.09871Z","time spent":"1.400866952s","remote":"127.0.0.1:46008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "}
{"level":"warn","ts":"2026-03-11T19:27:34.498948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:27:34.080001Z","time spent":"418.941026ms","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.52\" "}
{"level":"info","ts":"2026-03-11T19:27:34.499138Z","caller":"traceutil/trace.go:171","msg":"trace[1638027235] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1016; }","duration":"1.200895731s","start":"2026-03-11T19:27:33.29822Z","end":"2026-03-11T19:27:34.499116Z","steps":["trace[1638027235] 'agreement among raft nodes before linearized reading'  (duration: 1.200691137s)"],"step_count":1}
{"level":"warn","ts":"2026-03-11T19:27:34.499693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T19:27:33.298209Z","time spent":"1.201476621s","remote":"127.0.0.1:46008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "}