{"level":"warn","ts":"2026-02-25T06:05:52.518454Z","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-25T06:05:52.518633Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.116: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.116:2380","--initial-cluster=instance=https://199.204.45.116:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.116:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.116: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-25T06:05:52.518784Z","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-25T06:05:52.518817Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-02-25T06:05:52.518862Z","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-25T06:05:52.520603Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"]} {"level":"info","ts":"2026-02-25T06:05:52.520844Z","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.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.116: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-25T06:05:52.525714Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.303467ms"} {"level":"info","ts":"2026-02-25T06:05:52.533988Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5caa70ef1dd6dc04","cluster-id":"45afccab143d2c82"} {"level":"info","ts":"2026-02-25T06:05:52.534095Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T06:05:52.534135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 0"} {"level":"info","ts":"2026-02-25T06:05:52.534154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5caa70ef1dd6dc04 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T06:05:52.534172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 1"} {"level":"info","ts":"2026-02-25T06:05:52.534224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"warn","ts":"2026-02-25T06:05:52.540234Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T06:05:52.541481Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T06:05:52.542653Z","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-25T06:05:52.544896Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5caa70ef1dd6dc04","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T06:05:52.545241Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5caa70ef1dd6dc04","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T06:05:52.545298Z","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-25T06:05:52.546483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"info","ts":"2026-02-25T06:05:52.546801Z","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-25T06:05:52.546868Z","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-25T06:05:52.547199Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","added-peer-id":"5caa70ef1dd6dc04","added-peer-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-02-25T06:05:52.554499Z","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-25T06:05:52.554676Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-02-25T06:05:52.554711Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-02-25T06:05:52.555086Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5caa70ef1dd6dc04","initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T06:05:52.555161Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T06:05:53.336413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T06:05:53.336926Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T06:05:53.337602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgPreVoteResp from 5caa70ef1dd6dc04 at term 1"} {"level":"info","ts":"2026-02-25T06:05:53.337626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became candidate at term 2"} {"level":"info","ts":"2026-02-25T06:05:53.337632Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgVoteResp from 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-02-25T06:05:53.337648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became leader at term 2"} {"level":"info","ts":"2026-02-25T06:05:53.337703Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5caa70ef1dd6dc04 elected leader 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-02-25T06:05:53.345611Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5caa70ef1dd6dc04","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.116:2379]}","request-path":"/0/members/5caa70ef1dd6dc04/attributes","cluster-id":"45afccab143d2c82","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T06:05:53.345609Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T06:05:53.345644Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T06:05:53.346639Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T06:05:53.34675Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T06:05:53.346786Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T06:05:53.347062Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T06:05:53.347473Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T06:05:53.347505Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T06:05:53.34902Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.116:2379"} {"level":"info","ts":"2026-02-25T06:05:53.350202Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T06:06:18.732875Z","caller":"traceutil/trace.go:171","msg":"trace[2119715352] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"152.31294ms","start":"2026-02-25T06:06:18.580539Z","end":"2026-02-25T06:06:18.732852Z","steps":["trace[2119715352] 'process raft request' (duration: 152.194446ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:06:20.697735Z","caller":"traceutil/trace.go:171","msg":"trace[1810001666] linearizableReadLoop","detail":"{readStateIndex:482; appliedIndex:481; }","duration":"180.362953ms","start":"2026-02-25T06:06:20.517347Z","end":"2026-02-25T06:06:20.69771Z","steps":["trace[1810001666] 'read index received' (duration: 180.140867ms)","trace[1810001666] 'applied index is now lower than readState.Index' (duration: 221.256µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:06:20.697995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.620801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12665"} {"level":"info","ts":"2026-02-25T06:06:20.698155Z","caller":"traceutil/trace.go:171","msg":"trace[890917071] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"317.352431ms","start":"2026-02-25T06:06:20.380789Z","end":"2026-02-25T06:06:20.698142Z","steps":["trace[890917071] 'process raft request' (duration: 316.792915ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:06:20.698164Z","caller":"traceutil/trace.go:171","msg":"trace[432839462] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:469; }","duration":"180.746225ms","start":"2026-02-25T06:06:20.517339Z","end":"2026-02-25T06:06:20.698085Z","steps":["trace[432839462] 'agreement among raft nodes before linearized reading' (duration: 180.474197ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:06:20.698474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:06:20.380759Z","time spent":"317.425694ms","remote":"127.0.0.1:49358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:06:20.973185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.254155ms","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-25T06:06:20.973266Z","caller":"traceutil/trace.go:171","msg":"trace[1007583489] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:469; }","duration":"236.41017ms","start":"2026-02-25T06:06:20.736826Z","end":"2026-02-25T06:06:20.973236Z","steps":["trace[1007583489] 'range keys from in-memory index tree' (duration: 236.153822ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:06:20.973357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.630182ms","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-02-25T06:06:20.973435Z","caller":"traceutil/trace.go:171","msg":"trace[1663112312] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:469; }","duration":"185.718975ms","start":"2026-02-25T06:06:20.787696Z","end":"2026-02-25T06:06:20.973415Z","steps":["trace[1663112312] 'range keys from in-memory index tree' (duration: 185.519929ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:06:23.137739Z","caller":"traceutil/trace.go:171","msg":"trace[1108021512] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"151.484384ms","start":"2026-02-25T06:06:22.986227Z","end":"2026-02-25T06:06:23.137711Z","steps":["trace[1108021512] 'process raft request' (duration: 151.269908ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:06:23.82493Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853968745159727699,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T06:06:24.325951Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853968745159727699,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T06:06:24.370834Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.233380605s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T06:06:24.371253Z","caller":"traceutil/trace.go:171","msg":"trace[1657113493] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"1.384979673s","start":"2026-02-25T06:06:22.986252Z","end":"2026-02-25T06:06:24.371232Z","steps":["trace[1657113493] 'process raft request' (duration: 1.384791457s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:06:24.3714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:06:22.986231Z","time spent":"1.385065124s","remote":"127.0.0.1:49358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:06:24.371426Z","caller":"traceutil/trace.go:171","msg":"trace[1414702839] linearizableReadLoop","detail":"{readStateIndex:525; appliedIndex:523; }","duration":"1.047523757s","start":"2026-02-25T06:06:23.323882Z","end":"2026-02-25T06:06:24.371406Z","steps":["trace[1414702839] 'read index received' (duration: 1.047108095s)","trace[1414702839] 'applied index is now lower than readState.Index' (duration: 414.382µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:06:24.371492Z","caller":"traceutil/trace.go:171","msg":"trace[1838693853] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"1.38424617s","start":"2026-02-25T06:06:22.987225Z","end":"2026-02-25T06:06:24.371471Z","steps":["trace[1838693853] 'process raft request' (duration: 1.383935212s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:06:24.371611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:06:22.987202Z","time spent":"1.384367014s","remote":"127.0.0.1:49358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:06:24.371614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.047730382s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:06:24.371669Z","caller":"traceutil/trace.go:171","msg":"trace[84032318] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:511; }","duration":"1.047825585s","start":"2026-02-25T06:06:23.323832Z","end":"2026-02-25T06:06:24.371657Z","steps":["trace[84032318] 'agreement among raft nodes before linearized reading' (duration: 1.04767457s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:06:24.371706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:06:23.323814Z","time spent":"1.047882317s","remote":"127.0.0.1:49070","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-25T06:06:24.371767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.180151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4021"} {"level":"info","ts":"2026-02-25T06:06:24.371856Z","caller":"traceutil/trace.go:171","msg":"trace[1869180995] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:511; }","duration":"627.304965ms","start":"2026-02-25T06:06:23.744527Z","end":"2026-02-25T06:06:24.371832Z","steps":["trace[1869180995] 'agreement among raft nodes before linearized reading' (duration: 627.111299ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:06:24.371901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:06:23.744508Z","time spent":"627.384477ms","remote":"127.0.0.1:49264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4043,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-02-25T06:06:30.410119Z","caller":"traceutil/trace.go:171","msg":"trace[1208262923] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"259.795086ms","start":"2026-02-25T06:06:30.150299Z","end":"2026-02-25T06:06:30.410095Z","steps":["trace[1208262923] 'process raft request' (duration: 259.606411ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:06:30.565179Z","caller":"traceutil/trace.go:171","msg":"trace[1850151496] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"151.280923ms","start":"2026-02-25T06:06:30.413853Z","end":"2026-02-25T06:06:30.565134Z","steps":["trace[1850151496] 'process raft request' (duration: 151.042377ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:06:30.989095Z","caller":"traceutil/trace.go:171","msg":"trace[1911105434] linearizableReadLoop","detail":"{readStateIndex:568; appliedIndex:567; }","duration":"252.700472ms","start":"2026-02-25T06:06:30.736367Z","end":"2026-02-25T06:06:30.989068Z","steps":["trace[1911105434] 'read index received' (duration: 198.065236ms)","trace[1911105434] 'applied index is now lower than readState.Index' (duration: 54.634186ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:06:30.989348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.968998ms","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-02-25T06:06:30.989321Z","caller":"traceutil/trace.go:171","msg":"trace[488002818] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"419.053357ms","start":"2026-02-25T06:06:30.570179Z","end":"2026-02-25T06:06:30.989233Z","steps":["trace[488002818] 'process raft request' (duration: 364.319288ms)","trace[488002818] 'compare' (duration: 54.254646ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:06:30.989404Z","caller":"traceutil/trace.go:171","msg":"trace[1732295170] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:553; }","duration":"253.086802ms","start":"2026-02-25T06:06:30.736303Z","end":"2026-02-25T06:06:30.989389Z","steps":["trace[1732295170] 'agreement among raft nodes before linearized reading' (duration: 252.866686ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:06:30.989501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:06:30.570152Z","time spent":"419.257743ms","remote":"127.0.0.1:49358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:06:31.770233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.129353ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-02-25T06:06:33.493989Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853968745159727799,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T06:06:33.67711Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.103621835s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T06:06:33.677543Z","caller":"traceutil/trace.go:171","msg":"trace[1713005865] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:571; }","duration":"684.183957ms","start":"2026-02-25T06:06:32.99333Z","end":"2026-02-25T06:06:33.677514Z","steps":["trace[1713005865] 'read index received' (duration: 683.895639ms)","trace[1713005865] 'applied index is now lower than readState.Index' (duration: 287.418µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:06:33.677607Z","caller":"traceutil/trace.go:171","msg":"trace[1755157515] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"1.10419362s","start":"2026-02-25T06:06:32.573377Z","end":"2026-02-25T06:06:33.67757Z","steps":["trace[1755157515] 'process raft request' (duration: 1.103937064s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:06:33.678057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.767587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:06:33.678163Z","caller":"traceutil/trace.go:171","msg":"trace[302718103] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:556; }","duration":"207.920041ms","start":"2026-02-25T06:06:33.47022Z","end":"2026-02-25T06:06:33.67814Z","steps":["trace[302718103] 'agreement among raft nodes before linearized reading' (duration: 207.729386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:06:33.678171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:06:32.57335Z","time spent":"1.104771935s","remote":"127.0.0.1:49358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:06:33.677784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.432823ms","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-02-25T06:06:33.678376Z","caller":"traceutil/trace.go:171","msg":"trace[1286785255] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:556; }","duration":"685.065749ms","start":"2026-02-25T06:06:32.993301Z","end":"2026-02-25T06:06:33.678367Z","steps":["trace[1286785255] 'agreement among raft nodes before linearized reading' (duration: 684.357931ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:06:33.678403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:06:32.993287Z","time spent":"685.110691ms","remote":"127.0.0.1:49358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T06:06:33.677968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.109368ms","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-02-25T06:06:33.678483Z","caller":"traceutil/trace.go:171","msg":"trace[1284497834] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:556; }","duration":"650.670732ms","start":"2026-02-25T06:06:33.027807Z","end":"2026-02-25T06:06:33.678478Z","steps":["trace[1284497834] 'agreement among raft nodes before linearized reading' (duration: 650.119448ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:06:33.678505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:06:33.027791Z","time spent":"650.709843ms","remote":"127.0.0.1:49358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T06:06:33.678015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.422488ms","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-25T06:06:33.678555Z","caller":"traceutil/trace.go:171","msg":"trace[1427929915] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:556; }","duration":"210.990053ms","start":"2026-02-25T06:06:33.46756Z","end":"2026-02-25T06:06:33.67855Z","steps":["trace[1427929915] 'agreement among raft nodes before linearized reading' (duration: 210.419648ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:06:34.31553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.460322ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:06:34.315721Z","caller":"traceutil/trace.go:171","msg":"trace[412697881] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"633.053084ms","start":"2026-02-25T06:06:33.68265Z","end":"2026-02-25T06:06:34.315703Z","steps":["trace[412697881] 'process raft request' (duration: 633.018103ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:06:34.31575Z","caller":"traceutil/trace.go:171","msg":"trace[212675391] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"634.582416ms","start":"2026-02-25T06:06:33.681136Z","end":"2026-02-25T06:06:34.315719Z","steps":["trace[212675391] 'process raft request' (duration: 78.851288ms)","trace[212675391] 'compare' (duration: 555.331437ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:06:34.315791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:06:33.682645Z","time spent":"633.116297ms","remote":"127.0.0.1:49358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:06:34.315793Z","caller":"traceutil/trace.go:171","msg":"trace[1897049979] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"633.174218ms","start":"2026-02-25T06:06:33.68257Z","end":"2026-02-25T06:06:34.315745Z","steps":["trace[1897049979] 'process raft request' (duration: 633.052205ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:06:34.315874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:06:33.681117Z","time spent":"634.682399ms","remote":"127.0.0.1:49358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:06:34.315928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:06:33.682559Z","time spent":"633.297131ms","remote":"127.0.0.1:49358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:06:35.971122Z","caller":"traceutil/trace.go:171","msg":"trace[1041002199] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"283.529641ms","start":"2026-02-25T06:06:35.687522Z","end":"2026-02-25T06:06:35.971052Z","steps":["trace[1041002199] 'process raft request' (duration: 283.147001ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:06:35.971651Z","caller":"traceutil/trace.go:171","msg":"trace[795292851] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"223.941926ms","start":"2026-02-25T06:06:35.747688Z","end":"2026-02-25T06:06:35.97163Z","steps":["trace[795292851] 'process raft request' (duration: 223.773012ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:06:39.570219Z","caller":"traceutil/trace.go:171","msg":"trace[769922177] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"127.822844ms","start":"2026-02-25T06:06:39.442374Z","end":"2026-02-25T06:06:39.570196Z","steps":["trace[769922177] 'process raft request' (duration: 127.446554ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:08:01.041182Z","caller":"traceutil/trace.go:171","msg":"trace[1397324437] linearizableReadLoop","detail":"{readStateIndex:1021; appliedIndex:1020; }","duration":"287.076206ms","start":"2026-02-25T06:08:00.754081Z","end":"2026-02-25T06:08:01.041157Z","steps":["trace[1397324437] 'read index received' (duration: 286.971505ms)","trace[1397324437] 'applied index is now lower than readState.Index' (duration: 103.451µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:08:01.041249Z","caller":"traceutil/trace.go:171","msg":"trace[1693561169] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"351.250529ms","start":"2026-02-25T06:08:00.689956Z","end":"2026-02-25T06:08:01.041206Z","steps":["trace[1693561169] 'process raft request' (duration: 351.067397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:08:01.041404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:08:00.689945Z","time spent":"351.35822ms","remote":"127.0.0.1:49358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:08:01.041433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.313409ms","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-25T06:08:01.041509Z","caller":"traceutil/trace.go:171","msg":"trace[860669446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:985; }","duration":"287.463002ms","start":"2026-02-25T06:08:00.754017Z","end":"2026-02-25T06:08:01.04148Z","steps":["trace[860669446] 'agreement among raft nodes before linearized reading' (duration: 287.259289ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:08:01.046597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.058794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T06:08:01.046637Z","caller":"traceutil/trace.go:171","msg":"trace[1011006781] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:986; }","duration":"198.140656ms","start":"2026-02-25T06:08:00.848486Z","end":"2026-02-25T06:08:01.046627Z","steps":["trace[1011006781] 'agreement among raft nodes before linearized reading' (duration: 198.053665ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:08:01.046661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.152285ms","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-02-25T06:08:01.04673Z","caller":"traceutil/trace.go:171","msg":"trace[1845184497] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:986; }","duration":"262.258916ms","start":"2026-02-25T06:08:00.784453Z","end":"2026-02-25T06:08:01.046711Z","steps":["trace[1845184497] 'agreement among raft nodes before linearized reading' (duration: 262.065033ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:08:01.046823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.249626ms","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-02-25T06:08:01.046882Z","caller":"traceutil/trace.go:171","msg":"trace[527754988] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:986; }","duration":"262.353757ms","start":"2026-02-25T06:08:00.784511Z","end":"2026-02-25T06:08:01.046864Z","steps":["trace[527754988] 'agreement among raft nodes before linearized reading' (duration: 262.174815ms)"],"step_count":1}