{"level":"warn","ts":"2026-02-19T08:50:36.703437Z","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-19T08:50:36.703539Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.144: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.144:2380","--initial-cluster=instance=https://199.204.45.144:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.144:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.144: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-19T08:50:36.703614Z","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-19T08:50:36.703635Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-02-19T08:50:36.703663Z","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-19T08:50:36.704294Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"]} {"level":"info","ts":"2026-02-19T08:50:36.704364Z","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.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.144: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-19T08:50:36.708534Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.952803ms"} {"level":"info","ts":"2026-02-19T08:50:36.715578Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4594e5f716bad646","cluster-id":"ff2e51b364668606"} {"level":"info","ts":"2026-02-19T08:50:36.715651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=()"} {"level":"info","ts":"2026-02-19T08:50:36.715669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 0"} {"level":"info","ts":"2026-02-19T08:50:36.715683Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4594e5f716bad646 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-19T08:50:36.715703Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 1"} {"level":"info","ts":"2026-02-19T08:50:36.715742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"warn","ts":"2026-02-19T08:50:36.723029Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-19T08:50:36.725032Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-19T08:50:36.72604Z","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-19T08:50:36.728965Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4594e5f716bad646","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-19T08:50:36.729089Z","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-19T08:50:36.729183Z","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-19T08:50:36.729195Z","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-19T08:50:36.729448Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4594e5f716bad646","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-19T08:50:36.731291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"info","ts":"2026-02-19T08:50:36.731456Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","added-peer-id":"4594e5f716bad646","added-peer-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-02-19T08:50:36.734874Z","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-19T08:50:36.735124Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4594e5f716bad646","initial-advertise-peer-urls":["https://199.204.45.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-19T08:50:36.735151Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-19T08:50:36.735157Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-02-19T08:50:36.735394Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-02-19T08:50:37.116685Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 is starting a new election at term 1"} {"level":"info","ts":"2026-02-19T08:50:37.116761Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-19T08:50:37.11681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgPreVoteResp from 4594e5f716bad646 at term 1"} {"level":"info","ts":"2026-02-19T08:50:37.116833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became candidate at term 2"} {"level":"info","ts":"2026-02-19T08:50:37.116882Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgVoteResp from 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-02-19T08:50:37.1169Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became leader at term 2"} {"level":"info","ts":"2026-02-19T08:50:37.116926Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4594e5f716bad646 elected leader 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-02-19T08:50:37.117912Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4594e5f716bad646","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.144:2379]}","request-path":"/0/members/4594e5f716bad646/attributes","cluster-id":"ff2e51b364668606","publish-timeout":"7s"} {"level":"info","ts":"2026-02-19T08:50:37.118084Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-19T08:50:37.118204Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-19T08:50:37.118355Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T08:50:37.118485Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-19T08:50:37.118721Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-19T08:50:37.119316Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T08:50:37.11951Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T08:50:37.11959Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T08:50:37.121413Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-19T08:50:37.122193Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.144:2379"} {"level":"info","ts":"2026-02-19T08:51:01.166685Z","caller":"traceutil/trace.go:171","msg":"trace[1385192624] linearizableReadLoop","detail":"{readStateIndex:479; appliedIndex:478; }","duration":"218.53875ms","start":"2026-02-19T08:51:00.948119Z","end":"2026-02-19T08:51:01.166658Z","steps":["trace[1385192624] 'read index received' (duration: 218.387429ms)","trace[1385192624] 'applied index is now lower than readState.Index' (duration: 150.271µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T08:51:01.166744Z","caller":"traceutil/trace.go:171","msg":"trace[1345595938] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"445.554933ms","start":"2026-02-19T08:51:00.721166Z","end":"2026-02-19T08:51:01.166721Z","steps":["trace[1345595938] 'process raft request' (duration: 445.350051ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T08:51:01.166867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.239945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T08:51:01.166913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.758771ms","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-19T08:51:01.166974Z","caller":"traceutil/trace.go:171","msg":"trace[37168776] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:461; }","duration":"142.367707ms","start":"2026-02-19T08:51:01.024564Z","end":"2026-02-19T08:51:01.166931Z","steps":["trace[37168776] 'agreement among raft nodes before linearized reading' (duration: 142.251946ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T08:51:01.167009Z","caller":"traceutil/trace.go:171","msg":"trace[1979400838] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:461; }","duration":"218.929972ms","start":"2026-02-19T08:51:00.948053Z","end":"2026-02-19T08:51:01.166983Z","steps":["trace[1979400838] 'agreement among raft nodes before linearized reading' (duration: 218.734741ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T08:51:01.167386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T08:51:00.721143Z","time spent":"445.655723ms","remote":"127.0.0.1:43196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T08:51:04.032878Z","caller":"traceutil/trace.go:171","msg":"trace[637512439] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"128.757405ms","start":"2026-02-19T08:51:03.904063Z","end":"2026-02-19T08:51:04.032821Z","steps":["trace[637512439] 'process raft request' (duration: 128.322902ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T08:51:06.878535Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440200399215013710,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-19T08:51:06.928492Z","caller":"traceutil/trace.go:171","msg":"trace[879986715] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:526; }","duration":"550.011628ms","start":"2026-02-19T08:51:06.378446Z","end":"2026-02-19T08:51:06.928458Z","steps":["trace[879986715] 'read index received' (duration: 549.797346ms)","trace[879986715] 'applied index is now lower than readState.Index' (duration: 212.892µs)"],"step_count":2} {"level":"warn","ts":"2026-02-19T08:51:06.928753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.268179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-19T08:51:06.928836Z","caller":"traceutil/trace.go:171","msg":"trace[776024591] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:508; }","duration":"550.40867ms","start":"2026-02-19T08:51:06.378408Z","end":"2026-02-19T08:51:06.928816Z","steps":["trace[776024591] 'agreement among raft nodes before linearized reading' (duration: 550.178698ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T08:51:06.928958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T08:51:06.378382Z","time spent":"550.53021ms","remote":"127.0.0.1:43196","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-02-19T08:51:06.92898Z","caller":"traceutil/trace.go:171","msg":"trace[1425256647] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"797.622176ms","start":"2026-02-19T08:51:06.131326Z","end":"2026-02-19T08:51:06.928948Z","steps":["trace[1425256647] 'process raft request' (duration: 796.870411ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T08:51:06.929154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T08:51:06.131309Z","time spent":"797.740196ms","remote":"127.0.0.1:43122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4213,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T08:51:09.40529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.901348ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T08:51:09.405384Z","caller":"traceutil/trace.go:171","msg":"trace[1678711623] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"160.888391ms","start":"2026-02-19T08:51:09.244479Z","end":"2026-02-19T08:51:09.405367Z","steps":["trace[1678711623] 'process raft request' (duration: 42.66265ms)","trace[1678711623] 'compare' (duration: 117.787568ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T08:51:09.410731Z","caller":"traceutil/trace.go:171","msg":"trace[868844837] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"143.813036ms","start":"2026-02-19T08:51:09.266889Z","end":"2026-02-19T08:51:09.410702Z","steps":["trace[868844837] 'process raft request' (duration: 143.682775ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T08:51:10.170423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.937884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T08:51:10.170511Z","caller":"traceutil/trace.go:171","msg":"trace[1421923047] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:516; }","duration":"146.105745ms","start":"2026-02-19T08:51:10.024385Z","end":"2026-02-19T08:51:10.170491Z","steps":["trace[1421923047] 'range keys from in-memory index tree' (duration: 145.851603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T08:51:10.170813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.441285ms","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-19T08:51:10.170909Z","caller":"traceutil/trace.go:171","msg":"trace[1908822356] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:516; }","duration":"630.576676ms","start":"2026-02-19T08:51:09.54031Z","end":"2026-02-19T08:51:10.170886Z","steps":["trace[1908822356] 'range keys from in-memory index tree' (duration: 630.241564ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T08:51:10.170966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T08:51:09.540293Z","time spent":"630.663616ms","remote":"127.0.0.1:43196","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-19T08:51:10.171161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.859087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T08:51:10.171264Z","caller":"traceutil/trace.go:171","msg":"trace[1947815943] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:516; }","duration":"603.006258ms","start":"2026-02-19T08:51:09.568228Z","end":"2026-02-19T08:51:10.171234Z","steps":["trace[1947815943] 'range keys from in-memory index tree' (duration: 602.736307ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T08:51:10.171328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T08:51:09.568199Z","time spent":"603.112069ms","remote":"127.0.0.1:43040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"info","ts":"2026-02-19T08:51:14.587194Z","caller":"traceutil/trace.go:171","msg":"trace[944123401] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:571; }","duration":"340.152322ms","start":"2026-02-19T08:51:14.247016Z","end":"2026-02-19T08:51:14.587169Z","steps":["trace[944123401] 'read index received' (duration: 339.932421ms)","trace[944123401] 'applied index is now lower than readState.Index' (duration: 215.721µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T08:51:14.587537Z","caller":"traceutil/trace.go:171","msg":"trace[572572304] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"536.894807ms","start":"2026-02-19T08:51:14.050603Z","end":"2026-02-19T08:51:14.587498Z","steps":["trace[572572304] 'process raft request' (duration: 536.423174ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T08:51:14.587711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T08:51:14.050573Z","time spent":"537.038988ms","remote":"127.0.0.1:43128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T08:51:14.587802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.774776ms","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-19T08:51:14.587967Z","caller":"traceutil/trace.go:171","msg":"trace[128443777] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:552; }","duration":"341.002637ms","start":"2026-02-19T08:51:14.246951Z","end":"2026-02-19T08:51:14.587953Z","steps":["trace[128443777] 'agreement among raft nodes before linearized reading' (duration: 340.316233ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T08:51:14.58834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T08:51:14.246925Z","time spent":"341.401139ms","remote":"127.0.0.1:43196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-19T08:51:15.435572Z","caller":"traceutil/trace.go:171","msg":"trace[313986454] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"469.886817ms","start":"2026-02-19T08:51:14.965664Z","end":"2026-02-19T08:51:15.435551Z","steps":["trace[313986454] 'process raft request' (duration: 469.722206ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T08:51:15.435728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T08:51:14.965635Z","time spent":"470.008408ms","remote":"127.0.0.1:43196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T08:51:16.458839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.39684ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-19T08:51:16.458955Z","caller":"traceutil/trace.go:171","msg":"trace[15236929] linearizableReadLoop","detail":"{readStateIndex:575; appliedIndex:573; }","duration":"1.179778751s","start":"2026-02-19T08:51:15.279158Z","end":"2026-02-19T08:51:16.458937Z","steps":["trace[15236929] 'read index received' (duration: 156.25766ms)","trace[15236929] 'applied index is now lower than readState.Index' (duration: 1.023519711s)"],"step_count":2} {"level":"warn","ts":"2026-02-19T08:51:16.459174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.180001982s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-19T08:51:16.459207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.490379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T08:51:16.459255Z","caller":"traceutil/trace.go:171","msg":"trace[821855413] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:554; }","duration":"1.180124153s","start":"2026-02-19T08:51:15.279111Z","end":"2026-02-19T08:51:16.459235Z","steps":["trace[821855413] 'agreement among raft nodes before linearized reading' (duration: 1.179889502s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T08:51:16.459299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T08:51:15.279092Z","time spent":"1.180199853s","remote":"127.0.0.1:43196","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":"info","ts":"2026-02-19T08:51:16.459304Z","caller":"traceutil/trace.go:171","msg":"trace[790427784] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:554; }","duration":"433.603659ms","start":"2026-02-19T08:51:16.025674Z","end":"2026-02-19T08:51:16.459278Z","steps":["trace[790427784] 'agreement among raft nodes before linearized reading' (duration: 433.386048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T08:51:16.459354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T08:51:16.02563Z","time spent":"433.71442ms","remote":"127.0.0.1:42938","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-19T08:51:16.459446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.03525413s","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-19T08:51:16.459557Z","caller":"traceutil/trace.go:171","msg":"trace[974960984] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:554; }","duration":"1.035419132s","start":"2026-02-19T08:51:15.424104Z","end":"2026-02-19T08:51:16.459523Z","steps":["trace[974960984] 'agreement among raft nodes before linearized reading' (duration: 1.034920919s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T08:51:16.459622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T08:51:15.42408Z","time spent":"1.035531242s","remote":"127.0.0.1:43196","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":"info","ts":"2026-02-19T08:51:17.172219Z","caller":"traceutil/trace.go:171","msg":"trace[101596383] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"108.558493ms","start":"2026-02-19T08:51:17.063644Z","end":"2026-02-19T08:51:17.172203Z","steps":["trace[101596383] 'process raft request' (duration: 108.453022ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T08:51:18.731104Z","caller":"traceutil/trace.go:171","msg":"trace[389404746] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"399.014212ms","start":"2026-02-19T08:51:18.332052Z","end":"2026-02-19T08:51:18.731066Z","steps":["trace[389404746] 'process raft request' (duration: 398.856011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T08:51:18.731251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T08:51:18.33202Z","time spent":"399.164793ms","remote":"127.0.0.1:42996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T08:51:18.731102Z","caller":"traceutil/trace.go:171","msg":"trace[1863472960] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"255.787331ms","start":"2026-02-19T08:51:18.475256Z","end":"2026-02-19T08:51:18.731043Z","steps":["trace[1863472960] 'read index received' (duration: 255.65355ms)","trace[1863472960] 'applied index is now lower than readState.Index' (duration: 132.601µs)"],"step_count":2} {"level":"warn","ts":"2026-02-19T08:51:18.731467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.141753ms","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-19T08:51:18.731582Z","caller":"traceutil/trace.go:171","msg":"trace[1691446275] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:578; }","duration":"256.312295ms","start":"2026-02-19T08:51:18.475219Z","end":"2026-02-19T08:51:18.731531Z","steps":["trace[1691446275] 'agreement among raft nodes before linearized reading' (duration: 255.940143ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T08:51:18.734993Z","caller":"traceutil/trace.go:171","msg":"trace[1053173575] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"207.188481ms","start":"2026-02-19T08:51:18.527764Z","end":"2026-02-19T08:51:18.734953Z","steps":["trace[1053173575] 'process raft request' (duration: 207.02726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T08:51:18.735081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.614155ms","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-19T08:51:18.735138Z","caller":"traceutil/trace.go:171","msg":"trace[1826328670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:580; }","duration":"259.731606ms","start":"2026-02-19T08:51:18.47539Z","end":"2026-02-19T08:51:18.735121Z","steps":["trace[1826328670] 'agreement among raft nodes before linearized reading' (duration: 259.538375ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T08:51:18.735363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.459806ms","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-19T08:51:18.735403Z","caller":"traceutil/trace.go:171","msg":"trace[503647908] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:580; }","duration":"123.601097ms","start":"2026-02-19T08:51:18.611789Z","end":"2026-02-19T08:51:18.73539Z","steps":["trace[503647908] 'agreement among raft nodes before linearized reading' (duration: 123.370665ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T08:52:53.700527Z","caller":"traceutil/trace.go:171","msg":"trace[1668709647] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"426.295357ms","start":"2026-02-19T08:52:53.274204Z","end":"2026-02-19T08:52:53.700499Z","steps":["trace[1668709647] 'process raft request' (duration: 426.100122ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T08:52:53.700699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T08:52:53.274187Z","time spent":"426.422629ms","remote":"127.0.0.1:43196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}