{"level":"warn","ts":"2026-06-18T00:53:18.711589Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-18T00:53:18.711701Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.23:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.23:2380","--initial-cluster=instance=https://199.204.45.23:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.23:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.23: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-06-18T00:53:18.711792Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-18T00:53:18.711805Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-06-18T00:53:18.711838Z","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-06-18T00:53:18.712593Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"]} {"level":"info","ts":"2026-06-18T00:53:18.712733Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.23: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-06-18T00:53:18.71923Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.236571ms"} {"level":"info","ts":"2026-06-18T00:53:18.730991Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"51a5c416a4d98c86","cluster-id":"ade2045974c19907"} {"level":"info","ts":"2026-06-18T00:53:18.73117Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=()"} {"level":"info","ts":"2026-06-18T00:53:18.731203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 0"} {"level":"info","ts":"2026-06-18T00:53:18.731219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 51a5c416a4d98c86 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-18T00:53:18.73124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 1"} {"level":"info","ts":"2026-06-18T00:53:18.731285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"warn","ts":"2026-06-18T00:53:18.736989Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-18T00:53:18.738966Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-18T00:53:18.740454Z","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-06-18T00:53:18.743572Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"51a5c416a4d98c86","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-18T00:53:18.746701Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"51a5c416a4d98c86","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-18T00:53:18.746859Z","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-06-18T00:53:18.746891Z","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-06-18T00:53:18.7469Z","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-06-18T00:53:18.748753Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"info","ts":"2026-06-18T00:53:18.750718Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","added-peer-id":"51a5c416a4d98c86","added-peer-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-06-18T00:53:18.753259Z","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-06-18T00:53:18.75338Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-06-18T00:53:18.753443Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-06-18T00:53:18.753667Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"51a5c416a4d98c86","initial-advertise-peer-urls":["https://199.204.45.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-18T00:53:18.753717Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-18T00:53:18.932483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 is starting a new election at term 1"} {"level":"info","ts":"2026-06-18T00:53:18.932562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-18T00:53:18.932611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgPreVoteResp from 51a5c416a4d98c86 at term 1"} {"level":"info","ts":"2026-06-18T00:53:18.932634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became candidate at term 2"} {"level":"info","ts":"2026-06-18T00:53:18.932646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgVoteResp from 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-06-18T00:53:18.932822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became leader at term 2"} {"level":"info","ts":"2026-06-18T00:53:18.932839Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 51a5c416a4d98c86 elected leader 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-06-18T00:53:18.933807Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"51a5c416a4d98c86","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.23:2379]}","request-path":"/0/members/51a5c416a4d98c86/attributes","cluster-id":"ade2045974c19907","publish-timeout":"7s"} {"level":"info","ts":"2026-06-18T00:53:18.933894Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-18T00:53:18.934157Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-18T00:53:18.934287Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-18T00:53:18.935137Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","cluster-version":"3.5"} {"level":"info","ts":"2026-06-18T00:53:18.935312Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-18T00:53:18.935693Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-18T00:53:18.938604Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.23:2379"} {"level":"info","ts":"2026-06-18T00:53:18.941174Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-18T00:53:18.94135Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-18T00:53:18.941151Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-18T00:53:25.669555Z","caller":"traceutil/trace.go:171","msg":"trace[951591652] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"246.841411ms","start":"2026-06-18T00:53:25.422695Z","end":"2026-06-18T00:53:25.669536Z","steps":["trace[951591652] 'process raft request' (duration: 246.731459ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T00:53:28.269158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.882267366s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-18T00:53:28.269306Z","caller":"traceutil/trace.go:171","msg":"trace[1809485196] linearizableReadLoop","detail":"{readStateIndex:325; appliedIndex:324; }","duration":"1.70550041s","start":"2026-06-18T00:53:26.563791Z","end":"2026-06-18T00:53:28.269291Z","steps":["trace[1809485196] 'read index received' (duration: 36.621µs)","trace[1809485196] 'applied index is now lower than readState.Index' (duration: 1.705462589s)"],"step_count":2} {"level":"info","ts":"2026-06-18T00:53:28.269378Z","caller":"traceutil/trace.go:171","msg":"trace[1108184303] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"2.590621072s","start":"2026-06-18T00:53:25.678748Z","end":"2026-06-18T00:53:28.26937Z","steps":["trace[1108184303] 'process raft request' (duration: 707.621461ms)","trace[1108184303] 'compare' (duration: 1.882089602s)"],"step_count":2} {"level":"warn","ts":"2026-06-18T00:53:28.269422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T00:53:25.678724Z","time spent":"2.590677673s","remote":"127.0.0.1:45692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":199,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-18T00:53:28.269579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.572870199s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-18T00:53:28.269645Z","caller":"traceutil/trace.go:171","msg":"trace[1200245871] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:313; }","duration":"1.572975851s","start":"2026-06-18T00:53:26.696654Z","end":"2026-06-18T00:53:28.26963Z","steps":["trace[1200245871] 'agreement among raft nodes before linearized reading' (duration: 1.572805067s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T00:53:28.269682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T00:53:26.696586Z","time spent":"1.573090114s","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-18T00:53:28.269712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.705919539s","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-06-18T00:53:28.269771Z","caller":"traceutil/trace.go:171","msg":"trace[1309671491] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:313; }","duration":"1.705996981s","start":"2026-06-18T00:53:26.563757Z","end":"2026-06-18T00:53:28.269753Z","steps":["trace[1309671491] 'agreement among raft nodes before linearized reading' (duration: 1.705913099s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T00:53:28.269623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.396362609s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-18T00:53:28.269794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.476203189s","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-06-18T00:53:28.26983Z","caller":"traceutil/trace.go:171","msg":"trace[127119710] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:313; }","duration":"1.476284941s","start":"2026-06-18T00:53:26.793535Z","end":"2026-06-18T00:53:28.26982Z","steps":["trace[127119710] 'agreement among raft nodes before linearized reading' (duration: 1.47621149s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T00:53:28.269822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T00:53:26.563739Z","time spent":"1.706070752s","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-18T00:53:28.269857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T00:53:26.793511Z","time spent":"1.476338992s","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-18T00:53:28.269859Z","caller":"traceutil/trace.go:171","msg":"trace[1226731058] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:313; }","duration":"1.396619184s","start":"2026-06-18T00:53:26.873216Z","end":"2026-06-18T00:53:28.269835Z","steps":["trace[1226731058] 'agreement among raft nodes before linearized reading' (duration: 1.396349659s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T00:53:28.269928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T00:53:26.873197Z","time spent":"1.396712096s","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-18T00:53:28.875131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.060319ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-18T00:53:28.875309Z","caller":"traceutil/trace.go:171","msg":"trace[581849177] linearizableReadLoop","detail":"{readStateIndex:326; appliedIndex:325; }","duration":"598.797164ms","start":"2026-06-18T00:53:28.27649Z","end":"2026-06-18T00:53:28.875288Z","steps":["trace[581849177] 'read index received' (duration: 374.269205ms)","trace[581849177] 'applied index is now lower than readState.Index' (duration: 224.526389ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T00:53:28.875342Z","caller":"traceutil/trace.go:171","msg":"trace[662565028] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"601.909001ms","start":"2026-06-18T00:53:28.273387Z","end":"2026-06-18T00:53:28.875296Z","steps":["trace[662565028] 'process raft request' (duration: 377.34542ms)","trace[662565028] 'compare' (duration: 223.872776ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T00:53:28.875517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T00:53:28.273369Z","time spent":"602.052733ms","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T00:53:28.875585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.07348ms","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-06-18T00:53:28.875719Z","caller":"traceutil/trace.go:171","msg":"trace[900516243] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pod-garbage-collector; range_end:; response_count:0; response_revision:314; }","duration":"599.159192ms","start":"2026-06-18T00:53:28.276459Z","end":"2026-06-18T00:53:28.875618Z","steps":["trace[900516243] 'agreement among raft nodes before linearized reading' (duration: 599.05706ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T00:53:28.87579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T00:53:28.276449Z","time spent":"599.324085ms","remote":"127.0.0.1:45692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/pod-garbage-collector\" "} {"level":"warn","ts":"2026-06-18T00:53:28.876013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.378764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"info","ts":"2026-06-18T00:53:28.876131Z","caller":"traceutil/trace.go:171","msg":"trace[1390396145] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:317; }","duration":"379.528287ms","start":"2026-06-18T00:53:28.496575Z","end":"2026-06-18T00:53:28.876103Z","steps":["trace[1390396145] 'agreement among raft nodes before linearized reading' (duration: 379.353054ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T00:53:28.87612Z","caller":"traceutil/trace.go:171","msg":"trace[408037202] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"599.459418ms","start":"2026-06-18T00:53:28.27662Z","end":"2026-06-18T00:53:28.876079Z","steps":["trace[408037202] 'process raft request' (duration: 599.09727ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T00:53:28.876202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T00:53:28.496556Z","time spent":"379.631519ms","remote":"127.0.0.1:45674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4345,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-06-18T00:53:28.876249Z","caller":"traceutil/trace.go:171","msg":"trace[1628906210] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"599.604001ms","start":"2026-06-18T00:53:28.27663Z","end":"2026-06-18T00:53:28.876234Z","steps":["trace[1628906210] 'process raft request' (duration: 599.213943ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T00:53:28.876334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T00:53:28.276612Z","time spent":"599.680782ms","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T00:53:28.876376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T00:53:28.276611Z","time spent":"599.636331ms","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T00:53:28.876548Z","caller":"traceutil/trace.go:171","msg":"trace[959239642] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"599.898498ms","start":"2026-06-18T00:53:28.27663Z","end":"2026-06-18T00:53:28.876528Z","steps":["trace[959239642] 'process raft request' (duration: 599.248344ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T00:53:28.876688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T00:53:28.276612Z","time spent":"599.993939ms","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T00:54:01.16736Z","caller":"traceutil/trace.go:171","msg":"trace[1325939357] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"138.570287ms","start":"2026-06-18T00:54:01.02877Z","end":"2026-06-18T00:54:01.16734Z","steps":["trace[1325939357] 'process raft request' (duration: 138.403904ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T00:54:01.167657Z","caller":"traceutil/trace.go:171","msg":"trace[501044223] linearizableReadLoop","detail":"{readStateIndex:548; appliedIndex:548; }","duration":"137.775933ms","start":"2026-06-18T00:54:01.029834Z","end":"2026-06-18T00:54:01.16761Z","steps":["trace[501044223] 'read index received' (duration: 137.763653ms)","trace[501044223] 'applied index is now lower than readState.Index' (duration: 9.94µs)"],"step_count":2} {"level":"warn","ts":"2026-06-18T00:54:01.167858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.986867ms","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-06-18T00:54:01.167891Z","caller":"traceutil/trace.go:171","msg":"trace[1966405389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:528; }","duration":"138.068598ms","start":"2026-06-18T00:54:01.029812Z","end":"2026-06-18T00:54:01.167881Z","steps":["trace[1966405389] 'agreement among raft nodes before linearized reading' (duration: 137.931165ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T00:54:01.192795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.978595ms","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-06-18T00:54:01.19285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.515107ms","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-06-18T00:54:01.1929Z","caller":"traceutil/trace.go:171","msg":"trace[1913067414] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:539; }","duration":"156.598768ms","start":"2026-06-18T00:54:01.036288Z","end":"2026-06-18T00:54:01.192886Z","steps":["trace[1913067414] 'agreement among raft nodes before linearized reading' (duration: 156.505146ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T00:54:01.192899Z","caller":"traceutil/trace.go:171","msg":"trace[473530404] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:539; }","duration":"157.087517ms","start":"2026-06-18T00:54:01.035774Z","end":"2026-06-18T00:54:01.192861Z","steps":["trace[473530404] 'agreement among raft nodes before linearized reading' (duration: 156.884063ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T00:54:02.132503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.695638ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-18T00:54:02.132645Z","caller":"traceutil/trace.go:171","msg":"trace[140264425] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"581.660126ms","start":"2026-06-18T00:54:01.550961Z","end":"2026-06-18T00:54:02.132621Z","steps":["trace[140264425] 'process raft request' (duration: 194.746955ms)","trace[140264425] 'compare' (duration: 386.554736ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T00:54:02.132721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T00:54:01.550946Z","time spent":"581.736847ms","remote":"127.0.0.1:45546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7684,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T00:54:02.136569Z","caller":"traceutil/trace.go:171","msg":"trace[569166725] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"562.881793ms","start":"2026-06-18T00:54:01.573654Z","end":"2026-06-18T00:54:02.136536Z","steps":["trace[569166725] 'process raft request' (duration: 562.688099ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T00:54:02.136705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T00:54:01.57363Z","time spent":"563.011785ms","remote":"127.0.0.1:45546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80688,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T00:54:02.13677Z","caller":"traceutil/trace.go:171","msg":"trace[1341767511] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"559.515343ms","start":"2026-06-18T00:54:01.577219Z","end":"2026-06-18T00:54:02.136735Z","steps":["trace[1341767511] 'process raft request' (duration: 559.280459ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T00:54:02.136879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T00:54:01.577202Z","time spent":"559.629775ms","remote":"127.0.0.1:45546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64392,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-18T00:54:09.268414Z","caller":"traceutil/trace.go:171","msg":"trace[1586291128] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"638.653516ms","start":"2026-06-18T00:54:08.629744Z","end":"2026-06-18T00:54:09.268397Z","steps":["trace[1586291128] 'process raft request' (duration: 637.439935ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T00:54:09.268522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T00:54:08.629723Z","time spent":"638.741157ms","remote":"127.0.0.1:45580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T00:54:09.409536Z","caller":"traceutil/trace.go:171","msg":"trace[1765245371] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"125.264137ms","start":"2026-06-18T00:54:09.28425Z","end":"2026-06-18T00:54:09.409514Z","steps":["trace[1765245371] 'process raft request' (duration: 124.725979ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T00:54:11.860297Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125955463676104800,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T00:54:12.360755Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125955463676104800,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T00:54:12.684526Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.395170592s","expected-duration":"1s"} {"level":"info","ts":"2026-06-18T00:54:12.685489Z","caller":"traceutil/trace.go:171","msg":"trace[1887745911] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"1.395796125s","start":"2026-06-18T00:54:11.289215Z","end":"2026-06-18T00:54:12.685011Z","steps":["trace[1887745911] 'process raft request' (duration: 1.3954879s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T00:54:12.685633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T00:54:11.289189Z","time spent":"1.396361724s","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T00:54:12.68613Z","caller":"traceutil/trace.go:171","msg":"trace[29109386] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:677; }","duration":"1.326459195s","start":"2026-06-18T00:54:11.359659Z","end":"2026-06-18T00:54:12.686118Z","steps":["trace[29109386] 'read index received' (duration: 1.325137663s)","trace[29109386] 'applied index is now lower than readState.Index' (duration: 1.320492ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T00:54:12.686259Z","caller":"traceutil/trace.go:171","msg":"trace[622673227] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"426.18939ms","start":"2026-06-18T00:54:12.260053Z","end":"2026-06-18T00:54:12.686242Z","steps":["trace[622673227] 'process raft request' (duration: 425.985417ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T00:54:12.686314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T00:54:12.259956Z","time spent":"426.321463ms","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T00:54:12.686461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.620907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T00:54:12.686503Z","caller":"traceutil/trace.go:171","msg":"trace[761378995] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:654; }","duration":"472.690268ms","start":"2026-06-18T00:54:12.213801Z","end":"2026-06-18T00:54:12.686492Z","steps":["trace[761378995] 'agreement among raft nodes before linearized reading' (duration: 472.602426ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T00:54:12.686607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T00:54:12.213786Z","time spent":"472.811869ms","remote":"127.0.0.1:45508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-18T00:54:12.686588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.282533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.23\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-18T00:54:12.686745Z","caller":"traceutil/trace.go:171","msg":"trace[879040138] range","detail":"{range_begin:/registry/masterleases/199.204.45.23; range_end:; response_count:1; response_revision:654; }","duration":"455.475976ms","start":"2026-06-18T00:54:12.231236Z","end":"2026-06-18T00:54:12.686712Z","steps":["trace[879040138] 'agreement among raft nodes before linearized reading' (duration: 455.15914ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T00:54:12.686816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T00:54:12.23122Z","time spent":"455.579909ms","remote":"127.0.0.1:45526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.23\" "} {"level":"warn","ts":"2026-06-18T00:54:12.686754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.326254912s","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-06-18T00:54:12.686993Z","caller":"traceutil/trace.go:171","msg":"trace[2135292804] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:654; }","duration":"1.326524056s","start":"2026-06-18T00:54:11.360443Z","end":"2026-06-18T00:54:12.686967Z","steps":["trace[2135292804] 'agreement among raft nodes before linearized reading' (duration: 1.325918876s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T00:54:12.687166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.326447944s","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-06-18T00:54:12.68728Z","caller":"traceutil/trace.go:171","msg":"trace[2034341197] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:654; }","duration":"1.326612878s","start":"2026-06-18T00:54:11.360643Z","end":"2026-06-18T00:54:12.687256Z","steps":["trace[2034341197] 'agreement among raft nodes before linearized reading' (duration: 1.326245072s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T00:54:12.687325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T00:54:11.360633Z","time spent":"1.326684249s","remote":"127.0.0.1:45770","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-06-18T00:54:12.687155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.327446031s","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-06-18T00:54:12.687512Z","caller":"traceutil/trace.go:171","msg":"trace[1279696114] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:654; }","duration":"1.327857608s","start":"2026-06-18T00:54:11.35963Z","end":"2026-06-18T00:54:12.687487Z","steps":["trace[1279696114] 'agreement among raft nodes before linearized reading' (duration: 1.327183187s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T00:54:12.687606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T00:54:11.359615Z","time spent":"1.327974131s","remote":"127.0.0.1:45770","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-06-18T00:54:12.687232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T00:54:11.360421Z","time spent":"1.32679454s","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-18T00:54:14.627774Z","caller":"traceutil/trace.go:171","msg":"trace[1788161302] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"195.677939ms","start":"2026-06-18T00:54:14.43206Z","end":"2026-06-18T00:54:14.627738Z","steps":["trace[1788161302] 'process raft request' (duration: 195.466626ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T00:54:25.142561Z","caller":"traceutil/trace.go:171","msg":"trace[174213790] linearizableReadLoop","detail":"{readStateIndex:818; appliedIndex:817; }","duration":"392.636648ms","start":"2026-06-18T00:54:24.749896Z","end":"2026-06-18T00:54:25.142533Z","steps":["trace[174213790] 'read index received' (duration: 392.350314ms)","trace[174213790] 'applied index is now lower than readState.Index' (duration: 285.004µs)"],"step_count":2} {"level":"info","ts":"2026-06-18T00:54:25.142717Z","caller":"traceutil/trace.go:171","msg":"trace[1683151181] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"400.331364ms","start":"2026-06-18T00:54:24.742351Z","end":"2026-06-18T00:54:25.142682Z","steps":["trace[1683151181] 'process raft request' (duration: 400.008469ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T00:54:25.142847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.922393ms","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-06-18T00:54:25.142908Z","caller":"traceutil/trace.go:171","msg":"trace[221727154] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:788; }","duration":"393.027545ms","start":"2026-06-18T00:54:24.749865Z","end":"2026-06-18T00:54:25.142892Z","steps":["trace[221727154] 'agreement among raft nodes before linearized reading' (duration: 392.832703ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T00:54:25.142957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T00:54:24.749851Z","time spent":"393.093127ms","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-18T00:54:25.14297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T00:54:24.742327Z","time spent":"400.493536ms","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T00:54:25.14314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.904803ms","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-06-18T00:54:25.143247Z","caller":"traceutil/trace.go:171","msg":"trace[1362971245] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:788; }","duration":"388.034554ms","start":"2026-06-18T00:54:24.755168Z","end":"2026-06-18T00:54:25.143203Z","steps":["trace[1362971245] 'agreement among raft nodes before linearized reading' (duration: 387.75439ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T00:54:25.143192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.405295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T00:54:25.143306Z","caller":"traceutil/trace.go:171","msg":"trace[250052303] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:788; }","duration":"281.553387ms","start":"2026-06-18T00:54:24.861737Z","end":"2026-06-18T00:54:25.14329Z","steps":["trace[250052303] 'agreement among raft nodes before linearized reading' (duration: 281.317494ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T00:54:25.14333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T00:54:24.755144Z","time spent":"388.171216ms","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-18T00:54:25.143311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.008094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-18T00:54:25.143553Z","caller":"traceutil/trace.go:171","msg":"trace[1337104619] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:788; }","duration":"388.303539ms","start":"2026-06-18T00:54:24.755234Z","end":"2026-06-18T00:54:25.143538Z","steps":["trace[1337104619] 'agreement among raft nodes before linearized reading' (duration: 387.898962ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T00:54:25.143654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T00:54:24.755225Z","time spent":"388.39345ms","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-18T00:54:28.712863Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125955463676105107,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T00:54:29.141198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.943221ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-18T00:54:29.145044Z","caller":"traceutil/trace.go:171","msg":"trace[353425156] linearizableReadLoop","detail":"{readStateIndex:839; appliedIndex:837; }","duration":"932.345823ms","start":"2026-06-18T00:54:28.212661Z","end":"2026-06-18T00:54:29.145007Z","steps":["trace[353425156] 'read index received' (duration: 580.609842ms)","trace[353425156] 'applied index is now lower than readState.Index' (duration: 351.734671ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T00:54:29.145072Z","caller":"traceutil/trace.go:171","msg":"trace[1748832627] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"799.04016ms","start":"2026-06-18T00:54:28.346009Z","end":"2026-06-18T00:54:29.14505Z","steps":["trace[1748832627] 'process raft request' (duration: 798.812756ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T00:54:29.145158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.486916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T00:54:29.14519Z","caller":"traceutil/trace.go:171","msg":"trace[940624914] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:808; }","duration":"932.584707ms","start":"2026-06-18T00:54:28.212597Z","end":"2026-06-18T00:54:29.145182Z","steps":["trace[940624914] 'agreement among raft nodes before linearized reading' (duration: 932.512496ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T00:54:29.145212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T00:54:28.212576Z","time spent":"932.631038ms","remote":"127.0.0.1:45508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-18T00:54:29.145267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T00:54:28.34599Z","time spent":"799.183062ms","remote":"127.0.0.1:45732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T00:54:29.145451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.191781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-18T00:54:29.145497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"842.793251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-18T00:54:29.145628Z","caller":"traceutil/trace.go:171","msg":"trace[1988110799] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:808; }","duration":"359.342093ms","start":"2026-06-18T00:54:28.786188Z","end":"2026-06-18T00:54:29.14553Z","steps":["trace[1988110799] 'agreement among raft nodes before linearized reading' (duration: 359.149911ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T00:54:29.145896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T00:54:28.786171Z","time spent":"359.659319ms","remote":"127.0.0.1:46004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":2,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"info","ts":"2026-06-18T00:54:29.145669Z","caller":"traceutil/trace.go:171","msg":"trace[1836502624] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:808; }","duration":"842.990963ms","start":"2026-06-18T00:54:28.302661Z","end":"2026-06-18T00:54:29.145652Z","steps":["trace[1836502624] 'agreement among raft nodes before linearized reading' (duration: 842.630408ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T00:54:29.146139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T00:54:28.302644Z","time spent":"843.477551ms","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-18T00:54:29.282742Z","caller":"traceutil/trace.go:171","msg":"trace[1409478148] linearizableReadLoop","detail":"{readStateIndex:840; appliedIndex:839; }","duration":"127.625756ms","start":"2026-06-18T00:54:29.155089Z","end":"2026-06-18T00:54:29.282715Z","steps":["trace[1409478148] 'read index received' (duration: 125.175948ms)","trace[1409478148] 'applied index is now lower than readState.Index' (duration: 2.448598ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T00:54:29.282875Z","caller":"traceutil/trace.go:171","msg":"trace[1009349947] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"131.843573ms","start":"2026-06-18T00:54:29.151018Z","end":"2026-06-18T00:54:29.282862Z","steps":["trace[1009349947] 'process raft request' (duration: 129.279863ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T00:54:29.283393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.255216ms","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-06-18T00:54:29.283474Z","caller":"traceutil/trace.go:171","msg":"trace[264370949] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:809; }","duration":"128.410998ms","start":"2026-06-18T00:54:29.155045Z","end":"2026-06-18T00:54:29.283456Z","steps":["trace[264370949] 'agreement among raft nodes before linearized reading' (duration: 128.172284ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T00:55:12.474089Z","caller":"traceutil/trace.go:171","msg":"trace[1183633547] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"107.901681ms","start":"2026-06-18T00:55:12.366158Z","end":"2026-06-18T00:55:12.474059Z","steps":["trace[1183633547] 'process raft request' (duration: 102.99718ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T00:55:31.8183Z","caller":"traceutil/trace.go:171","msg":"trace[483799403] linearizableReadLoop","detail":"{readStateIndex:1192; appliedIndex:1192; }","duration":"141.655286ms","start":"2026-06-18T00:55:31.676622Z","end":"2026-06-18T00:55:31.818278Z","steps":["trace[483799403] 'read index received' (duration: 141.646505ms)","trace[483799403] 'applied index is now lower than readState.Index' (duration: 7.591µs)"],"step_count":2} {"level":"info","ts":"2026-06-18T00:55:31.818316Z","caller":"traceutil/trace.go:171","msg":"trace[195703295] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"208.591069ms","start":"2026-06-18T00:55:31.609696Z","end":"2026-06-18T00:55:31.818287Z","steps":["trace[195703295] 'process raft request' (duration: 208.414555ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T00:55:31.818497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.859329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-18T00:55:31.818544Z","caller":"traceutil/trace.go:171","msg":"trace[961552422] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1149; }","duration":"141.92373ms","start":"2026-06-18T00:55:31.676593Z","end":"2026-06-18T00:55:31.818516Z","steps":["trace[961552422] 'agreement among raft nodes before linearized reading' (duration: 141.769228ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T00:55:32.009824Z","caller":"traceutil/trace.go:171","msg":"trace[148182828] transaction","detail":"{read_only:false; response_revision:1151; number_of_response:1; }","duration":"186.050085ms","start":"2026-06-18T00:55:31.823756Z","end":"2026-06-18T00:55:32.009806Z","steps":["trace[148182828] 'process raft request' (duration: 186.015104ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T00:55:32.010153Z","caller":"traceutil/trace.go:171","msg":"trace[690596656] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"326.453263ms","start":"2026-06-18T00:55:31.68369Z","end":"2026-06-18T00:55:32.010143Z","steps":["trace[690596656] 'process raft request' (duration: 284.788822ms)","trace[690596656] 'compare' (duration: 41.091753ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T00:55:32.010239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T00:55:31.683671Z","time spent":"326.508744ms","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T00:55:32.010335Z","caller":"traceutil/trace.go:171","msg":"trace[779917620] linearizableReadLoop","detail":"{readStateIndex:1193; appliedIndex:1192; }","duration":"191.952827ms","start":"2026-06-18T00:55:31.818347Z","end":"2026-06-18T00:55:32.0103Z","steps":["trace[779917620] 'read index received' (duration: 150.146174ms)","trace[779917620] 'applied index is now lower than readState.Index' (duration: 41.803703ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T00:55:32.010531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.063884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-18T00:55:32.01058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.696133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-18T00:55:32.010616Z","caller":"traceutil/trace.go:171","msg":"trace[704824030] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1151; }","duration":"328.187326ms","start":"2026-06-18T00:55:31.682408Z","end":"2026-06-18T00:55:32.010595Z","steps":["trace[704824030] 'agreement among raft nodes before linearized reading' (duration: 327.920332ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T00:55:32.010625Z","caller":"traceutil/trace.go:171","msg":"trace[1880864576] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1151; }","duration":"328.803065ms","start":"2026-06-18T00:55:31.681807Z","end":"2026-06-18T00:55:32.01061Z","steps":["trace[1880864576] 'agreement among raft nodes before linearized reading' (duration: 328.601862ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T00:55:32.010651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T00:55:31.682404Z","time spent":"328.242506ms","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-18T00:55:32.01067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T00:55:31.681779Z","time spent":"328.882506ms","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-18T00:55:32.014889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.560697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-18T00:55:32.014933Z","caller":"traceutil/trace.go:171","msg":"trace[1755937550] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1151; }","duration":"332.63594ms","start":"2026-06-18T00:55:31.682287Z","end":"2026-06-18T00:55:32.014923Z","steps":["trace[1755937550] 'agreement among raft nodes before linearized reading' (duration: 332.513057ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T00:55:32.014958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T00:55:31.682268Z","time spent":"332.68522ms","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-18T00:55:34.148363Z","caller":"traceutil/trace.go:171","msg":"trace[1802673946] transaction","detail":"{read_only:false; response_revision:1157; number_of_response:1; }","duration":"131.426065ms","start":"2026-06-18T00:55:34.016919Z","end":"2026-06-18T00:55:34.148345Z","steps":["trace[1802673946] 'process raft request' (duration: 131.322004ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T00:55:34.150239Z","caller":"traceutil/trace.go:171","msg":"trace[1017782433] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"121.019631ms","start":"2026-06-18T00:55:34.029204Z","end":"2026-06-18T00:55:34.150223Z","steps":["trace[1017782433] 'process raft request' (duration: 120.951229ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T00:55:34.150543Z","caller":"traceutil/trace.go:171","msg":"trace[111831462] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"130.680726ms","start":"2026-06-18T00:55:34.019847Z","end":"2026-06-18T00:55:34.150528Z","steps":["trace[111831462] 'process raft request' (duration: 130.148098ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T00:56:22.192719Z","caller":"traceutil/trace.go:171","msg":"trace[504115939] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"124.153956ms","start":"2026-06-18T00:56:22.06853Z","end":"2026-06-18T00:56:22.192684Z","steps":["trace[504115939] 'process raft request' (duration: 123.537921ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T00:56:28.378206Z","caller":"traceutil/trace.go:171","msg":"trace[621683307] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"155.88831ms","start":"2026-06-18T00:56:28.222281Z","end":"2026-06-18T00:56:28.37817Z","steps":["trace[621683307] 'process raft request' (duration: 155.614919ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T00:56:28.897425Z","caller":"traceutil/trace.go:171","msg":"trace[154541695] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"381.018502ms","start":"2026-06-18T00:56:28.516389Z","end":"2026-06-18T00:56:28.897407Z","steps":["trace[154541695] 'process raft request' (duration: 380.906858ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T00:56:28.897559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T00:56:28.516348Z","time spent":"381.137946ms","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}