{"level":"warn","ts":"2026-02-25T19:27:27.282145Z","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-25T19:27:27.282296Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.100:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.100:2380","--initial-cluster=instance=https://199.19.213.100:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.100:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.100: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-25T19:27:27.282386Z","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-25T19:27:27.282401Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.100:2380"]} {"level":"info","ts":"2026-02-25T19:27:27.282426Z","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-25T19:27:27.283262Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.100:2379"]} {"level":"info","ts":"2026-02-25T19:27:27.283359Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.100:2380"],"listen-peer-urls":["https://199.19.213.100:2380"],"advertise-client-urls":["https://199.19.213.100:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.100:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.100: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-25T19:27:27.28815Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.486839ms"} {"level":"info","ts":"2026-02-25T19:27:27.294097Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"79e65b4197a2a1a","cluster-id":"bba89cbb2a6fc22a"} {"level":"info","ts":"2026-02-25T19:27:27.294174Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T19:27:27.294201Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a became follower at term 0"} {"level":"info","ts":"2026-02-25T19:27:27.294212Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 79e65b4197a2a1a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T19:27:27.294222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a became follower at term 1"} {"level":"info","ts":"2026-02-25T19:27:27.294265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a switched to configuration voters=(548988028781734426)"} {"level":"warn","ts":"2026-02-25T19:27:27.305104Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T19:27:27.306774Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T19:27:27.308192Z","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-25T19:27:27.313471Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"79e65b4197a2a1a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T19:27:27.31366Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"79e65b4197a2a1a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T19:27:27.313777Z","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-25T19:27:27.313865Z","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-25T19:27:27.313899Z","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-25T19:27:27.315477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a switched to configuration voters=(548988028781734426)"} {"level":"info","ts":"2026-02-25T19:27:27.317199Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bba89cbb2a6fc22a","local-member-id":"79e65b4197a2a1a","added-peer-id":"79e65b4197a2a1a","added-peer-peer-urls":["https://199.19.213.100:2380"]} {"level":"info","ts":"2026-02-25T19:27:27.319287Z","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-25T19:27:27.319453Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.100:2380"} {"level":"info","ts":"2026-02-25T19:27:27.319507Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.100:2380"} {"level":"info","ts":"2026-02-25T19:27:27.319801Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"79e65b4197a2a1a","initial-advertise-peer-urls":["https://199.19.213.100:2380"],"listen-peer-urls":["https://199.19.213.100:2380"],"advertise-client-urls":["https://199.19.213.100:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.100:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T19:27:27.319913Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T19:27:27.49522Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T19:27:27.495355Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T19:27:27.495429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a received MsgPreVoteResp from 79e65b4197a2a1a at term 1"} {"level":"info","ts":"2026-02-25T19:27:27.495482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a became candidate at term 2"} {"level":"info","ts":"2026-02-25T19:27:27.495516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a received MsgVoteResp from 79e65b4197a2a1a at term 2"} {"level":"info","ts":"2026-02-25T19:27:27.495554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a became leader at term 2"} {"level":"info","ts":"2026-02-25T19:27:27.4956Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 79e65b4197a2a1a elected leader 79e65b4197a2a1a at term 2"} {"level":"info","ts":"2026-02-25T19:27:27.496734Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"79e65b4197a2a1a","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.100:2379]}","request-path":"/0/members/79e65b4197a2a1a/attributes","cluster-id":"bba89cbb2a6fc22a","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T19:27:27.496939Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T19:27:27.497061Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T19:27:27.497681Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T19:27:27.497035Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T19:27:27.497114Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:27:27.498992Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bba89cbb2a6fc22a","local-member-id":"79e65b4197a2a1a","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:27:27.499148Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:27:27.499246Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:27:27.501366Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.100:2379"} {"level":"info","ts":"2026-02-25T19:27:27.501586Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T19:27:52.483408Z","caller":"traceutil/trace.go:171","msg":"trace[1811461233] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"405.806915ms","start":"2026-02-25T19:27:52.077578Z","end":"2026-02-25T19:27:52.483385Z","steps":["trace[1811461233] 'process raft request' (duration: 405.65817ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:52.484198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:52.077561Z","time spent":"405.933633ms","remote":"127.0.0.1:40164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:27:56.453507Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033909468208448020,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:27:56.926573Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.219009999s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T19:27:56.926993Z","caller":"traceutil/trace.go:171","msg":"trace[271606984] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"1.219488308s","start":"2026-02-25T19:27:55.707481Z","end":"2026-02-25T19:27:56.92697Z","steps":["trace[271606984] 'process raft request' (duration: 1.219343722s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:56.927154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:55.707465Z","time spent":"1.219609747s","remote":"127.0.0.1:40164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:27:57.009613Z","caller":"traceutil/trace.go:171","msg":"trace[442935101] linearizableReadLoop","detail":"{readStateIndex:481; appliedIndex:479; }","duration":"1.056949618s","start":"2026-02-25T19:27:55.952638Z","end":"2026-02-25T19:27:57.009588Z","steps":["trace[442935101] 'read index received' (duration: 974.212967ms)","trace[442935101] 'applied index is now lower than readState.Index' (duration: 82.735231ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:27:57.00982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.057130417s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:27:57.009887Z","caller":"traceutil/trace.go:171","msg":"trace[1382094593] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:462; }","duration":"1.057277803s","start":"2026-02-25T19:27:55.952598Z","end":"2026-02-25T19:27:57.009875Z","steps":["trace[1382094593] 'agreement among raft nodes before linearized reading' (duration: 1.057136796s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:57.009899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.344906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-25T19:27:57.009924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:55.952578Z","time spent":"1.057337013s","remote":"127.0.0.1:39880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T19:27:57.009943Z","caller":"traceutil/trace.go:171","msg":"trace[1571808803] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:462; }","duration":"359.430001ms","start":"2026-02-25T19:27:56.6505Z","end":"2026-02-25T19:27:57.00993Z","steps":["trace[1571808803] 'agreement among raft nodes before linearized reading' (duration: 359.371021ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:57.009976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:56.65048Z","time spent":"359.488842ms","remote":"127.0.0.1:40164","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-02-25T19:27:57.009838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"893.934895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4375"} {"level":"warn","ts":"2026-02-25T19:27:57.010177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.093387ms","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-25T19:27:57.010192Z","caller":"traceutil/trace.go:171","msg":"trace[1496320128] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:462; }","duration":"894.347456ms","start":"2026-02-25T19:27:56.115835Z","end":"2026-02-25T19:27:57.010182Z","steps":["trace[1496320128] 'agreement among raft nodes before linearized reading' (duration: 893.927937ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:57.010206Z","caller":"traceutil/trace.go:171","msg":"trace[1144706166] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:462; }","duration":"509.174123ms","start":"2026-02-25T19:27:56.501023Z","end":"2026-02-25T19:27:57.010197Z","steps":["trace[1144706166] 'agreement among raft nodes before linearized reading' (duration: 509.124382ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:57.010225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:56.11581Z","time spent":"894.406735ms","remote":"127.0.0.1:40058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4398,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-25T19:27:57.010241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:56.501005Z","time spent":"509.227874ms","remote":"127.0.0.1:40164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T19:27:57.166296Z","caller":"traceutil/trace.go:171","msg":"trace[820112878] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"152.130816ms","start":"2026-02-25T19:27:57.014131Z","end":"2026-02-25T19:27:57.166262Z","steps":["trace[820112878] 'process raft request' (duration: 151.943058ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:57.410613Z","caller":"traceutil/trace.go:171","msg":"trace[534771098] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"395.053962ms","start":"2026-02-25T19:27:57.01553Z","end":"2026-02-25T19:27:57.410584Z","steps":["trace[534771098] 'process raft request' (duration: 394.803934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:57.41079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:57.015509Z","time spent":"395.197359ms","remote":"127.0.0.1:40164","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-02-25T19:27:57.410814Z","caller":"traceutil/trace.go:171","msg":"trace[65686621] linearizableReadLoop","detail":"{readStateIndex:483; appliedIndex:482; }","duration":"391.824496ms","start":"2026-02-25T19:27:57.01897Z","end":"2026-02-25T19:27:57.410795Z","steps":["trace[65686621] 'read index received' (duration: 147.321375ms)","trace[65686621] 'applied index is now lower than readState.Index' (duration: 244.501941ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:27:57.410916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.934488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:27:57.41099Z","caller":"traceutil/trace.go:171","msg":"trace[1608952211] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:465; }","duration":"392.006105ms","start":"2026-02-25T19:27:57.018949Z","end":"2026-02-25T19:27:57.410955Z","steps":["trace[1608952211] 'agreement among raft nodes before linearized reading' (duration: 391.907331ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:57.411033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:57.01894Z","time spent":"392.081622ms","remote":"127.0.0.1:39880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T19:27:57.411248Z","caller":"traceutil/trace.go:171","msg":"trace[1412337199] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"391.675061ms","start":"2026-02-25T19:27:57.019557Z","end":"2026-02-25T19:27:57.411232Z","steps":["trace[1412337199] 'process raft request' (duration: 390.947713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:57.411335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:57.01955Z","time spent":"391.736221ms","remote":"127.0.0.1:40058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4567,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:28:03.347698Z","caller":"traceutil/trace.go:171","msg":"trace[783762679] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"340.908944ms","start":"2026-02-25T19:28:03.006769Z","end":"2026-02-25T19:28:03.347678Z","steps":["trace[783762679] 'process raft request' (duration: 340.778526ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:03.347906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:03.006752Z","time spent":"341.063549ms","remote":"127.0.0.1:40164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:28:04.326581Z","caller":"traceutil/trace.go:171","msg":"trace[1804900025] linearizableReadLoop","detail":"{readStateIndex:520; appliedIndex:518; }","duration":"1.053605492s","start":"2026-02-25T19:28:03.272947Z","end":"2026-02-25T19:28:04.326553Z","steps":["trace[1804900025] 'read index received' (duration: 74.595363ms)","trace[1804900025] 'applied index is now lower than readState.Index' (duration: 979.005929ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:04.326719Z","caller":"traceutil/trace.go:171","msg":"trace[1087810728] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"1.250854925s","start":"2026-02-25T19:28:03.075848Z","end":"2026-02-25T19:28:04.326703Z","steps":["trace[1087810728] 'process raft request' (duration: 1.175299859s)","trace[1087810728] 'compare' (duration: 75.304367ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:28:04.326891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:03.075818Z","time spent":"1.250991443s","remote":"127.0.0.1:40164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:28:04.326913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.67566ms","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-02-25T19:28:04.326909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.471037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-25T19:28:04.32692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.053959994s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T19:28:04.326979Z","caller":"traceutil/trace.go:171","msg":"trace[811477321] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:500; }","duration":"757.773255ms","start":"2026-02-25T19:28:03.569187Z","end":"2026-02-25T19:28:04.326961Z","steps":["trace[811477321] 'agreement among raft nodes before linearized reading' (duration: 757.654514ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:04.326985Z","caller":"traceutil/trace.go:171","msg":"trace[705395028] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:500; }","duration":"878.603645ms","start":"2026-02-25T19:28:03.448365Z","end":"2026-02-25T19:28:04.326969Z","steps":["trace[705395028] 'agreement among raft nodes before linearized reading' (duration: 878.442511ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:04.326995Z","caller":"traceutil/trace.go:171","msg":"trace[818831631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:500; }","duration":"1.054084824s","start":"2026-02-25T19:28:03.272891Z","end":"2026-02-25T19:28:04.326976Z","steps":["trace[818831631] 'agreement among raft nodes before linearized reading' (duration: 1.053913952s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:04.32703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:03.272867Z","time spent":"1.054157142s","remote":"127.0.0.1:40164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T19:28:04.327029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:03.569169Z","time spent":"757.849423ms","remote":"127.0.0.1:40164","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-02-25T19:28:04.327027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:03.448343Z","time spent":"878.675773ms","remote":"127.0.0.1:40164","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-02-25T19:28:04.851865Z","caller":"traceutil/trace.go:171","msg":"trace[1255125572] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"106.013438ms","start":"2026-02-25T19:28:04.745829Z","end":"2026-02-25T19:28:04.851842Z","steps":["trace[1255125572] 'process raft request' (duration: 105.835207ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:06.343463Z","caller":"traceutil/trace.go:171","msg":"trace[1053310722] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"988.467468ms","start":"2026-02-25T19:28:05.354978Z","end":"2026-02-25T19:28:06.343446Z","steps":["trace[1053310722] 'process raft request' (duration: 988.379628ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:06.343584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:05.354949Z","time spent":"988.564468ms","remote":"127.0.0.1:40164","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-02-25T19:28:07.951903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000398889s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-25T19:28:07.951997Z","caller":"traceutil/trace.go:171","msg":"trace[784709823] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000543758s","start":"2026-02-25T19:28:05.95143Z","end":"2026-02-25T19:28:07.951973Z","steps":["trace[784709823] 'agreement among raft nodes before linearized reading' (duration: 2.000429823s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:07.952047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:05.951409Z","time spent":"2.00062855s","remote":"127.0.0.1:39880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:28:08.163876Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.82049339s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-25T19:28:09.232891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.068785551s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T19:28:09.232971Z","caller":"traceutil/trace.go:171","msg":"trace[741498274] linearizableReadLoop","detail":"{readStateIndex:529; appliedIndex:527; }","duration":"3.281475862s","start":"2026-02-25T19:28:05.95148Z","end":"2026-02-25T19:28:09.232956Z","steps":["trace[741498274] 'read index received' (duration: 391.931014ms)","trace[741498274] 'applied index is now lower than readState.Index' (duration: 2.889543437s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:28:09.233169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.896413937s","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-02-25T19:28:09.233167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.280895985s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:28:09.233201Z","caller":"traceutil/trace.go:171","msg":"trace[1135438638] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:508; }","duration":"1.28096339s","start":"2026-02-25T19:28:07.952228Z","end":"2026-02-25T19:28:09.233191Z","steps":["trace[1135438638] 'agreement among raft nodes before linearized reading' (duration: 1.280902527s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:09.23321Z","caller":"traceutil/trace.go:171","msg":"trace[903022031] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:508; }","duration":"2.896496066s","start":"2026-02-25T19:28:06.336692Z","end":"2026-02-25T19:28:09.233188Z","steps":["trace[903022031] 'agreement among raft nodes before linearized reading' (duration: 2.896432353s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:09.233227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:06.336662Z","time spent":"2.896561401s","remote":"127.0.0.1:40164","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-02-25T19:28:09.233227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:07.952213Z","time spent":"1.28100874s","remote":"127.0.0.1:39864","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-02-25T19:28:09.233279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.890976379s","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-25T19:28:09.233309Z","caller":"traceutil/trace.go:171","msg":"trace[828048813] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:508; }","duration":"2.891038682s","start":"2026-02-25T19:28:06.342262Z","end":"2026-02-25T19:28:09.233301Z","steps":["trace[828048813] 'agreement among raft nodes before linearized reading' (duration: 2.890926778s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:09.233289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.305334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-25T19:28:09.233354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:06.342244Z","time spent":"2.891104476s","remote":"127.0.0.1:40164","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-02-25T19:28:09.233337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.891006895s","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-25T19:28:09.2334Z","caller":"traceutil/trace.go:171","msg":"trace[897282346] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:508; }","duration":"886.466648ms","start":"2026-02-25T19:28:08.346908Z","end":"2026-02-25T19:28:09.233375Z","steps":["trace[897282346] 'agreement among raft nodes before linearized reading' (duration: 886.239119ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:09.233413Z","caller":"traceutil/trace.go:171","msg":"trace[735496790] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:508; }","duration":"2.89112031s","start":"2026-02-25T19:28:06.342274Z","end":"2026-02-25T19:28:09.233394Z","steps":["trace[735496790] 'agreement among raft nodes before linearized reading' (duration: 2.890878168s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:09.233454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:06.342261Z","time spent":"2.891186964s","remote":"127.0.0.1:40164","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-02-25T19:28:09.233456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:08.346889Z","time spent":"886.556168ms","remote":"127.0.0.1:40164","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":"info","ts":"2026-02-25T19:28:11.323929Z","caller":"traceutil/trace.go:171","msg":"trace[1843825273] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"290.112708ms","start":"2026-02-25T19:28:11.033787Z","end":"2026-02-25T19:28:11.3239Z","steps":["trace[1843825273] 'process raft request' (duration: 289.969049ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:29:38.262239Z","caller":"traceutil/trace.go:171","msg":"trace[1496926859] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"479.506028ms","start":"2026-02-25T19:29:37.782657Z","end":"2026-02-25T19:29:38.262163Z","steps":["trace[1496926859] 'process raft request' (duration: 479.384941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:29:38.262406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:29:37.782643Z","time spent":"479.682824ms","remote":"127.0.0.1:40164","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-02-25T19:29:38.280628Z","caller":"traceutil/trace.go:171","msg":"trace[1298781670] linearizableReadLoop","detail":"{readStateIndex:988; appliedIndex:987; }","duration":"360.807092ms","start":"2026-02-25T19:29:37.919797Z","end":"2026-02-25T19:29:38.280604Z","steps":["trace[1298781670] 'read index received' (duration: 342.397443ms)","trace[1298781670] 'applied index is now lower than readState.Index' (duration: 18.408569ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:29:38.280771Z","caller":"traceutil/trace.go:171","msg":"trace[287725965] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"497.924536ms","start":"2026-02-25T19:29:37.782804Z","end":"2026-02-25T19:29:38.280728Z","steps":["trace[287725965] 'process raft request' (duration: 497.683932ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:29:38.28088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.056086ms","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-25T19:29:38.280926Z","caller":"traceutil/trace.go:171","msg":"trace[1443724174] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:946; }","duration":"361.148744ms","start":"2026-02-25T19:29:37.919762Z","end":"2026-02-25T19:29:38.280911Z","steps":["trace[1443724174] 'agreement among raft nodes before linearized reading' (duration: 360.982178ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:29:38.280934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.09571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T19:29:38.280967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:29:37.919745Z","time spent":"361.212683ms","remote":"127.0.0.1:40164","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-02-25T19:29:38.280983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:29:37.782734Z","time spent":"498.129542ms","remote":"127.0.0.1:40164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:29:38.281015Z","caller":"traceutil/trace.go:171","msg":"trace[110394647] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:946; }","duration":"330.196277ms","start":"2026-02-25T19:29:37.950796Z","end":"2026-02-25T19:29:38.280992Z","steps":["trace[110394647] 'agreement among raft nodes before linearized reading' (duration: 330.08694ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:29:38.281064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:29:37.950785Z","time spent":"330.268036ms","remote":"127.0.0.1:39864","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-02-25T19:29:38.281404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.391277ms","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-25T19:29:38.281672Z","caller":"traceutil/trace.go:171","msg":"trace[1076547641] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:946; }","duration":"361.571284ms","start":"2026-02-25T19:29:37.919963Z","end":"2026-02-25T19:29:38.281534Z","steps":["trace[1076547641] 'agreement among raft nodes before linearized reading' (duration: 360.825082ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:29:38.281718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:29:37.919933Z","time spent":"361.771599ms","remote":"127.0.0.1:40164","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\" "}