{"level":"warn","ts":"2026-02-25T16:59:14.673642Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-02-25T16:59:14.673731Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.7: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.19.213.7:2380","--initial-cluster=instance=https://199.19.213.7:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.7:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.7: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-02-25T16:59:14.673797Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-02-25T16:59:14.673809Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.7:2380"]}
{"level":"info","ts":"2026-02-25T16:59:14.673823Z","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-02-25T16:59:14.674436Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.7:2379"]}
{"level":"info","ts":"2026-02-25T16:59:14.674509Z","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.19.213.7:2380"],"listen-peer-urls":["https://199.19.213.7:2380"],"advertise-client-urls":["https://199.19.213.7:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.7:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.7: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-02-25T16:59:14.683028Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.330785ms"}
{"level":"info","ts":"2026-02-25T16:59:14.692113Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4a9071808bffa6cd","cluster-id":"24a449649d146c95"}
{"level":"info","ts":"2026-02-25T16:59:14.692199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4a9071808bffa6cd switched to configuration voters=()"}
{"level":"info","ts":"2026-02-25T16:59:14.692223Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4a9071808bffa6cd became follower at term 0"}
{"level":"info","ts":"2026-02-25T16:59:14.692237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4a9071808bffa6cd [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-02-25T16:59:14.692254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4a9071808bffa6cd became follower at term 1"}
{"level":"info","ts":"2026-02-25T16:59:14.692297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4a9071808bffa6cd switched to configuration voters=(5372919152371541709)"}
{"level":"warn","ts":"2026-02-25T16:59:14.705334Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-02-25T16:59:14.708434Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-02-25T16:59:14.713981Z","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-02-25T16:59:14.721633Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4a9071808bffa6cd","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-02-25T16:59:14.72268Z","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-02-25T16:59:14.722758Z","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-02-25T16:59:14.722765Z","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-02-25T16:59:14.722618Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4a9071808bffa6cd","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-02-25T16:59:14.724939Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4a9071808bffa6cd switched to configuration voters=(5372919152371541709)"}
{"level":"info","ts":"2026-02-25T16:59:14.725466Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"24a449649d146c95","local-member-id":"4a9071808bffa6cd","added-peer-id":"4a9071808bffa6cd","added-peer-peer-urls":["https://199.19.213.7:2380"]}
{"level":"info","ts":"2026-02-25T16:59:14.72765Z","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-02-25T16:59:14.7278Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.7:2380"}
{"level":"info","ts":"2026-02-25T16:59:14.727866Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.7:2380"}
{"level":"info","ts":"2026-02-25T16:59:14.727969Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4a9071808bffa6cd","initial-advertise-peer-urls":["https://199.19.213.7:2380"],"listen-peer-urls":["https://199.19.213.7:2380"],"advertise-client-urls":["https://199.19.213.7:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.7:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-02-25T16:59:14.728031Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-02-25T16:59:15.493441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4a9071808bffa6cd is starting a new election at term 1"}
{"level":"info","ts":"2026-02-25T16:59:15.493513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4a9071808bffa6cd became pre-candidate at term 1"}
{"level":"info","ts":"2026-02-25T16:59:15.493553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4a9071808bffa6cd received MsgPreVoteResp from 4a9071808bffa6cd at term 1"}
{"level":"info","ts":"2026-02-25T16:59:15.49359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4a9071808bffa6cd became candidate at term 2"}
{"level":"info","ts":"2026-02-25T16:59:15.493607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4a9071808bffa6cd received MsgVoteResp from 4a9071808bffa6cd at term 2"}
{"level":"info","ts":"2026-02-25T16:59:15.493637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4a9071808bffa6cd became leader at term 2"}
{"level":"info","ts":"2026-02-25T16:59:15.49366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4a9071808bffa6cd elected leader 4a9071808bffa6cd at term 2"}
{"level":"info","ts":"2026-02-25T16:59:15.494924Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-02-25T16:59:15.496122Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-02-25T16:59:15.496124Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4a9071808bffa6cd","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.7:2379]}","request-path":"/0/members/4a9071808bffa6cd/attributes","cluster-id":"24a449649d146c95","publish-timeout":"7s"}
{"level":"info","ts":"2026-02-25T16:59:15.496195Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-02-25T16:59:15.496554Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-02-25T16:59:15.496611Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-02-25T16:59:15.496847Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"24a449649d146c95","local-member-id":"4a9071808bffa6cd","cluster-version":"3.5"}
{"level":"info","ts":"2026-02-25T16:59:15.497562Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-02-25T16:59:15.49764Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-02-25T16:59:15.49887Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.7:2379"}
{"level":"info","ts":"2026-02-25T16:59:15.499414Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-02-25T16:59:17.921267Z","caller":"traceutil/trace.go:171","msg":"trace[211600278] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:76; }","duration":"164.576165ms","start":"2026-02-25T16:59:17.756664Z","end":"2026-02-25T16:59:17.92124Z","steps":["trace[211600278] 'read index received'  (duration: 102.631015ms)","trace[211600278] 'applied index is now lower than readState.Index'  (duration: 61.94396ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-25T16:59:17.921492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.798454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"}
{"level":"info","ts":"2026-02-25T16:59:17.921519Z","caller":"traceutil/trace.go:171","msg":"trace[1420936029] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"165.829507ms","start":"2026-02-25T16:59:17.755527Z","end":"2026-02-25T16:59:17.921356Z","steps":["trace[1420936029] 'process raft request'  (duration: 103.751341ms)","trace[1420936029] 'compare'  (duration: 61.839896ms)"],"step_count":2}
{"level":"info","ts":"2026-02-25T16:59:17.921544Z","caller":"traceutil/trace.go:171","msg":"trace[295920232] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:73; }","duration":"164.887678ms","start":"2026-02-25T16:59:17.756646Z","end":"2026-02-25T16:59:17.921534Z","steps":["trace[295920232] 'agreement among raft nodes before linearized reading'  (duration: 164.747193ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:18.162115Z","caller":"traceutil/trace.go:171","msg":"trace[2088377609] transaction","detail":"{read_only:false; response_revision:75; number_of_response:1; }","duration":"221.065494ms","start":"2026-02-25T16:59:17.941026Z","end":"2026-02-25T16:59:18.162091Z","steps":["trace[2088377609] 'process raft request'  (duration: 218.432317ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:18.907752Z","caller":"traceutil/trace.go:171","msg":"trace[1966314491] transaction","detail":"{read_only:false; response_revision:133; number_of_response:1; }","duration":"157.543989ms","start":"2026-02-25T16:59:18.750187Z","end":"2026-02-25T16:59:18.907731Z","steps":["trace[1966314491] 'process raft request'  (duration: 157.388523ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:19.399684Z","caller":"traceutil/trace.go:171","msg":"trace[518594110] transaction","detail":"{read_only:false; response_revision:150; number_of_response:1; }","duration":"142.105534ms","start":"2026-02-25T16:59:19.257555Z","end":"2026-02-25T16:59:19.39966Z","steps":["trace[518594110] 'process raft request'  (duration: 142.019181ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:19.399779Z","caller":"traceutil/trace.go:171","msg":"trace[1139532020] linearizableReadLoop","detail":"{readStateIndex:154; appliedIndex:153; }","duration":"143.816412ms","start":"2026-02-25T16:59:19.255924Z","end":"2026-02-25T16:59:19.399741Z","steps":["trace[1139532020] 'read index received'  (duration: 101.624416ms)","trace[1139532020] 'applied index is now lower than readState.Index'  (duration: 42.186106ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-25T16:59:19.399936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.995099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:clusterrole-aggregation-controller\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-02-25T16:59:19.399984Z","caller":"traceutil/trace.go:171","msg":"trace[333858602] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:clusterrole-aggregation-controller; range_end:; response_count:0; response_revision:150; }","duration":"144.084032ms","start":"2026-02-25T16:59:19.255885Z","end":"2026-02-25T16:59:19.399969Z","steps":["trace[333858602] 'agreement among raft nodes before linearized reading'  (duration: 144.006249ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T16:59:20.030715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.025676ms","expected-duration":"100ms","prefix":"","request":"header:<ID:12019435147466212771 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/events/default/instance.18978bdba2b36094\" mod_revision:0 > success:<request_put:<key:\"/registry/events/default/instance.18978bdba2b36094\" value_size:557 lease:2796063110611436925 >> failure:<>>","response":"size:16"}
{"level":"info","ts":"2026-02-25T16:59:20.031018Z","caller":"traceutil/trace.go:171","msg":"trace[1813965741] linearizableReadLoop","detail":"{readStateIndex:175; appliedIndex:172; }","duration":"106.550651ms","start":"2026-02-25T16:59:19.924446Z","end":"2026-02-25T16:59:20.030997Z","steps":["trace[1813965741] 'read index received'  (duration: 23.87131ms)","trace[1813965741] 'applied index is now lower than readState.Index'  (duration: 82.677921ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-25T16:59:20.031164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.705026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-02-25T16:59:20.031207Z","caller":"traceutil/trace.go:171","msg":"trace[547395144] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:170; }","duration":"106.77585ms","start":"2026-02-25T16:59:19.924419Z","end":"2026-02-25T16:59:20.031195Z","steps":["trace[547395144] 'agreement among raft nodes before linearized reading'  (duration: 106.652205ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:20.03132Z","caller":"traceutil/trace.go:171","msg":"trace[709552798] transaction","detail":"{read_only:false; response_revision:168; number_of_response:1; }","duration":"232.899862ms","start":"2026-02-25T16:59:19.798407Z","end":"2026-02-25T16:59:20.031307Z","steps":["trace[709552798] 'process raft request'  (duration: 94.799802ms)","trace[709552798] 'compare'  (duration: 136.87221ms)"],"step_count":2}
{"level":"info","ts":"2026-02-25T16:59:20.031434Z","caller":"traceutil/trace.go:171","msg":"trace[1199076436] transaction","detail":"{read_only:false; response_revision:170; number_of_response:1; }","duration":"231.90064ms","start":"2026-02-25T16:59:19.79952Z","end":"2026-02-25T16:59:20.03142Z","steps":["trace[1199076436] 'process raft request'  (duration: 231.367798ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:20.031737Z","caller":"traceutil/trace.go:171","msg":"trace[390972228] transaction","detail":"{read_only:false; response_revision:169; number_of_response:1; }","duration":"232.638659ms","start":"2026-02-25T16:59:19.799086Z","end":"2026-02-25T16:59:20.031725Z","steps":["trace[390972228] 'process raft request'  (duration: 231.741613ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T16:59:21.408473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.920988ms","expected-duration":"100ms","prefix":"","request":"header:<ID:12019435147466212935 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/roles/kube-system/kubeadm:kubelet-config\" mod_revision:0 > success:<request_put:<key:\"/registry/roles/kube-system/kubeadm:kubelet-config\" value_size:272 >> failure:<>>","response":"size:16"}
{"level":"info","ts":"2026-02-25T16:59:21.408655Z","caller":"traceutil/trace.go:171","msg":"trace[2016212981] linearizableReadLoop","detail":"{readStateIndex:261; appliedIndex:260; }","duration":"188.042324ms","start":"2026-02-25T16:59:21.220581Z","end":"2026-02-25T16:59:21.408623Z","steps":["trace[2016212981] 'read index received'  (duration: 39.431µs)","trace[2016212981] 'applied index is now lower than readState.Index'  (duration: 188.001163ms)"],"step_count":2}
{"level":"info","ts":"2026-02-25T16:59:21.408768Z","caller":"traceutil/trace.go:171","msg":"trace[1759358244] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"247.26181ms","start":"2026-02-25T16:59:21.161462Z","end":"2026-02-25T16:59:21.408723Z","steps":["trace[1759358244] 'process raft request'  (duration: 42.967837ms)","trace[1759358244] 'compare'  (duration: 203.651018ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-25T16:59:21.408838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.242892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-02-25T16:59:21.408917Z","caller":"traceutil/trace.go:171","msg":"trace[556461281] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:0; response_revision:255; }","duration":"188.363207ms","start":"2026-02-25T16:59:21.220533Z","end":"2026-02-25T16:59:21.408896Z","steps":["trace[556461281] 'agreement among raft nodes before linearized reading'  (duration: 188.207741ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T16:59:22.337693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.237923ms","expected-duration":"100ms","prefix":"","request":"header:<ID:12019435147466212988 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/secrets/kube-system/bootstrap-token-gjoaif\" mod_revision:0 > success:<request_put:<key:\"/registry/secrets/kube-system/bootstrap-token-gjoaif\" value_size:465 >> failure:<>>","response":"size:16"}
{"level":"info","ts":"2026-02-25T16:59:22.337804Z","caller":"traceutil/trace.go:171","msg":"trace[988716776] linearizableReadLoop","detail":"{readStateIndex:279; appliedIndex:278; }","duration":"303.231189ms","start":"2026-02-25T16:59:22.034557Z","end":"2026-02-25T16:59:22.337788Z","steps":["trace[988716776] 'read index received'  (duration: 94.768177ms)","trace[988716776] 'applied index is now lower than readState.Index'  (duration: 208.462002ms)"],"step_count":2}
{"level":"info","ts":"2026-02-25T16:59:22.337889Z","caller":"traceutil/trace.go:171","msg":"trace[187003830] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"311.139356ms","start":"2026-02-25T16:59:22.026738Z","end":"2026-02-25T16:59:22.337877Z","steps":["trace[187003830] 'process raft request'  (duration: 102.652504ms)","trace[187003830] 'compare'  (duration: 208.051106ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-25T16:59:22.337943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:22.026725Z","time spent":"311.196508ms","remote":"127.0.0.1:58606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":525,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/secrets/kube-system/bootstrap-token-gjoaif\" mod_revision:0 > success:<request_put:<key:\"/registry/secrets/kube-system/bootstrap-token-gjoaif\" value_size:465 >> failure:<>"}
{"level":"warn","ts":"2026-02-25T16:59:22.338127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.557651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"}
{"level":"info","ts":"2026-02-25T16:59:22.338291Z","caller":"traceutil/trace.go:171","msg":"trace[764697826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:273; }","duration":"303.742839ms","start":"2026-02-25T16:59:22.034528Z","end":"2026-02-25T16:59:22.338271Z","steps":["trace[764697826] 'agreement among raft nodes before linearized reading'  (duration: 303.53009ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T16:59:22.338103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.084579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replication-controller\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-02-25T16:59:22.338366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:22.03451Z","time spent":"303.841423ms","remote":"127.0.0.1:58756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"info","ts":"2026-02-25T16:59:22.33837Z","caller":"traceutil/trace.go:171","msg":"trace[867252419] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replication-controller; range_end:; response_count:0; response_revision:273; }","duration":"273.430203ms","start":"2026-02-25T16:59:22.064924Z","end":"2026-02-25T16:59:22.338354Z","steps":["trace[867252419] 'agreement among raft nodes before linearized reading'  (duration: 273.026647ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T16:59:23.28165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.919097ms","expected-duration":"100ms","prefix":"","request":"header:<ID:12019435147466212994 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/secrets/kube-system/kubeadm-certs\" mod_revision:0 > success:<request_put:<key:\"/registry/secrets/kube-system/kubeadm-certs\" value_size:11385 >> failure:<>>","response":"size:16"}
{"level":"info","ts":"2026-02-25T16:59:23.281819Z","caller":"traceutil/trace.go:171","msg":"trace[2011105885] transaction","detail":"{read_only:false; response_revision:276; number_of_response:1; }","duration":"935.862078ms","start":"2026-02-25T16:59:22.345938Z","end":"2026-02-25T16:59:23.2818Z","steps":["trace[2011105885] 'process raft request'  (duration: 935.808386ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T16:59:23.281904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:22.345932Z","time spent":"935.946781ms","remote":"127.0.0.1:58700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":194,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/serviceaccounts/kube-system/replication-controller\" mod_revision:0 > success:<request_put:<key:\"/registry/serviceaccounts/kube-system/replication-controller\" value_size:127 >> failure:<>"}
{"level":"info","ts":"2026-02-25T16:59:23.281914Z","caller":"traceutil/trace.go:171","msg":"trace[1079999412] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"936.107697ms","start":"2026-02-25T16:59:22.345743Z","end":"2026-02-25T16:59:23.281851Z","steps":["trace[1079999412] 'process raft request'  (duration: 367.91824ms)","trace[1079999412] 'compare'  (duration: 567.772682ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-25T16:59:23.28214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:22.345728Z","time spent":"936.312626ms","remote":"127.0.0.1:58606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11436,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/secrets/kube-system/kubeadm-certs\" mod_revision:0 > success:<request_put:<key:\"/registry/secrets/kube-system/kubeadm-certs\" value_size:11385 >> failure:<>"}
{"level":"warn","ts":"2026-02-25T16:59:23.572969Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12019435147466212992,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-02-25T16:59:24.073926Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12019435147466212992,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-02-25T16:59:24.510014Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.79558748s","expected-duration":"1s"}
{"level":"warn","ts":"2026-02-25T16:59:24.701135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.738902ms","expected-duration":"100ms","prefix":"","request":"header:<ID:12019435147466212996 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/pods/kube-system/etcd-instance\" mod_revision:0 > success:<request_put:<key:\"/registry/pods/kube-system/etcd-instance\" value_size:3883 >> failure:<>>","response":"size:16"}
{"level":"info","ts":"2026-02-25T16:59:24.701465Z","caller":"traceutil/trace.go:171","msg":"trace[1947750195] linearizableReadLoop","detail":"{readStateIndex:284; appliedIndex:282; }","duration":"1.629443632s","start":"2026-02-25T16:59:23.071996Z","end":"2026-02-25T16:59:24.70144Z","steps":["trace[1947750195] 'read index received'  (duration: 1.438366976s)","trace[1947750195] 'applied index is now lower than readState.Index'  (duration: 191.074566ms)"],"step_count":2}
{"level":"info","ts":"2026-02-25T16:59:24.70151Z","caller":"traceutil/trace.go:171","msg":"trace[74454797] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"1.41249093s","start":"2026-02-25T16:59:23.28899Z","end":"2026-02-25T16:59:24.70148Z","steps":["trace[74454797] 'process raft request'  (duration: 1.412369595s)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:24.701482Z","caller":"traceutil/trace.go:171","msg":"trace[1292310736] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"2.097160485s","start":"2026-02-25T16:59:22.604278Z","end":"2026-02-25T16:59:24.701439Z","steps":["trace[1292310736] 'process raft request'  (duration: 1.906054558s)","trace[1292310736] 'compare'  (duration: 190.520394ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-25T16:59:24.701823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.159107469s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"}
{"level":"info","ts":"2026-02-25T16:59:24.701908Z","caller":"traceutil/trace.go:171","msg":"trace[840290288] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:278; }","duration":"1.159258554s","start":"2026-02-25T16:59:23.542634Z","end":"2026-02-25T16:59:24.701893Z","steps":["trace[840290288] 'agreement among raft nodes before linearized reading'  (duration: 1.159126579s)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T16:59:24.701973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:23.542601Z","time spent":"1.159359988s","remote":"127.0.0.1:58756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"warn","ts":"2026-02-25T16:59:24.701956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:22.604192Z","time spent":"2.097666764s","remote":"127.0.0.1:58670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3931,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/pods/kube-system/etcd-instance\" mod_revision:0 > success:<request_put:<key:\"/registry/pods/kube-system/etcd-instance\" value_size:3883 >> failure:<>"}
{"level":"warn","ts":"2026-02-25T16:59:24.70167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:23.288958Z","time spent":"1.412644875s","remote":"127.0.0.1:58808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":324,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/roles/kube-system/kubeadm:kubeadm-certs\" mod_revision:0 > success:<request_put:<key:\"/registry/roles/kube-system/kubeadm:kubeadm-certs\" value_size:267 >> failure:<>"}
{"level":"warn","ts":"2026-02-25T16:59:24.701732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.629730493s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"}
{"level":"info","ts":"2026-02-25T16:59:24.702263Z","caller":"traceutil/trace.go:171","msg":"trace[1974530382] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:278; }","duration":"1.630277344s","start":"2026-02-25T16:59:23.071964Z","end":"2026-02-25T16:59:24.702241Z","steps":["trace[1974530382] 'agreement among raft nodes before linearized reading'  (duration: 1.629673601s)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T16:59:24.702352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:23.071949Z","time spent":"1.630355737s","remote":"127.0.0.1:58756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"warn","ts":"2026-02-25T16:59:24.701751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.815871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5865"}
{"level":"info","ts":"2026-02-25T16:59:24.702503Z","caller":"traceutil/trace.go:171","msg":"trace[63521683] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:278; }","duration":"759.59749ms","start":"2026-02-25T16:59:23.94289Z","end":"2026-02-25T16:59:24.702488Z","steps":["trace[63521683] 'agreement among raft nodes before linearized reading'  (duration: 758.816061ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T16:59:24.702553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:23.942862Z","time spent":"759.679003ms","remote":"127.0.0.1:58670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":5887,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "}
{"level":"warn","ts":"2026-02-25T16:59:24.701796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.227887727s","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-02-25T16:59:24.702697Z","caller":"traceutil/trace.go:171","msg":"trace[513562657] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:278; }","duration":"1.228818502s","start":"2026-02-25T16:59:23.473846Z","end":"2026-02-25T16:59:24.702665Z","steps":["trace[513562657] 'agreement among raft nodes before linearized reading'  (duration: 1.227926839s)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T16:59:24.702789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:23.473815Z","time spent":"1.228947538s","remote":"127.0.0.1:58756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-02-25T16:59:24.701782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.412702848s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pod-garbage-collector\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-02-25T16:59:24.702862Z","caller":"traceutil/trace.go:171","msg":"trace[829231820] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pod-garbage-collector; range_end:; response_count:0; response_revision:278; }","duration":"1.413839061s","start":"2026-02-25T16:59:23.289003Z","end":"2026-02-25T16:59:24.702842Z","steps":["trace[829231820] 'agreement among raft nodes before linearized reading'  (duration: 1.412701918s)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T16:59:24.701811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.471329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"}
{"level":"warn","ts":"2026-02-25T16:59:24.702991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:23.288977Z","time spent":"1.413962935s","remote":"127.0.0.1:58700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/kube-system/pod-garbage-collector\" "}
{"level":"info","ts":"2026-02-25T16:59:24.703056Z","caller":"traceutil/trace.go:171","msg":"trace[333327077] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:278; }","duration":"265.735066ms","start":"2026-02-25T16:59:24.43728Z","end":"2026-02-25T16:59:24.703015Z","steps":["trace[333327077] 'agreement among raft nodes before linearized reading'  (duration: 264.462778ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:25.075311Z","caller":"traceutil/trace.go:171","msg":"trace[602340733] linearizableReadLoop","detail":"{readStateIndex:296; appliedIndex:295; }","duration":"118.951118ms","start":"2026-02-25T16:59:24.956336Z","end":"2026-02-25T16:59:25.075287Z","steps":["trace[602340733] 'read index received'  (duration: 58.17796ms)","trace[602340733] 'applied index is now lower than readState.Index'  (duration: 60.772208ms)"],"step_count":2}
{"level":"info","ts":"2026-02-25T16:59:25.075422Z","caller":"traceutil/trace.go:171","msg":"trace[742554108] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"123.016543ms","start":"2026-02-25T16:59:24.95237Z","end":"2026-02-25T16:59:25.075387Z","steps":["trace[742554108] 'process raft request'  (duration: 62.150601ms)","trace[742554108] 'compare'  (duration: 60.659285ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-25T16:59:25.075474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.069843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-02-25T16:59:25.075649Z","caller":"traceutil/trace.go:171","msg":"trace[458145314] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:0; response_revision:290; }","duration":"119.198857ms","start":"2026-02-25T16:59:24.9563Z","end":"2026-02-25T16:59:25.075499Z","steps":["trace[458145314] 'agreement among raft nodes before linearized reading'  (duration: 119.075993ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:25.349386Z","caller":"traceutil/trace.go:171","msg":"trace[1344331086] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"238.211209ms","start":"2026-02-25T16:59:25.111141Z","end":"2026-02-25T16:59:25.349352Z","steps":["trace[1344331086] 'process raft request'  (duration: 156.32974ms)","trace[1344331086] 'compare'  (duration: 81.64741ms)"],"step_count":2}
{"level":"info","ts":"2026-02-25T16:59:25.473206Z","caller":"traceutil/trace.go:171","msg":"trace[286187574] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"114.764521ms","start":"2026-02-25T16:59:25.358401Z","end":"2026-02-25T16:59:25.473165Z","steps":["trace[286187574] 'process raft request'  (duration: 114.699179ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:25.473246Z","caller":"traceutil/trace.go:171","msg":"trace[105838512] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"114.978289ms","start":"2026-02-25T16:59:25.358236Z","end":"2026-02-25T16:59:25.473215Z","steps":["trace[105838512] 'process raft request'  (duration: 91.101975ms)","trace[105838512] 'compare'  (duration: 23.608974ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-25T16:59:25.795655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.250664ms","expected-duration":"100ms","prefix":"","request":"header:<ID:12019435147466213042 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/clusterrolebindings/kubeadm:get-nodes\" mod_revision:0 > success:<request_put:<key:\"/registry/clusterrolebindings/kubeadm:get-nodes\" value_size:399 >> failure:<>>","response":"size:16"}
{"level":"info","ts":"2026-02-25T16:59:25.795786Z","caller":"traceutil/trace.go:171","msg":"trace[1746656672] transaction","detail":"{read_only:false; response_revision:299; number_of_response:1; }","duration":"243.331611ms","start":"2026-02-25T16:59:25.552443Z","end":"2026-02-25T16:59:25.795774Z","steps":["trace[1746656672] 'process raft request'  (duration: 243.29565ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:25.795817Z","caller":"traceutil/trace.go:171","msg":"trace[982091705] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"244.955191ms","start":"2026-02-25T16:59:25.550826Z","end":"2026-02-25T16:59:25.795781Z","steps":["trace[982091705] 'process raft request'  (duration: 55.514721ms)","trace[982091705] 'compare'  (duration: 189.097768ms)"],"step_count":2}
{"level":"info","ts":"2026-02-25T16:59:26.106088Z","caller":"traceutil/trace.go:171","msg":"trace[878646386] linearizableReadLoop","detail":"{readStateIndex:311; appliedIndex:310; }","duration":"192.443599ms","start":"2026-02-25T16:59:25.913624Z","end":"2026-02-25T16:59:26.106068Z","steps":["trace[878646386] 'read index received'  (duration: 164.788304ms)","trace[878646386] 'applied index is now lower than readState.Index'  (duration: 27.654415ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-25T16:59:26.106215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.584364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:1 size:205"}
{"level":"info","ts":"2026-02-25T16:59:26.106237Z","caller":"traceutil/trace.go:171","msg":"trace[1743491827] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:1; response_revision:305; }","duration":"192.647846ms","start":"2026-02-25T16:59:25.913584Z","end":"2026-02-25T16:59:26.106232Z","steps":["trace[1743491827] 'agreement among raft nodes before linearized reading'  (duration: 192.551623ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:26.106125Z","caller":"traceutil/trace.go:171","msg":"trace[769606623] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"194.283007ms","start":"2026-02-25T16:59:25.911804Z","end":"2026-02-25T16:59:26.106087Z","steps":["trace[769606623] 'process raft request'  (duration: 166.712395ms)","trace[769606623] 'compare'  (duration: 27.341864ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-25T16:59:26.355721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.706562ms","expected-duration":"100ms","prefix":"","request":"header:<ID:12019435147466213056 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/roles/kube-public/kubeadm:bootstrap-signer-clusterinfo\" mod_revision:0 > success:<request_put:<key:\"/registry/roles/kube-public/kubeadm:bootstrap-signer-clusterinfo\" value_size:284 >> failure:<>>","response":"size:16"}
{"level":"info","ts":"2026-02-25T16:59:26.355921Z","caller":"traceutil/trace.go:171","msg":"trace[1477913158] linearizableReadLoop","detail":"{readStateIndex:312; appliedIndex:311; }","duration":"238.447196ms","start":"2026-02-25T16:59:26.117397Z","end":"2026-02-25T16:59:26.355844Z","steps":["trace[1477913158] 'read index received'  (duration: 93.448253ms)","trace[1477913158] 'applied index is now lower than readState.Index'  (duration: 144.996723ms)"],"step_count":2}
{"level":"info","ts":"2026-02-25T16:59:26.356997Z","caller":"traceutil/trace.go:171","msg":"trace[617926255] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"247.248312ms","start":"2026-02-25T16:59:26.109495Z","end":"2026-02-25T16:59:26.356744Z","steps":["trace[617926255] 'process raft request'  (duration: 101.435939ms)","trace[617926255] 'compare'  (duration: 143.418715ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-25T16:59:26.357278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.844078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-02-25T16:59:26.357348Z","caller":"traceutil/trace.go:171","msg":"trace[554361792] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:0; response_revision:306; }","duration":"239.966483ms","start":"2026-02-25T16:59:26.11736Z","end":"2026-02-25T16:59:26.357327Z","steps":["trace[554361792] 'agreement among raft nodes before linearized reading'  (duration: 238.608832ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:26.568909Z","caller":"traceutil/trace.go:171","msg":"trace[2048238405] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"205.153532ms","start":"2026-02-25T16:59:26.363734Z","end":"2026-02-25T16:59:26.568888Z","steps":["trace[2048238405] 'process raft request'  (duration: 205.117411ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:26.568954Z","caller":"traceutil/trace.go:171","msg":"trace[1436011318] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"207.236389ms","start":"2026-02-25T16:59:26.361681Z","end":"2026-02-25T16:59:26.568917Z","steps":["trace[1436011318] 'process raft request'  (duration: 129.394635ms)","trace[1436011318] 'compare'  (duration: 77.647677ms)"],"step_count":2}
2026/02/25 16:59:26 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing"
{"level":"warn","ts":"2026-02-25T16:59:26.954693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.429143ms","expected-duration":"100ms","prefix":"","request":"header:<ID:12019435147466213063 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/serviceaccounts/kube-system/cronjob-controller\" mod_revision:0 > success:<request_put:<key:\"/registry/serviceaccounts/kube-system/cronjob-controller\" value_size:123 >> failure:<>>","response":"size:16"}
{"level":"info","ts":"2026-02-25T16:59:26.954839Z","caller":"traceutil/trace.go:171","msg":"trace[219657571] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"375.057918ms","start":"2026-02-25T16:59:26.579749Z","end":"2026-02-25T16:59:26.954807Z","steps":["trace[219657571] 'process raft request'  (duration: 159.443398ms)","trace[219657571] 'compare'  (duration: 214.900823ms)"],"step_count":2}
{"level":"info","ts":"2026-02-25T16:59:26.954914Z","caller":"traceutil/trace.go:171","msg":"trace[1738446337] linearizableReadLoop","detail":"{readStateIndex:315; appliedIndex:314; }","duration":"218.642071ms","start":"2026-02-25T16:59:26.73624Z","end":"2026-02-25T16:59:26.954882Z","steps":["trace[1738446337] 'read index received'  (duration: 2.962519ms)","trace[1738446337] 'applied index is now lower than readState.Index'  (duration: 215.676332ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-25T16:59:26.954992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:26.579728Z","time spent":"375.183552ms","remote":"127.0.0.1:58700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":186,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/serviceaccounts/kube-system/cronjob-controller\" mod_revision:0 > success:<request_put:<key:\"/registry/serviceaccounts/kube-system/cronjob-controller\" value_size:123 >> failure:<>"}
{"level":"warn","ts":"2026-02-25T16:59:26.955079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.786515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"}
{"level":"info","ts":"2026-02-25T16:59:26.955107Z","caller":"traceutil/trace.go:171","msg":"trace[1791206764] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:310; }","duration":"205.850257ms","start":"2026-02-25T16:59:26.749246Z","end":"2026-02-25T16:59:26.955097Z","steps":["trace[1791206764] 'agreement among raft nodes before linearized reading'  (duration: 205.745763ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T16:59:26.955099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.288635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-02-25T16:59:26.955133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.222044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"}
{"level":"info","ts":"2026-02-25T16:59:26.955183Z","caller":"traceutil/trace.go:171","msg":"trace[1084406779] range","detail":"{range_begin:/registry/deployments/kube-system/; range_end:/registry/deployments/kube-system0; response_count:0; response_revision:310; }","duration":"114.437851ms","start":"2026-02-25T16:59:26.840723Z","end":"2026-02-25T16:59:26.95516Z","steps":["trace[1084406779] 'agreement among raft nodes before linearized reading'  (duration: 114.284135ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:26.955199Z","caller":"traceutil/trace.go:171","msg":"trace[7423676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:310; }","duration":"205.304957ms","start":"2026-02-25T16:59:26.749875Z","end":"2026-02-25T16:59:26.95518Z","steps":["trace[7423676] 'agreement among raft nodes before linearized reading'  (duration: 205.151362ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T16:59:26.955226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.974854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"}
{"level":"warn","ts":"2026-02-25T16:59:26.955307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.468663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"}
{"level":"info","ts":"2026-02-25T16:59:26.95531Z","caller":"traceutil/trace.go:171","msg":"trace[669658129] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:310; }","duration":"219.104648ms","start":"2026-02-25T16:59:26.736188Z","end":"2026-02-25T16:59:26.955293Z","steps":["trace[669658129] 'agreement among raft nodes before linearized reading'  (duration: 218.775726ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:26.955345Z","caller":"traceutil/trace.go:171","msg":"trace[419069032] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:310; }","duration":"205.555956ms","start":"2026-02-25T16:59:26.749777Z","end":"2026-02-25T16:59:26.955333Z","steps":["trace[419069032] 'agreement among raft nodes before linearized reading'  (duration: 205.290766ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:27.161144Z","caller":"traceutil/trace.go:171","msg":"trace[1279347142] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"202.03088ms","start":"2026-02-25T16:59:26.959077Z","end":"2026-02-25T16:59:27.161108Z","steps":["trace[1279347142] 'process raft request'  (duration: 174.627126ms)","trace[1279347142] 'compare'  (duration: 27.221397ms)"],"step_count":2}
{"level":"info","ts":"2026-02-25T16:59:27.16543Z","caller":"traceutil/trace.go:171","msg":"trace[1132458812] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"206.275665ms","start":"2026-02-25T16:59:26.959135Z","end":"2026-02-25T16:59:27.16541Z","steps":["trace[1132458812] 'process raft request'  (duration: 206.195332ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:27.165451Z","caller":"traceutil/trace.go:171","msg":"trace[413296754] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"206.295356ms","start":"2026-02-25T16:59:26.959135Z","end":"2026-02-25T16:59:27.16543Z","steps":["trace[413296754] 'process raft request'  (duration: 206.111869ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:27.165719Z","caller":"traceutil/trace.go:171","msg":"trace[559742920] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"206.281994ms","start":"2026-02-25T16:59:26.959421Z","end":"2026-02-25T16:59:27.165703Z","steps":["trace[559742920] 'process raft request'  (duration: 205.948102ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:27.165761Z","caller":"traceutil/trace.go:171","msg":"trace[1915328714] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"205.61162ms","start":"2026-02-25T16:59:26.960106Z","end":"2026-02-25T16:59:27.165718Z","steps":["trace[1915328714] 'process raft request'  (duration: 205.458974ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:27.165874Z","caller":"traceutil/trace.go:171","msg":"trace[752578655] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"204.26347ms","start":"2026-02-25T16:59:26.961597Z","end":"2026-02-25T16:59:27.16586Z","steps":["trace[752578655] 'process raft request'  (duration: 204.082144ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:27.165989Z","caller":"traceutil/trace.go:171","msg":"trace[347514899] transaction","detail":"{read_only:false; number_of_response:0; response_revision:317; }","duration":"121.685327ms","start":"2026-02-25T16:59:27.044293Z","end":"2026-02-25T16:59:27.165978Z","steps":["trace[347514899] 'process raft request'  (duration: 121.573773ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:27.166034Z","caller":"traceutil/trace.go:171","msg":"trace[1314643132] transaction","detail":"{read_only:false; number_of_response:0; response_revision:317; }","duration":"121.411387ms","start":"2026-02-25T16:59:27.044604Z","end":"2026-02-25T16:59:27.166015Z","steps":["trace[1314643132] 'process raft request'  (duration: 121.299683ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:27.166053Z","caller":"traceutil/trace.go:171","msg":"trace[757858912] linearizableReadLoop","detail":"{readStateIndex:321; appliedIndex:316; }","duration":"204.905214ms","start":"2026-02-25T16:59:26.961133Z","end":"2026-02-25T16:59:27.166038Z","steps":["trace[757858912] 'read index received'  (duration: 172.540279ms)","trace[757858912] 'applied index is now lower than readState.Index'  (duration: 32.363905ms)"],"step_count":2}
{"level":"info","ts":"2026-02-25T16:59:27.166206Z","caller":"traceutil/trace.go:171","msg":"trace[1702626753] transaction","detail":"{read_only:false; number_of_response:0; response_revision:317; }","duration":"120.212722ms","start":"2026-02-25T16:59:27.045982Z","end":"2026-02-25T16:59:27.166195Z","steps":["trace[1702626753] 'process raft request'  (duration: 120.004125ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T16:59:27.166161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.005757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"}
{"level":"info","ts":"2026-02-25T16:59:27.166283Z","caller":"traceutil/trace.go:171","msg":"trace[367062399] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:317; }","duration":"205.160053ms","start":"2026-02-25T16:59:26.961106Z","end":"2026-02-25T16:59:27.166266Z","steps":["trace[367062399] 'agreement among raft nodes before linearized reading'  (duration: 204.978066ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:27.166368Z","caller":"traceutil/trace.go:171","msg":"trace[23288749] transaction","detail":"{read_only:false; number_of_response:0; response_revision:317; }","duration":"121.459369ms","start":"2026-02-25T16:59:27.044777Z","end":"2026-02-25T16:59:27.166237Z","steps":["trace[23288749] 'process raft request'  (duration: 121.152908ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:27.166199Z","caller":"traceutil/trace.go:171","msg":"trace[2048915675] transaction","detail":"{read_only:false; number_of_response:0; response_revision:317; }","duration":"121.395967ms","start":"2026-02-25T16:59:27.044787Z","end":"2026-02-25T16:59:27.166183Z","steps":["trace[2048915675] 'process raft request'  (duration: 121.174109ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T16:59:27.166599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.843655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/coredns\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-02-25T16:59:27.166684Z","caller":"traceutil/trace.go:171","msg":"trace[284119387] range","detail":"{range_begin:/registry/configmaps/kube-system/coredns; range_end:; response_count:0; response_revision:317; }","duration":"203.976049ms","start":"2026-02-25T16:59:26.962692Z","end":"2026-02-25T16:59:27.166668Z","steps":["trace[284119387] 'agreement among raft nodes before linearized reading'  (duration: 203.848745ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:27.166294Z","caller":"traceutil/trace.go:171","msg":"trace[1433045864] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"143.463525ms","start":"2026-02-25T16:59:27.022815Z","end":"2026-02-25T16:59:27.166278Z","steps":["trace[1433045864] 'process raft request'  (duration: 142.988308ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:27.293273Z","caller":"traceutil/trace.go:171","msg":"trace[1328602642] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"103.820183ms","start":"2026-02-25T16:59:27.189426Z","end":"2026-02-25T16:59:27.293247Z","steps":["trace[1328602642] 'process raft request'  (duration: 91.73933ms)","trace[1328602642] 'compare'  (duration: 11.919467ms)"],"step_count":2}
{"level":"info","ts":"2026-02-25T16:59:27.752076Z","caller":"traceutil/trace.go:171","msg":"trace[875902267] linearizableReadLoop","detail":"{readStateIndex:364; appliedIndex:363; }","duration":"105.110961ms","start":"2026-02-25T16:59:27.646934Z","end":"2026-02-25T16:59:27.752045Z","steps":["trace[875902267] 'read index received'  (duration: 5.553114ms)","trace[875902267] 'applied index is now lower than readState.Index'  (duration: 99.557127ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-25T16:59:27.752424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.437343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"}
{"level":"info","ts":"2026-02-25T16:59:27.75258Z","caller":"traceutil/trace.go:171","msg":"trace[1989131325] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:352; }","duration":"105.674231ms","start":"2026-02-25T16:59:27.646895Z","end":"2026-02-25T16:59:27.752569Z","steps":["trace[1989131325] 'agreement among raft nodes before linearized reading'  (duration: 105.36362ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:27.752482Z","caller":"traceutil/trace.go:171","msg":"trace[1901691074] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"153.673109ms","start":"2026-02-25T16:59:27.598729Z","end":"2026-02-25T16:59:27.752402Z","steps":["trace[1901691074] 'process raft request'  (duration: 53.837392ms)","trace[1901691074] 'compare'  (duration: 99.391521ms)"],"step_count":2}
{"level":"info","ts":"2026-02-25T16:59:28.112119Z","caller":"traceutil/trace.go:171","msg":"trace[1758394793] linearizableReadLoop","detail":"{readStateIndex:381; appliedIndex:380; }","duration":"114.748827ms","start":"2026-02-25T16:59:27.997346Z","end":"2026-02-25T16:59:28.112094Z","steps":["trace[1758394793] 'read index received'  (duration: 114.698936ms)","trace[1758394793] 'applied index is now lower than readState.Index'  (duration: 48.631µs)"],"step_count":2}
{"level":"info","ts":"2026-02-25T16:59:28.112227Z","caller":"traceutil/trace.go:171","msg":"trace[1793890805] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"130.740604ms","start":"2026-02-25T16:59:27.98145Z","end":"2026-02-25T16:59:28.11219Z","steps":["trace[1793890805] 'process raft request'  (duration: 130.531726ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T16:59:28.112459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.100311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"}
{"level":"info","ts":"2026-02-25T16:59:28.112507Z","caller":"traceutil/trace.go:171","msg":"trace[1243657520] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:369; }","duration":"115.185473ms","start":"2026-02-25T16:59:27.997298Z","end":"2026-02-25T16:59:28.112483Z","steps":["trace[1243657520] 'agreement among raft nodes before linearized reading'  (duration: 114.917904ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:28.268884Z","caller":"traceutil/trace.go:171","msg":"trace[1503401649] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"148.131405ms","start":"2026-02-25T16:59:28.12073Z","end":"2026-02-25T16:59:28.268861Z","steps":["trace[1503401649] 'process raft request'  (duration: 75.801495ms)","trace[1503401649] 'compare'  (duration: 72.207186ms)"],"step_count":2}
{"level":"info","ts":"2026-02-25T16:59:28.269536Z","caller":"traceutil/trace.go:171","msg":"trace[178323719] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"147.838883ms","start":"2026-02-25T16:59:28.121687Z","end":"2026-02-25T16:59:28.269525Z","steps":["trace[178323719] 'process raft request'  (duration: 147.815922ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:28.26961Z","caller":"traceutil/trace.go:171","msg":"trace[477288708] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"148.251519ms","start":"2026-02-25T16:59:28.121337Z","end":"2026-02-25T16:59:28.269589Z","steps":["trace[477288708] 'process raft request'  (duration: 148.086063ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:28.269686Z","caller":"traceutil/trace.go:171","msg":"trace[1639343402] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"148.010439ms","start":"2026-02-25T16:59:28.121669Z","end":"2026-02-25T16:59:28.26968Z","steps":["trace[1639343402] 'process raft request'  (duration: 147.805142ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T16:59:29.101392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.26293ms","expected-duration":"100ms","prefix":"","request":"header:<ID:12019435147466213210 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/pods/kube-system/etcd-instance\" mod_revision:368 > success:<request_put:<key:\"/registry/pods/kube-system/etcd-instance\" value_size:5187 >> failure:<request_range:<key:\"/registry/pods/kube-system/etcd-instance\" > >>","response":"size:16"}
{"level":"info","ts":"2026-02-25T16:59:29.101477Z","caller":"traceutil/trace.go:171","msg":"trace[378636675] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"648.814519ms","start":"2026-02-25T16:59:28.452651Z","end":"2026-02-25T16:59:29.101466Z","steps":["trace[378636675] 'process raft request'  (duration: 381.346611ms)","trace[378636675] 'compare'  (duration: 267.180387ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-25T16:59:29.10151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:28.452634Z","time spent":"648.862021ms","remote":"127.0.0.1:58670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5235,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/pods/kube-system/etcd-instance\" mod_revision:368 > success:<request_put:<key:\"/registry/pods/kube-system/etcd-instance\" value_size:5187 >> failure:<request_range:<key:\"/registry/pods/kube-system/etcd-instance\" > >"}
{"level":"warn","ts":"2026-02-25T16:59:29.491122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.376832ms","expected-duration":"100ms","prefix":"","request":"header:<ID:12019435147466213212 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/pods/kube-system/kube-scheduler-instance\" mod_revision:370 > success:<request_put:<key:\"/registry/pods/kube-system/kube-scheduler-instance\" value_size:4179 >> failure:<request_range:<key:\"/registry/pods/kube-system/kube-scheduler-instance\" > >>","response":"size:16"}
{"level":"info","ts":"2026-02-25T16:59:29.491222Z","caller":"traceutil/trace.go:171","msg":"trace[943587286] linearizableReadLoop","detail":"{readStateIndex:405; appliedIndex:404; }","duration":"326.416388ms","start":"2026-02-25T16:59:29.164789Z","end":"2026-02-25T16:59:29.491206Z","steps":["trace[943587286] 'read index received'  (duration: 184.818498ms)","trace[943587286] 'applied index is now lower than readState.Index'  (duration: 141.59682ms)"],"step_count":2}
{"level":"info","ts":"2026-02-25T16:59:29.491314Z","caller":"traceutil/trace.go:171","msg":"trace[1187102175] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"366.589525ms","start":"2026-02-25T16:59:29.124714Z","end":"2026-02-25T16:59:29.491304Z","steps":["trace[1187102175] 'process raft request'  (duration: 224.953064ms)","trace[1187102175] 'compare'  (duration: 141.251838ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-25T16:59:29.49138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:29.124695Z","time spent":"366.653938ms","remote":"127.0.0.1:58670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4237,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/pods/kube-system/kube-scheduler-instance\" mod_revision:370 > success:<request_put:<key:\"/registry/pods/kube-system/kube-scheduler-instance\" value_size:4179 >> failure:<request_range:<key:\"/registry/pods/kube-system/kube-scheduler-instance\" > >"}
{"level":"warn","ts":"2026-02-25T16:59:29.491522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.738849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"}
{"level":"info","ts":"2026-02-25T16:59:29.491551Z","caller":"traceutil/trace.go:171","msg":"trace[600407735] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:393; }","duration":"326.800832ms","start":"2026-02-25T16:59:29.164741Z","end":"2026-02-25T16:59:29.491541Z","steps":["trace[600407735] 'agreement among raft nodes before linearized reading'  (duration: 326.74362ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T16:59:29.491579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:29.164719Z","time spent":"326.854314ms","remote":"127.0.0.1:58756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-02-25T16:59:29.491862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.735671ms","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-02-25T16:59:29.491946Z","caller":"traceutil/trace.go:171","msg":"trace[1212374970] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:393; }","duration":"322.048071ms","start":"2026-02-25T16:59:29.169881Z","end":"2026-02-25T16:59:29.491929Z","steps":["trace[1212374970] 'agreement among raft nodes before linearized reading'  (duration: 321.698879ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T16:59:29.491961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.674945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"}
{"level":"info","ts":"2026-02-25T16:59:29.492182Z","caller":"traceutil/trace.go:171","msg":"trace[491423925] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:393; }","duration":"317.916204ms","start":"2026-02-25T16:59:29.174257Z","end":"2026-02-25T16:59:29.492173Z","steps":["trace[491423925] 'agreement among raft nodes before linearized reading'  (duration: 317.668385ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T16:59:29.492219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:29.174243Z","time spent":"317.970217ms","remote":"127.0.0.1:58756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"warn","ts":"2026-02-25T16:59:29.491979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:29.169862Z","time spent":"322.109894ms","remote":"127.0.0.1:58756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-02-25T16:59:29.49241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.465021ms","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-02-25T16:59:29.492439Z","caller":"traceutil/trace.go:171","msg":"trace[1069880708] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:393; }","duration":"321.540723ms","start":"2026-02-25T16:59:29.170892Z","end":"2026-02-25T16:59:29.492433Z","steps":["trace[1069880708] 'agreement among raft nodes before linearized reading'  (duration: 321.102598ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T16:59:29.492482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:29.170867Z","time spent":"321.609056ms","remote":"127.0.0.1:58756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"info","ts":"2026-02-25T16:59:29.642735Z","caller":"traceutil/trace.go:171","msg":"trace[1977867675] linearizableReadLoop","detail":"{readStateIndex:406; appliedIndex:405; }","duration":"145.324323ms","start":"2026-02-25T16:59:29.497384Z","end":"2026-02-25T16:59:29.642708Z","steps":["trace[1977867675] 'read index received'  (duration: 144.285456ms)","trace[1977867675] 'applied index is now lower than readState.Index'  (duration: 1.037547ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-25T16:59:29.642959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.527011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6680"}
{"level":"info","ts":"2026-02-25T16:59:29.643003Z","caller":"traceutil/trace.go:171","msg":"trace[133354042] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:394; }","duration":"145.644375ms","start":"2026-02-25T16:59:29.497348Z","end":"2026-02-25T16:59:29.642992Z","steps":["trace[133354042] 'agreement among raft nodes before linearized reading'  (duration: 145.492079ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:29.643018Z","caller":"traceutil/trace.go:171","msg":"trace[1623343494] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"146.571229ms","start":"2026-02-25T16:59:29.496422Z","end":"2026-02-25T16:59:29.642993Z","steps":["trace[1623343494] 'process raft request'  (duration: 145.247241ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:29.65222Z","caller":"traceutil/trace.go:171","msg":"trace[726637224] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"154.7225ms","start":"2026-02-25T16:59:29.497476Z","end":"2026-02-25T16:59:29.652198Z","steps":["trace[726637224] 'process raft request'  (duration: 154.576035ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:29.65241Z","caller":"traceutil/trace.go:171","msg":"trace[2112566178] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"154.180531ms","start":"2026-02-25T16:59:29.498212Z","end":"2026-02-25T16:59:29.652392Z","steps":["trace[2112566178] 'process raft request'  (duration: 154.097628ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:29.652412Z","caller":"traceutil/trace.go:171","msg":"trace[427358339] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"154.477461ms","start":"2026-02-25T16:59:29.497919Z","end":"2026-02-25T16:59:29.652396Z","steps":["trace[427358339] 'process raft request'  (duration: 154.241783ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T16:59:30.15956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.743457ms","expected-duration":"100ms","prefix":"","request":"header:<ID:12019435147466213222 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/pods/kube-system/kube-apiserver-instance\" mod_revision:391 > success:<request_put:<key:\"/registry/pods/kube-system/kube-apiserver-instance\" value_size:7146 >> failure:<request_range:<key:\"/registry/pods/kube-system/kube-apiserver-instance\" > >>","response":"size:16"}
{"level":"info","ts":"2026-02-25T16:59:30.159732Z","caller":"traceutil/trace.go:171","msg":"trace[1744365831] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"475.497566ms","start":"2026-02-25T16:59:29.684199Z","end":"2026-02-25T16:59:30.159697Z","steps":["trace[1744365831] 'process raft request'  (duration: 124.552681ms)","trace[1744365831] 'compare'  (duration: 350.651104ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-25T16:59:30.160117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:29.684178Z","time spent":"475.913999ms","remote":"127.0.0.1:58670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7204,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/pods/kube-system/kube-apiserver-instance\" mod_revision:391 > success:<request_put:<key:\"/registry/pods/kube-system/kube-apiserver-instance\" value_size:7146 >> failure:<request_range:<key:\"/registry/pods/kube-system/kube-apiserver-instance\" > >"}
{"level":"info","ts":"2026-02-25T16:59:30.896736Z","caller":"traceutil/trace.go:171","msg":"trace[2146474194] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"191.53104ms","start":"2026-02-25T16:59:30.705172Z","end":"2026-02-25T16:59:30.896703Z","steps":["trace[2146474194] 'process raft request'  (duration: 164.986111ms)","trace[2146474194] 'compare'  (duration: 26.355073ms)"],"step_count":2}
{"level":"info","ts":"2026-02-25T16:59:30.896767Z","caller":"traceutil/trace.go:171","msg":"trace[1257518877] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"122.02582ms","start":"2026-02-25T16:59:30.774718Z","end":"2026-02-25T16:59:30.896744Z","steps":["trace[1257518877] 'process raft request'  (duration: 121.977108ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T16:59:30.897326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.723725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.7\" ","response":"range_response_count:1 size:131"}
{"level":"info","ts":"2026-02-25T16:59:30.897448Z","caller":"traceutil/trace.go:171","msg":"trace[144341965] range","detail":"{range_begin:/registry/masterleases/199.19.213.7; range_end:; response_count:1; response_revision:404; }","duration":"170.90389ms","start":"2026-02-25T16:59:30.726535Z","end":"2026-02-25T16:59:30.897439Z","steps":["trace[144341965] 'agreement among raft nodes before linearized reading'  (duration: 170.677482ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:30.897659Z","caller":"traceutil/trace.go:171","msg":"trace[1411970296] linearizableReadLoop","detail":"{readStateIndex:416; appliedIndex:414; }","duration":"170.130604ms","start":"2026-02-25T16:59:30.726609Z","end":"2026-02-25T16:59:30.89674Z","steps":["trace[1411970296] 'read index received'  (duration: 46.700034ms)","trace[1411970296] 'applied index is now lower than readState.Index'  (duration: 123.42796ms)"],"step_count":2}
{"level":"info","ts":"2026-02-25T16:59:31.249177Z","caller":"traceutil/trace.go:171","msg":"trace[871063667] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"263.680558ms","start":"2026-02-25T16:59:30.985478Z","end":"2026-02-25T16:59:31.249158Z","steps":["trace[871063667] 'process raft request'  (duration: 263.564354ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:31.485144Z","caller":"traceutil/trace.go:171","msg":"trace[2131267522] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"229.208095ms","start":"2026-02-25T16:59:31.255899Z","end":"2026-02-25T16:59:31.485107Z","steps":["trace[2131267522] 'process raft request'  (duration: 158.186888ms)","trace[2131267522] 'compare'  (duration: 70.891082ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-25T16:59:31.756889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.724126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"}
{"level":"warn","ts":"2026-02-25T16:59:31.756963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.930734ms","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-02-25T16:59:31.756973Z","caller":"traceutil/trace.go:171","msg":"trace[233802994] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:407; }","duration":"100.826951ms","start":"2026-02-25T16:59:31.656126Z","end":"2026-02-25T16:59:31.756953Z","steps":["trace[233802994] 'range keys from in-memory index tree'  (duration: 100.663355ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T16:59:31.757004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.949955ms","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-02-25T16:59:31.756918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.939574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"}
{"level":"info","ts":"2026-02-25T16:59:31.757162Z","caller":"traceutil/trace.go:171","msg":"trace[246698574] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:407; }","duration":"109.236444ms","start":"2026-02-25T16:59:31.647911Z","end":"2026-02-25T16:59:31.757148Z","steps":["trace[246698574] 'range keys from in-memory index tree'  (duration: 108.843771ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:31.757188Z","caller":"traceutil/trace.go:171","msg":"trace[434554662] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:407; }","duration":"101.10748ms","start":"2026-02-25T16:59:31.656006Z","end":"2026-02-25T16:59:31.757114Z","steps":["trace[434554662] 'range keys from in-memory index tree'  (duration: 100.856291ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:31.757021Z","caller":"traceutil/trace.go:171","msg":"trace[340522920] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:407; }","duration":"101.002906ms","start":"2026-02-25T16:59:31.656001Z","end":"2026-02-25T16:59:31.757004Z","steps":["trace[340522920] 'range keys from in-memory index tree'  (duration: 100.873622ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:31.87086Z","caller":"traceutil/trace.go:171","msg":"trace[1099418732] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"109.888646ms","start":"2026-02-25T16:59:31.760945Z","end":"2026-02-25T16:59:31.870834Z","steps":["trace[1099418732] 'process raft request'  (duration: 107.008355ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:31.870973Z","caller":"traceutil/trace.go:171","msg":"trace[1031508043] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"109.925287ms","start":"2026-02-25T16:59:31.761027Z","end":"2026-02-25T16:59:31.870952Z","steps":["trace[1031508043] 'process raft request'  (duration: 109.731911ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:31.870893Z","caller":"traceutil/trace.go:171","msg":"trace[921923877] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"109.845805ms","start":"2026-02-25T16:59:31.761033Z","end":"2026-02-25T16:59:31.870879Z","steps":["trace[921923877] 'process raft request'  (duration: 109.776752ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:34.236607Z","caller":"traceutil/trace.go:171","msg":"trace[343007067] linearizableReadLoop","detail":"{readStateIndex:426; appliedIndex:425; }","duration":"397.011374ms","start":"2026-02-25T16:59:33.839574Z","end":"2026-02-25T16:59:34.236586Z","steps":["trace[343007067] 'read index received'  (duration: 396.89818ms)","trace[343007067] 'applied index is now lower than readState.Index'  (duration: 111.784µs)"],"step_count":2}
{"level":"warn","ts":"2026-02-25T16:59:34.236777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.19986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"}
{"level":"info","ts":"2026-02-25T16:59:34.236807Z","caller":"traceutil/trace.go:171","msg":"trace[1215108833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:412; }","duration":"397.251622ms","start":"2026-02-25T16:59:33.839548Z","end":"2026-02-25T16:59:34.2368Z","steps":["trace[1215108833] 'agreement among raft nodes before linearized reading'  (duration: 397.168859ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T16:59:34.236835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:33.839476Z","time spent":"397.352755ms","remote":"127.0.0.1:58756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-02-25T16:59:34.236996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.025782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"}
{"level":"info","ts":"2026-02-25T16:59:34.23708Z","caller":"traceutil/trace.go:171","msg":"trace[1757567848] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:412; }","duration":"361.133015ms","start":"2026-02-25T16:59:33.87592Z","end":"2026-02-25T16:59:34.237053Z","steps":["trace[1757567848] 'agreement among raft nodes before linearized reading'  (duration: 360.98133ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T16:59:34.237117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:33.875909Z","time spent":"361.202227ms","remote":"127.0.0.1:58756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"warn","ts":"2026-02-25T16:59:34.237175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.80498ms","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-02-25T16:59:34.237286Z","caller":"traceutil/trace.go:171","msg":"trace[264302923] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:412; }","duration":"361.981796ms","start":"2026-02-25T16:59:33.875275Z","end":"2026-02-25T16:59:34.237257Z","steps":["trace[264302923] 'agreement among raft nodes before linearized reading'  (duration: 361.802129ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T16:59:34.237338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:33.875254Z","time spent":"362.07439ms","remote":"127.0.0.1:58756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"info","ts":"2026-02-25T16:59:34.237242Z","caller":"traceutil/trace.go:171","msg":"trace[1790280067] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"500.979453ms","start":"2026-02-25T16:59:33.73622Z","end":"2026-02-25T16:59:34.237199Z","steps":["trace[1790280067] 'process raft request'  (duration: 500.214127ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T16:59:34.237487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.884867ms","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-02-25T16:59:34.237554Z","caller":"traceutil/trace.go:171","msg":"trace[587190105] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:412; }","duration":"362.99501ms","start":"2026-02-25T16:59:33.874551Z","end":"2026-02-25T16:59:34.237546Z","steps":["trace[587190105] 'agreement among raft nodes before linearized reading'  (duration: 362.334227ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T16:59:34.237622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:33.874536Z","time spent":"363.079493ms","remote":"127.0.0.1:58756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"warn","ts":"2026-02-25T16:59:34.237707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:33.736204Z","time spent":"501.280964ms","remote":"127.0.0.1:58670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5235,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/pods/kube-system/etcd-instance\" mod_revision:392 > success:<request_put:<key:\"/registry/pods/kube-system/etcd-instance\" value_size:5187 >> failure:<request_range:<key:\"/registry/pods/kube-system/etcd-instance\" > >"}
{"level":"warn","ts":"2026-02-25T16:59:34.743549Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12019435147466213254,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-02-25T16:59:35.269632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.077003ms","expected-duration":"100ms","prefix":"","request":"header:<ID:12019435147466213255 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:410 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:371 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >>","response":"size:16"}
{"level":"info","ts":"2026-02-25T16:59:35.26974Z","caller":"traceutil/trace.go:171","msg":"trace[821738552] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"1.027104613s","start":"2026-02-25T16:59:34.242619Z","end":"2026-02-25T16:59:35.269724Z","steps":["trace[821738552] 'process raft request'  (duration: 822.868235ms)","trace[821738552] 'compare'  (duration: 203.919128ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-25T16:59:35.269784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:34.242592Z","time spent":"1.027172206s","remote":"127.0.0.1:58756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:410 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:371 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"info","ts":"2026-02-25T16:59:35.654229Z","caller":"traceutil/trace.go:171","msg":"trace[736926226] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"1.411555384s","start":"2026-02-25T16:59:34.242646Z","end":"2026-02-25T16:59:35.654201Z","steps":["trace[736926226] 'process raft request'  (duration: 1.385032207s)","trace[736926226] 'compare'  (duration: 26.349712ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-25T16:59:35.654441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:34.242627Z","time spent":"1.411677529s","remote":"127.0.0.1:58756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:411 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:430 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"info","ts":"2026-02-25T16:59:35.654788Z","caller":"traceutil/trace.go:171","msg":"trace[1075617985] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"1.412080763s","start":"2026-02-25T16:59:34.242687Z","end":"2026-02-25T16:59:35.654768Z","steps":["trace[1075617985] 'process raft request'  (duration: 1.411467692s)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:35.654778Z","caller":"traceutil/trace.go:171","msg":"trace[19744776] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"1.410872382s","start":"2026-02-25T16:59:34.243845Z","end":"2026-02-25T16:59:35.654718Z","steps":["trace[19744776] 'process raft request'  (duration: 1.41055826s)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T16:59:35.654881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:34.242667Z","time spent":"1.412163716s","remote":"127.0.0.1:58756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:409 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:412 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"warn","ts":"2026-02-25T16:59:35.655071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:34.243836Z","time spent":"1.411075588s","remote":"127.0.0.1:58756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:408 > 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-02-25T16:59:35.655427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.412254408s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5250"}
{"level":"info","ts":"2026-02-25T16:59:35.654713Z","caller":"traceutil/trace.go:171","msg":"trace[663728272] linearizableReadLoop","detail":"{readStateIndex:430; appliedIndex:426; }","duration":"1.411392658s","start":"2026-02-25T16:59:34.243164Z","end":"2026-02-25T16:59:35.654557Z","steps":["trace[663728272] 'read index received'  (duration: 822.308876ms)","trace[663728272] 'applied index is now lower than readState.Index'  (duration: 589.082372ms)"],"step_count":2}
{"level":"info","ts":"2026-02-25T16:59:35.655523Z","caller":"traceutil/trace.go:171","msg":"trace[1226316496] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:416; }","duration":"1.412372422s","start":"2026-02-25T16:59:34.243136Z","end":"2026-02-25T16:59:35.655509Z","steps":["trace[1226316496] 'agreement among raft nodes before linearized reading'  (duration: 1.412227877s)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T16:59:35.655733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:34.243125Z","time spent":"1.412594589s","remote":"127.0.0.1:58670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5272,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "}
{"level":"info","ts":"2026-02-25T16:59:37.27864Z","caller":"traceutil/trace.go:171","msg":"trace[2004826823] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"278.504355ms","start":"2026-02-25T16:59:37.000086Z","end":"2026-02-25T16:59:37.27859Z","steps":["trace[2004826823] 'process raft request'  (duration: 259.916076ms)","trace[2004826823] 'compare'  (duration: 17.966568ms)"],"step_count":2}
{"level":"info","ts":"2026-02-25T16:59:37.650446Z","caller":"traceutil/trace.go:171","msg":"trace[1026492439] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"187.381918ms","start":"2026-02-25T16:59:37.463033Z","end":"2026-02-25T16:59:37.650415Z","steps":["trace[1026492439] 'process raft request'  (duration: 121.926734ms)","trace[1026492439] 'compare'  (duration: 65.224046ms)"],"step_count":2}
{"level":"info","ts":"2026-02-25T16:59:37.741158Z","caller":"traceutil/trace.go:171","msg":"trace[1848466698] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"267.119199ms","start":"2026-02-25T16:59:37.474012Z","end":"2026-02-25T16:59:37.741131Z","steps":["trace[1848466698] 'process raft request'  (duration: 266.957574ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:41.196142Z","caller":"traceutil/trace.go:171","msg":"trace[1053950775] linearizableReadLoop","detail":"{readStateIndex:447; appliedIndex:446; }","duration":"231.686182ms","start":"2026-02-25T16:59:40.964435Z","end":"2026-02-25T16:59:41.196122Z","steps":["trace[1053950775] 'read index received'  (duration: 231.509216ms)","trace[1053950775] 'applied index is now lower than readState.Index'  (duration: 175.926µs)"],"step_count":2}
{"level":"warn","ts":"2026-02-25T16:59:41.196168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:40.728277Z","time spent":"467.88343ms","remote":"127.0.0.1:58548","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""}
{"level":"warn","ts":"2026-02-25T16:59:41.196253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.807626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-02-25T16:59:41.196279Z","caller":"traceutil/trace.go:171","msg":"trace[252096622] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:431; }","duration":"231.885438ms","start":"2026-02-25T16:59:40.964386Z","end":"2026-02-25T16:59:41.196271Z","steps":["trace[252096622] 'agreement among raft nodes before linearized reading'  (duration: 231.821636ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:41.382621Z","caller":"traceutil/trace.go:171","msg":"trace[1059696057] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"185.356395ms","start":"2026-02-25T16:59:41.197236Z","end":"2026-02-25T16:59:41.382592Z","steps":["trace[1059696057] 'process raft request'  (duration: 183.799397ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:42.081071Z","caller":"traceutil/trace.go:171","msg":"trace[496580864] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"211.23209ms","start":"2026-02-25T16:59:41.869821Z","end":"2026-02-25T16:59:42.081053Z","steps":["trace[496580864] 'process raft request'  (duration: 211.197358ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:42.081224Z","caller":"traceutil/trace.go:171","msg":"trace[169267245] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"212.552071ms","start":"2026-02-25T16:59:41.868663Z","end":"2026-02-25T16:59:42.081215Z","steps":["trace[169267245] 'process raft request'  (duration: 135.386756ms)","trace[169267245] 'compare'  (duration: 76.69099ms)"],"step_count":2}
{"level":"info","ts":"2026-02-25T16:59:43.764478Z","caller":"traceutil/trace.go:171","msg":"trace[1343578401] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"367.969418ms","start":"2026-02-25T16:59:43.396458Z","end":"2026-02-25T16:59:43.764427Z","steps":["trace[1343578401] 'process raft request'  (duration: 367.780612ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T16:59:43.764625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:43.396431Z","time spent":"368.119751ms","remote":"127.0.0.1:58756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:433 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:371 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"info","ts":"2026-02-25T16:59:46.260989Z","caller":"traceutil/trace.go:171","msg":"trace[364871413] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"158.202857ms","start":"2026-02-25T16:59:46.102758Z","end":"2026-02-25T16:59:46.260961Z","steps":["trace[364871413] 'process raft request'  (duration: 158.026062ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:46.261958Z","caller":"traceutil/trace.go:171","msg":"trace[1962627410] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"157.301509ms","start":"2026-02-25T16:59:46.104642Z","end":"2026-02-25T16:59:46.261944Z","steps":["trace[1962627410] 'process raft request'  (duration: 157.231267ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T16:59:59.8648Z","caller":"traceutil/trace.go:171","msg":"trace[1578131378] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"263.675881ms","start":"2026-02-25T16:59:59.601101Z","end":"2026-02-25T16:59:59.864777Z","steps":["trace[1578131378] 'process raft request'  (duration: 263.235029ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T17:00:06.401636Z","caller":"traceutil/trace.go:171","msg":"trace[1927552539] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:600; }","duration":"372.916845ms","start":"2026-02-25T17:00:06.028694Z","end":"2026-02-25T17:00:06.401611Z","steps":["trace[1927552539] 'read index received'  (duration: 372.647949ms)","trace[1927552539] 'applied index is now lower than readState.Index'  (duration: 267.706µs)"],"step_count":2}
{"level":"info","ts":"2026-02-25T17:00:06.401736Z","caller":"traceutil/trace.go:171","msg":"trace[413977187] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"412.644529ms","start":"2026-02-25T17:00:05.989054Z","end":"2026-02-25T17:00:06.401699Z","steps":["trace[413977187] 'process raft request'  (duration: 412.370833ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T17:00:06.401911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.210442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"}
{"level":"info","ts":"2026-02-25T17:00:06.401956Z","caller":"traceutil/trace.go:171","msg":"trace[1022533715] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:580; }","duration":"373.310034ms","start":"2026-02-25T17:00:06.028634Z","end":"2026-02-25T17:00:06.401944Z","steps":["trace[1022533715] 'agreement among raft nodes before linearized reading'  (duration: 373.108419ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T17:00:06.401996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:00:06.028609Z","time spent":"373.379106ms","remote":"127.0.0.1:58756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-02-25T17:00:06.401998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:00:05.989032Z","time spent":"412.833353ms","remote":"127.0.0.1:58756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:573 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:371 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"warn","ts":"2026-02-25T17:00:06.402166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.850593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-5lvjr\" ","response":"range_response_count:1 size:16648"}
{"level":"info","ts":"2026-02-25T17:00:06.402201Z","caller":"traceutil/trace.go:171","msg":"trace[1606545372] range","detail":"{range_begin:/registry/pods/kube-system/cilium-5lvjr; range_end:; response_count:1; response_revision:580; }","duration":"338.925245ms","start":"2026-02-25T17:00:06.063267Z","end":"2026-02-25T17:00:06.402192Z","steps":["trace[1606545372] 'agreement among raft nodes before linearized reading'  (duration: 338.836793ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T17:00:06.402225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:00:06.063159Z","time spent":"339.060829ms","remote":"127.0.0.1:58670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16670,"request content":"key:\"/registry/pods/kube-system/cilium-5lvjr\" "}
{"level":"info","ts":"2026-02-25T17:00:07.288621Z","caller":"traceutil/trace.go:171","msg":"trace[36421799] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"881.343957ms","start":"2026-02-25T17:00:06.407255Z","end":"2026-02-25T17:00:07.288599Z","steps":["trace[36421799] 'process raft request'  (duration: 881.171372ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T17:00:07.288795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:00:06.407233Z","time spent":"881.466089ms","remote":"127.0.0.1:58756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:576 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:412 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"warn","ts":"2026-02-25T17:00:07.822031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.474233ms","expected-duration":"100ms","prefix":"","request":"header:<ID:12019435147466213599 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:575 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:430 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >>","response":"size:16"}
{"level":"info","ts":"2026-02-25T17:00:07.822228Z","caller":"traceutil/trace.go:171","msg":"trace[1758210884] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"1.414195016s","start":"2026-02-25T17:00:06.408019Z","end":"2026-02-25T17:00:07.822214Z","steps":["trace[1758210884] 'process raft request'  (duration: 1.210445677s)","trace[1758210884] 'compare'  (duration: 203.336839ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-25T17:00:07.822281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:00:06.408003Z","time spent":"1.414252678s","remote":"127.0.0.1:58756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:575 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:430 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"info","ts":"2026-02-25T17:00:07.8223Z","caller":"traceutil/trace.go:171","msg":"trace[1293172252] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:601; }","duration":"856.666493ms","start":"2026-02-25T17:00:06.965615Z","end":"2026-02-25T17:00:07.822282Z","steps":["trace[1293172252] 'read index received'  (duration: 322.797839ms)","trace[1293172252] 'applied index is now lower than readState.Index'  (duration: 533.867744ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-25T17:00:07.822436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.821517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-02-25T17:00:07.822484Z","caller":"traceutil/trace.go:171","msg":"trace[793437881] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:584; }","duration":"856.905199ms","start":"2026-02-25T17:00:06.965564Z","end":"2026-02-25T17:00:07.822469Z","steps":["trace[793437881] 'agreement among raft nodes before linearized reading'  (duration: 856.803617ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T17:00:07.822708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:00:06.965544Z","time spent":"857.155665ms","remote":"127.0.0.1:58504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "}
{"level":"info","ts":"2026-02-25T17:00:07.822449Z","caller":"traceutil/trace.go:171","msg":"trace[1277484743] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"1.41435823s","start":"2026-02-25T17:00:06.408085Z","end":"2026-02-25T17:00:07.822443Z","steps":["trace[1277484743] 'process raft request'  (duration: 1.414070313s)"],"step_count":1}
{"level":"info","ts":"2026-02-25T17:00:07.822764Z","caller":"traceutil/trace.go:171","msg":"trace[2046588370] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"1.406325924s","start":"2026-02-25T17:00:06.416423Z","end":"2026-02-25T17:00:07.822748Z","steps":["trace[2046588370] 'process raft request'  (duration: 1.405804661s)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T17:00:07.822786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:00:06.408081Z","time spent":"1.414689998s","remote":"127.0.0.1:58756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:574 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:368 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"warn","ts":"2026-02-25T17:00:07.822823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:00:06.416411Z","time spent":"1.406382635s","remote":"127.0.0.1:58670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16795,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/pods/kube-system/cilium-5lvjr\" mod_revision:528 > success:<request_put:<key:\"/registry/pods/kube-system/cilium-5lvjr\" value_size:16746 >> failure:<request_range:<key:\"/registry/pods/kube-system/cilium-5lvjr\" > >"}
{"level":"warn","ts":"2026-02-25T17:00:08.90687Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12019435147466213604,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-02-25T17:00:09.407382Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12019435147466213604,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-02-25T17:00:09.627576Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.333846176s","expected-duration":"1s"}
{"level":"info","ts":"2026-02-25T17:00:09.627983Z","caller":"traceutil/trace.go:171","msg":"trace[168555276] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:606; }","duration":"1.22136597s","start":"2026-02-25T17:00:08.406591Z","end":"2026-02-25T17:00:09.627957Z","steps":["trace[168555276] 'read index received'  (duration: 1.221130995s)","trace[168555276] 'applied index is now lower than readState.Index'  (duration: 231.035µs)"],"step_count":2}
{"level":"info","ts":"2026-02-25T17:00:09.628097Z","caller":"traceutil/trace.go:171","msg":"trace[1567258722] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"1.33441727s","start":"2026-02-25T17:00:08.293639Z","end":"2026-02-25T17:00:09.628056Z","steps":["trace[1567258722] 'process raft request'  (duration: 1.334128423s)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T17:00:09.628209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.221601956s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"}
{"level":"info","ts":"2026-02-25T17:00:09.628269Z","caller":"traceutil/trace.go:171","msg":"trace[1639181478] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:586; }","duration":"1.221695538s","start":"2026-02-25T17:00:08.406558Z","end":"2026-02-25T17:00:09.628254Z","steps":["trace[1639181478] 'agreement among raft nodes before linearized reading'  (duration: 1.221516924s)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T17:00:09.628271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:00:08.293617Z","time spent":"1.334527734s","remote":"127.0.0.1:58756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" mod_revision:535 > success:<request_put:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" value_size:600 >> failure:<request_range:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" > >"}
{"level":"warn","ts":"2026-02-25T17:00:09.628304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.917718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-02-25T17:00:09.628354Z","caller":"traceutil/trace.go:171","msg":"trace[1101306561] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:586; }","duration":"663.99549ms","start":"2026-02-25T17:00:08.964344Z","end":"2026-02-25T17:00:09.628339Z","steps":["trace[1101306561] 'agreement among raft nodes before linearized reading'  (duration: 663.920098ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T17:00:09.628391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:00:08.964327Z","time spent":"664.053882ms","remote":"127.0.0.1:58504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-02-25T17:00:09.628433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.847387ms","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-02-25T17:00:09.628473Z","caller":"traceutil/trace.go:171","msg":"trace[1909077948] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:586; }","duration":"335.919319ms","start":"2026-02-25T17:00:09.292542Z","end":"2026-02-25T17:00:09.628461Z","steps":["trace[1909077948] 'agreement among raft nodes before linearized reading'  (duration: 335.807566ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T17:00:09.628517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:00:09.292523Z","time spent":"335.9832ms","remote":"127.0.0.1:58756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"warn","ts":"2026-02-25T17:00:09.628314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:00:08.406541Z","time spent":"1.221760409s","remote":"127.0.0.1:58756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"warn","ts":"2026-02-25T17:00:10.327589Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12019435147466213609,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-02-25T17:00:10.82792Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12019435147466213609,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-02-25T17:00:11.328142Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12019435147466213609,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-02-25T17:00:11.611785Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.979509345s","expected-duration":"1s"}
{"level":"info","ts":"2026-02-25T17:00:11.661714Z","caller":"traceutil/trace.go:171","msg":"trace[191196884] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"2.029495981s","start":"2026-02-25T17:00:09.632195Z","end":"2026-02-25T17:00:11.661691Z","steps":["trace[191196884] 'process raft request'  (duration: 1.979815522s)","trace[191196884] 'compare'  (duration: 49.563107ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-25T17:00:11.661823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:00:09.632177Z","time spent":"2.029585153s","remote":"127.0.0.1:58756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:580 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:371 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"warn","ts":"2026-02-25T17:00:12.476989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.376586ms","expected-duration":"100ms","prefix":"","request":"header:<ID:12019435147466213611 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:581 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:412 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >>","response":"size:16"}
{"level":"info","ts":"2026-02-25T17:00:12.477082Z","caller":"traceutil/trace.go:171","msg":"trace[1975597980] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:607; }","duration":"2.650183127s","start":"2026-02-25T17:00:09.826884Z","end":"2026-02-25T17:00:12.477067Z","steps":["trace[1975597980] 'read index received'  (duration: 1.785134157s)","trace[1975597980] 'applied index is now lower than readState.Index'  (duration: 865.04792ms)"],"step_count":2}
{"level":"info","ts":"2026-02-25T17:00:12.477249Z","caller":"traceutil/trace.go:171","msg":"trace[1268924782] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"2.844991555s","start":"2026-02-25T17:00:09.632236Z","end":"2026-02-25T17:00:12.477228Z","steps":["trace[1268924782] 'process raft request'  (duration: 2.585292731s)","trace[1268924782] 'compare'  (duration: 259.294025ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-25T17:00:12.477303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.650402322s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"}
{"level":"info","ts":"2026-02-25T17:00:12.477353Z","caller":"traceutil/trace.go:171","msg":"trace[1449114676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:588; }","duration":"2.650496864s","start":"2026-02-25T17:00:09.826844Z","end":"2026-02-25T17:00:12.477341Z","steps":["trace[1449114676] 'agreement among raft nodes before linearized reading'  (duration: 2.650351151s)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T17:00:12.477368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.65032224s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"}
{"level":"warn","ts":"2026-02-25T17:00:12.477378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:00:09.826826Z","time spent":"2.650548205s","remote":"127.0.0.1:58756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"info","ts":"2026-02-25T17:00:12.477401Z","caller":"traceutil/trace.go:171","msg":"trace[35941829] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:588; }","duration":"2.650382551s","start":"2026-02-25T17:00:09.827008Z","end":"2026-02-25T17:00:12.477391Z","steps":["trace[35941829] 'agreement among raft nodes before linearized reading'  (duration: 2.650267729s)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T17:00:12.477441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:00:09.826998Z","time spent":"2.650436282s","remote":"127.0.0.1:58756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-02-25T17:00:12.477564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.511520648s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-02-25T17:00:12.477563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:00:09.632222Z","time spent":"2.845083876s","remote":"127.0.0.1:58756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:581 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:412 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"info","ts":"2026-02-25T17:00:12.477597Z","caller":"traceutil/trace.go:171","msg":"trace[540603545] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:588; }","duration":"1.51158642s","start":"2026-02-25T17:00:10.966001Z","end":"2026-02-25T17:00:12.477587Z","steps":["trace[540603545] 'agreement among raft nodes before linearized reading'  (duration: 1.511540139s)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T17:00:12.477626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:00:10.965983Z","time spent":"1.511637781s","remote":"127.0.0.1:58504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-02-25T17:00:12.478858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.749205351s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.7\" ","response":"range_response_count:1 size:131"}
{"level":"info","ts":"2026-02-25T17:00:12.478918Z","caller":"traceutil/trace.go:171","msg":"trace[65375070] range","detail":"{range_begin:/registry/masterleases/199.19.213.7; range_end:; response_count:1; response_revision:588; }","duration":"1.749349525s","start":"2026-02-25T17:00:10.729554Z","end":"2026-02-25T17:00:12.478904Z","steps":["trace[65375070] 'agreement among raft nodes before linearized reading'  (duration: 1.748190878s)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T17:00:12.478957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:00:10.729526Z","time spent":"1.749422647s","remote":"127.0.0.1:58548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":153,"request content":"key:\"/registry/masterleases/199.19.213.7\" "}
{"level":"info","ts":"2026-02-25T17:00:13.215591Z","caller":"traceutil/trace.go:171","msg":"trace[907429677] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"123.154051ms","start":"2026-02-25T17:00:13.092414Z","end":"2026-02-25T17:00:13.215568Z","steps":["trace[907429677] 'process raft request'  (duration: 123.021158ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T17:00:13.22434Z","caller":"traceutil/trace.go:171","msg":"trace[1193419117] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"117.904352ms","start":"2026-02-25T17:00:13.106414Z","end":"2026-02-25T17:00:13.224318Z","steps":["trace[1193419117] 'process raft request'  (duration: 117.769529ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T17:00:14.997744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.88167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"}
{"level":"info","ts":"2026-02-25T17:00:14.997824Z","caller":"traceutil/trace.go:171","msg":"trace[813671144] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:621; }","duration":"506.016353ms","start":"2026-02-25T17:00:14.491788Z","end":"2026-02-25T17:00:14.997804Z","steps":["trace[813671144] 'range keys from in-memory index tree'  (duration: 505.685046ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T17:00:14.997868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:00:14.491761Z","time spent":"506.096104ms","remote":"127.0.0.1:58756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-02-25T17:00:14.997869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.261178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"}
{"level":"warn","ts":"2026-02-25T17:00:14.997925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.925252ms","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-02-25T17:00:14.997955Z","caller":"traceutil/trace.go:171","msg":"trace[868820442] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:621; }","duration":"491.406821ms","start":"2026-02-25T17:00:14.506524Z","end":"2026-02-25T17:00:14.997931Z","steps":["trace[868820442] 'range keys from in-memory index tree'  (duration: 491.004393ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T17:00:14.997987Z","caller":"traceutil/trace.go:171","msg":"trace[1150836396] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:621; }","duration":"506.028573ms","start":"2026-02-25T17:00:14.491939Z","end":"2026-02-25T17:00:14.997968Z","steps":["trace[1150836396] 'range keys from in-memory index tree'  (duration: 505.750287ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T17:00:14.998022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:00:14.506501Z","time spent":"491.492353ms","remote":"127.0.0.1:58756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-02-25T17:00:14.998031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:00:14.491911Z","time spent":"506.107235ms","remote":"127.0.0.1:58756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"info","ts":"2026-02-25T17:00:15.001876Z","caller":"traceutil/trace.go:171","msg":"trace[1686814129] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:644; }","duration":"485.921296ms","start":"2026-02-25T17:00:14.515943Z","end":"2026-02-25T17:00:15.001865Z","steps":["trace[1686814129] 'read index received'  (duration: 485.802413ms)","trace[1686814129] 'applied index is now lower than readState.Index'  (duration: 118.343µs)"],"step_count":2}
{"level":"warn","ts":"2026-02-25T17:00:15.001998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.206868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-02-25T17:00:15.002022Z","caller":"traceutil/trace.go:171","msg":"trace[1665207324] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:622; }","duration":"296.268289ms","start":"2026-02-25T17:00:14.705744Z","end":"2026-02-25T17:00:15.002012Z","steps":["trace[1665207324] 'agreement among raft nodes before linearized reading'  (duration: 296.188047ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T17:00:15.002055Z","caller":"traceutil/trace.go:171","msg":"trace[2031928081] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"494.966512ms","start":"2026-02-25T17:00:14.507042Z","end":"2026-02-25T17:00:15.002009Z","steps":["trace[2031928081] 'process raft request'  (duration: 494.747407ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T17:00:15.00219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:00:14.507023Z","time spent":"495.106495ms","remote":"127.0.0.1:58568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" mod_revision:0 > success:<request_put:<key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" value_size:3262 >> failure:<>"}
{"level":"warn","ts":"2026-02-25T17:00:15.002879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.913998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-jmf6x\" ","response":"range_response_count:1 size:4814"}
{"level":"info","ts":"2026-02-25T17:00:15.002952Z","caller":"traceutil/trace.go:171","msg":"trace[1393926886] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-jmf6x; range_end:; response_count:1; response_revision:622; }","duration":"487.028622ms","start":"2026-02-25T17:00:14.515905Z","end":"2026-02-25T17:00:15.002934Z","steps":["trace[1393926886] 'agreement among raft nodes before linearized reading'  (duration: 486.827896ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T17:00:15.002987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:00:14.515885Z","time spent":"487.093713ms","remote":"127.0.0.1:58670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4836,"request content":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-jmf6x\" "}
{"level":"warn","ts":"2026-02-25T17:00:17.547184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.484915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"}
{"level":"info","ts":"2026-02-25T17:00:17.547262Z","caller":"traceutil/trace.go:171","msg":"trace[2064142523] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:661; }","duration":"242.607008ms","start":"2026-02-25T17:00:17.304637Z","end":"2026-02-25T17:00:17.547244Z","steps":["trace[2064142523] 'range keys from in-memory index tree'  (duration: 242.325161ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T17:00:17.547383Z","caller":"traceutil/trace.go:171","msg":"trace[410770333] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"216.436288ms","start":"2026-02-25T17:00:17.330934Z","end":"2026-02-25T17:00:17.54737Z","steps":["trace[410770333] 'process raft request'  (duration: 213.444602ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T17:00:20.140105Z","caller":"traceutil/trace.go:171","msg":"trace[1747893148] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"117.500674ms","start":"2026-02-25T17:00:20.022577Z","end":"2026-02-25T17:00:20.140078Z","steps":["trace[1747893148] 'process raft request'  (duration: 117.32806ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T17:00:24.266729Z","caller":"traceutil/trace.go:171","msg":"trace[124700450] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"100.21333ms","start":"2026-02-25T17:00:24.166501Z","end":"2026-02-25T17:00:24.266715Z","steps":["trace[124700450] 'process raft request'  (duration: 99.956245ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T17:00:25.868879Z","caller":"traceutil/trace.go:171","msg":"trace[598554979] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"143.235024ms","start":"2026-02-25T17:00:25.725609Z","end":"2026-02-25T17:00:25.868844Z","steps":["trace[598554979] 'process raft request'  (duration: 143.105601ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T17:00:28.355354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.597257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-02-25T17:00:28.355415Z","caller":"traceutil/trace.go:171","msg":"trace[1561352275] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:773; }","duration":"229.683329ms","start":"2026-02-25T17:00:28.125714Z","end":"2026-02-25T17:00:28.355397Z","steps":["trace[1561352275] 'range keys from in-memory index tree'  (duration: 229.545086ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T17:00:28.355478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.631209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-02-25T17:00:28.355534Z","caller":"traceutil/trace.go:171","msg":"trace[1220679671] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:773; }","duration":"229.72961ms","start":"2026-02-25T17:00:28.125792Z","end":"2026-02-25T17:00:28.355521Z","steps":["trace[1220679671] 'count revisions from in-memory index tree'  (duration: 229.558777ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T17:00:28.355587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.206789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"warn","ts":"2026-02-25T17:00:28.355609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.888597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-02-25T17:00:28.355631Z","caller":"traceutil/trace.go:171","msg":"trace[1626523312] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:773; }","duration":"248.955059ms","start":"2026-02-25T17:00:28.106669Z","end":"2026-02-25T17:00:28.355624Z","steps":["trace[1626523312] 'count revisions from in-memory index tree'  (duration: 248.751254ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T17:00:28.355634Z","caller":"traceutil/trace.go:171","msg":"trace[1490903737] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:773; }","duration":"274.27542ms","start":"2026-02-25T17:00:28.081346Z","end":"2026-02-25T17:00:28.355622Z","steps":["trace[1490903737] 'count revisions from in-memory index tree'  (duration: 274.159088ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T17:00:28.355691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.904674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-02-25T17:00:28.355727Z","caller":"traceutil/trace.go:171","msg":"trace[2052680742] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:773; }","duration":"229.965705ms","start":"2026-02-25T17:00:28.125752Z","end":"2026-02-25T17:00:28.355717Z","steps":["trace[2052680742] 'range keys from in-memory index tree'  (duration: 229.854503ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T17:00:28.355786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.602885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-02-25T17:00:28.355822Z","caller":"traceutil/trace.go:171","msg":"trace[1527490074] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:773; }","duration":"266.647206ms","start":"2026-02-25T17:00:28.089165Z","end":"2026-02-25T17:00:28.355813Z","steps":["trace[1527490074] 'count revisions from in-memory index tree'  (duration: 266.566124ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T17:00:28.355857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.36699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-02-25T17:00:28.355876Z","caller":"traceutil/trace.go:171","msg":"trace[1302508420] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:773; }","duration":"266.395311ms","start":"2026-02-25T17:00:28.089475Z","end":"2026-02-25T17:00:28.35587Z","steps":["trace[1302508420] 'range keys from in-memory index tree'  (duration: 266.349609ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T17:00:28.355733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.866537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-02-25T17:00:28.355901Z","caller":"traceutil/trace.go:171","msg":"trace[1305871888] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:773; }","duration":"249.03206ms","start":"2026-02-25T17:00:28.10686Z","end":"2026-02-25T17:00:28.355892Z","steps":["trace[1305871888] 'range keys from in-memory index tree'  (duration: 248.806156ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T17:00:28.355928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.801699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-02-25T17:00:28.355944Z","caller":"traceutil/trace.go:171","msg":"trace[1289155746] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:773; }","duration":"266.840999ms","start":"2026-02-25T17:00:28.089098Z","end":"2026-02-25T17:00:28.355939Z","steps":["trace[1289155746] 'range keys from in-memory index tree'  (duration: 266.704157ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-25T17:00:28.355334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.55195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-02-25T17:00:28.356096Z","caller":"traceutil/trace.go:171","msg":"trace[274561496] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:773; }","duration":"249.338636ms","start":"2026-02-25T17:00:28.106707Z","end":"2026-02-25T17:00:28.356046Z","steps":["trace[274561496] 'range keys from in-memory index tree'  (duration: 248.446678ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T17:00:28.35614Z","caller":"traceutil/trace.go:171","msg":"trace[1719776766] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"206.694465ms","start":"2026-02-25T17:00:28.149435Z","end":"2026-02-25T17:00:28.35613Z","steps":["trace[1719776766] 'process raft request'  (duration: 205.888249ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T17:01:37.703449Z","caller":"traceutil/trace.go:171","msg":"trace[1888974628] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"130.607897ms","start":"2026-02-25T17:01:37.572813Z","end":"2026-02-25T17:01:37.70342Z","steps":["trace[1888974628] 'process raft request'  (duration: 130.400834ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T17:01:38.056513Z","caller":"traceutil/trace.go:171","msg":"trace[1782138971] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"254.184716ms","start":"2026-02-25T17:01:37.802293Z","end":"2026-02-25T17:01:38.056478Z","steps":["trace[1782138971] 'process raft request'  (duration: 253.998243ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T17:01:47.017202Z","caller":"traceutil/trace.go:171","msg":"trace[2004168200] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"171.284495ms","start":"2026-02-25T17:01:46.845894Z","end":"2026-02-25T17:01:47.017178Z","steps":["trace[2004168200] 'process raft request'  (duration: 171.149683ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T17:02:16.057595Z","caller":"traceutil/trace.go:171","msg":"trace[736947779] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"124.244117ms","start":"2026-02-25T17:02:15.93331Z","end":"2026-02-25T17:02:16.057554Z","steps":["trace[736947779] 'process raft request'  (duration: 40.768326ms)","trace[736947779] 'compare'  (duration: 83.308299ms)"],"step_count":2}
{"level":"info","ts":"2026-02-25T17:02:16.119553Z","caller":"traceutil/trace.go:171","msg":"trace[1473552272] linearizableReadLoop","detail":"{readStateIndex:1178; appliedIndex:1176; }","duration":"156.875243ms","start":"2026-02-25T17:02:15.96265Z","end":"2026-02-25T17:02:16.119525Z","steps":["trace[1473552272] 'read index received'  (duration: 926.391µs)","trace[1473552272] 'applied index is now lower than readState.Index'  (duration: 155.947512ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-25T17:02:16.119765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.186956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-02-25T17:02:16.119899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.224827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-5lvjr\" ","response":"range_response_count:1 size:18054"}
{"level":"info","ts":"2026-02-25T17:02:16.119799Z","caller":"traceutil/trace.go:171","msg":"trace[593935356] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1125; }","duration":"156.258766ms","start":"2026-02-25T17:02:15.963529Z","end":"2026-02-25T17:02:16.119787Z","steps":["trace[593935356] 'agreement among raft nodes before linearized reading'  (duration: 156.180435ms)"],"step_count":1}
{"level":"info","ts":"2026-02-25T17:02:16.119986Z","caller":"traceutil/trace.go:171","msg":"trace[1678997700] range","detail":"{range_begin:/registry/pods/kube-system/cilium-5lvjr; range_end:; response_count:1; response_revision:1125; }","duration":"157.358328ms","start":"2026-02-25T17:02:15.96261Z","end":"2026-02-25T17:02:16.119968Z","steps":["trace[1678997700] 'agreement among raft nodes before linearized reading'  (duration: 157.076795ms)"],"step_count":1}