{"level":"warn","ts":"2026-02-24T18:51:23.068067Z","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-24T18:51:23.068452Z","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-24T18:51:23.068816Z","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-24T18:51:23.068866Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-02-24T18:51:23.068909Z","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-24T18:51:23.069965Z","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-24T18:51:23.070053Z","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-24T18:51:23.074588Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.32481ms"} {"level":"info","ts":"2026-02-24T18:51:23.083595Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4594e5f716bad646","cluster-id":"ff2e51b364668606"} {"level":"info","ts":"2026-02-24T18:51:23.083758Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=()"} {"level":"info","ts":"2026-02-24T18:51:23.083824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 0"} {"level":"info","ts":"2026-02-24T18:51:23.083851Z","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-24T18:51:23.083895Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 1"} {"level":"info","ts":"2026-02-24T18:51:23.083952Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"warn","ts":"2026-02-24T18:51:23.091111Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-24T18:51:23.092798Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-24T18:51:23.094126Z","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-24T18:51:23.096493Z","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-24T18:51:23.09745Z","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-24T18:51:23.097597Z","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-24T18:51:23.097726Z","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-24T18:51:23.09775Z","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-24T18:51:23.100944Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"info","ts":"2026-02-24T18:51:23.101175Z","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-24T18:51:23.102021Z","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-24T18:51:23.102341Z","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-24T18:51:23.102382Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-24T18:51:23.102518Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-02-24T18:51:23.10254Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-02-24T18:51:23.884479Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 is starting a new election at term 1"} {"level":"info","ts":"2026-02-24T18:51:23.884534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-24T18:51:23.884575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgPreVoteResp from 4594e5f716bad646 at term 1"} {"level":"info","ts":"2026-02-24T18:51:23.884595Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became candidate at term 2"} {"level":"info","ts":"2026-02-24T18:51:23.884606Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgVoteResp from 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-02-24T18:51:23.884622Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became leader at term 2"} {"level":"info","ts":"2026-02-24T18:51:23.884641Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4594e5f716bad646 elected leader 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-02-24T18:51:23.885714Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T18:51:23.886502Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T18:51:23.886511Z","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-24T18:51:23.886543Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T18:51:23.886952Z","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-24T18:51:23.887032Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T18:51:23.887062Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T18:51:23.887083Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-24T18:51:23.88718Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-24T18:51:23.890012Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.144:2379"} {"level":"info","ts":"2026-02-24T18:51:23.890161Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-24T18:51:25.540624Z","caller":"traceutil/trace.go:171","msg":"trace[810876599] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"323.380379ms","start":"2026-02-24T18:51:25.217201Z","end":"2026-02-24T18:51:25.540581Z","steps":["trace[810876599] 'process raft request' (duration: 323.256848ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:51:25.540729Z","caller":"traceutil/trace.go:171","msg":"trace[896447009] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"389.178322ms","start":"2026-02-24T18:51:25.151524Z","end":"2026-02-24T18:51:25.540702Z","steps":["trace[896447009] 'read index received' (duration: 75.708112ms)","trace[896447009] 'applied index is now lower than readState.Index' (duration: 313.4693ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T18:51:25.540993Z","caller":"traceutil/trace.go:171","msg":"trace[283376355] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"398.469196ms","start":"2026-02-24T18:51:25.142498Z","end":"2026-02-24T18:51:25.540967Z","steps":["trace[283376355] 'process raft request' (duration: 397.814372ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:51:25.541058Z","caller":"traceutil/trace.go:171","msg":"trace[57286731] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"309.494993ms","start":"2026-02-24T18:51:25.231518Z","end":"2026-02-24T18:51:25.541013Z","steps":["trace[57286731] 'process raft request' (duration: 309.333402ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:51:25.541211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.628996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-24T18:51:25.541187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.742217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-24T18:51:25.541337Z","caller":"traceutil/trace.go:171","msg":"trace[561840215] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"389.814207ms","start":"2026-02-24T18:51:25.151498Z","end":"2026-02-24T18:51:25.541312Z","steps":["trace[561840215] 'agreement among raft nodes before linearized reading' (duration: 389.438694ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:51:25.54135Z","caller":"traceutil/trace.go:171","msg":"trace[819285363] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"347.938088ms","start":"2026-02-24T18:51:25.193392Z","end":"2026-02-24T18:51:25.54133Z","steps":["trace[819285363] 'agreement among raft nodes before linearized reading' (duration: 347.677536ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:51:25.541407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.749137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-24T18:51:25.541397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:51:25.193378Z","time spent":"348.007938ms","remote":"127.0.0.1:51806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-24T18:51:25.541414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:51:25.151487Z","time spent":"389.900227ms","remote":"127.0.0.1:52104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-02-24T18:51:25.541447Z","caller":"traceutil/trace.go:171","msg":"trace[1594573179] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"244.799898ms","start":"2026-02-24T18:51:25.296637Z","end":"2026-02-24T18:51:25.541436Z","steps":["trace[1594573179] 'agreement among raft nodes before linearized reading' (duration: 244.749527ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:51:25.541549Z","caller":"traceutil/trace.go:171","msg":"trace[1233458322] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"322.416252ms","start":"2026-02-24T18:51:25.219124Z","end":"2026-02-24T18:51:25.54154Z","steps":["trace[1233458322] 'process raft request' (duration: 321.445626ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:51:25.541511Z","caller":"traceutil/trace.go:171","msg":"trace[723198896] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"323.415349ms","start":"2026-02-24T18:51:25.218046Z","end":"2026-02-24T18:51:25.541462Z","steps":["trace[723198896] 'process raft request' (duration: 322.501412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:51:25.541615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.454847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-24T18:51:25.541621Z","caller":"traceutil/trace.go:171","msg":"trace[1577806526] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"323.888843ms","start":"2026-02-24T18:51:25.217723Z","end":"2026-02-24T18:51:25.541611Z","steps":["trace[1577806526] 'process raft request' (duration: 322.790285ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:51:25.541639Z","caller":"traceutil/trace.go:171","msg":"trace[945638329] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"327.492107ms","start":"2026-02-24T18:51:25.214139Z","end":"2026-02-24T18:51:25.541631Z","steps":["trace[945638329] 'agreement among raft nodes before linearized reading' (duration: 327.451537ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:51:25.541647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.677375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.144\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-24T18:51:25.541663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:51:25.214106Z","time spent":"327.551657ms","remote":"127.0.0.1:51806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-02-24T18:51:25.541667Z","caller":"traceutil/trace.go:171","msg":"trace[1617311053] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"322.423642ms","start":"2026-02-24T18:51:25.219236Z","end":"2026-02-24T18:51:25.54166Z","steps":["trace[1617311053] 'process raft request' (duration: 321.355455ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:51:25.542236Z","caller":"traceutil/trace.go:171","msg":"trace[1473399523] range","detail":"{range_begin:/registry/masterleases/199.204.45.144; range_end:; response_count:0; response_revision:11; }","duration":"328.726765ms","start":"2026-02-24T18:51:25.212944Z","end":"2026-02-24T18:51:25.541671Z","steps":["trace[1473399523] 'agreement among raft nodes before linearized reading' (duration: 328.679825ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:51:25.544012Z","caller":"traceutil/trace.go:171","msg":"trace[72851958] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"320.61075ms","start":"2026-02-24T18:51:25.223174Z","end":"2026-02-24T18:51:25.543784Z","steps":["trace[72851958] 'process raft request' (duration: 317.471968ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:51:25.541334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.953399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-24T18:51:25.544279Z","caller":"traceutil/trace.go:171","msg":"trace[100374436] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"247.943989ms","start":"2026-02-24T18:51:25.296308Z","end":"2026-02-24T18:51:25.544252Z","steps":["trace[100374436] 'agreement among raft nodes before linearized reading' (duration: 244.964598ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:51:25.544987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:51:25.212934Z","time spent":"329.35123ms","remote":"127.0.0.1:51740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.204.45.144\" "} {"level":"warn","ts":"2026-02-24T18:51:25.545788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:51:25.142491Z","time spent":"398.557317ms","remote":"127.0.0.1:51784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T18:51:25.546213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.179841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-24T18:51:25.54633Z","caller":"traceutil/trace.go:171","msg":"trace[2002975640] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"280.316612ms","start":"2026-02-24T18:51:25.26596Z","end":"2026-02-24T18:51:25.546277Z","steps":["trace[2002975640] 'agreement among raft nodes before linearized reading' (duration: 279.888579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:51:25.546414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:51:25.219109Z","time spent":"322.464153ms","remote":"127.0.0.1:52216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T18:51:25.54659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:51:25.218042Z","time spent":"323.549721ms","remote":"127.0.0.1:52216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T18:51:25.546858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:51:25.217702Z","time spent":"323.938493ms","remote":"127.0.0.1:52154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T18:51:25.546982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:51:25.21923Z","time spent":"322.457732ms","remote":"127.0.0.1:52216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T18:51:25.547336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.992758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-24T18:51:25.546371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:51:25.23151Z","time spent":"309.660525ms","remote":"127.0.0.1:51972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T18:51:25.541808Z","caller":"traceutil/trace.go:171","msg":"trace[679821426] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"322.532843ms","start":"2026-02-24T18:51:25.219229Z","end":"2026-02-24T18:51:25.541762Z","steps":["trace[679821426] 'process raft request' (duration: 321.394375ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:51:25.548291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:51:25.219207Z","time spent":"329.003808ms","remote":"127.0.0.1:52216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T18:51:25.54998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:51:25.223166Z","time spent":"320.882712ms","remote":"127.0.0.1:51870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2849,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T18:51:25.550078Z","caller":"traceutil/trace.go:171","msg":"trace[1885510979] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"329.047538ms","start":"2026-02-24T18:51:25.218306Z","end":"2026-02-24T18:51:25.547353Z","steps":["trace[1885510979] 'agreement among raft nodes before linearized reading' (duration: 328.998898ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:51:25.550185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:51:25.218301Z","time spent":"331.854098ms","remote":"127.0.0.1:51794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-02-24T18:51:25.54583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:51:25.21718Z","time spent":"323.55385ms","remote":"127.0.0.1:52216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T18:51:46.594097Z","caller":"traceutil/trace.go:171","msg":"trace[381223696] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"374.208394ms","start":"2026-02-24T18:51:46.219859Z","end":"2026-02-24T18:51:46.594067Z","steps":["trace[381223696] 'process raft request' (duration: 374.049885ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:51:46.594278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:51:46.219835Z","time spent":"374.340501ms","remote":"127.0.0.1:51984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T18:51:46.715943Z","caller":"traceutil/trace.go:171","msg":"trace[1984949108] linearizableReadLoop","detail":"{readStateIndex:472; appliedIndex:470; }","duration":"311.098184ms","start":"2026-02-24T18:51:46.404827Z","end":"2026-02-24T18:51:46.715925Z","steps":["trace[1984949108] 'read index received' (duration: 189.063183ms)","trace[1984949108] 'applied index is now lower than readState.Index' (duration: 122.034441ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T18:51:46.716026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.188149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T18:51:46.716043Z","caller":"traceutil/trace.go:171","msg":"trace[2104452448] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:456; }","duration":"311.243092ms","start":"2026-02-24T18:51:46.404794Z","end":"2026-02-24T18:51:46.716037Z","steps":["trace[2104452448] 'agreement among raft nodes before linearized reading' (duration: 311.196879ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:51:46.71606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:51:46.404782Z","time spent":"311.274224ms","remote":"127.0.0.1:51698","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-24T18:51:46.716064Z","caller":"traceutil/trace.go:171","msg":"trace[648927620] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"377.534404ms","start":"2026-02-24T18:51:46.33849Z","end":"2026-02-24T18:51:46.716024Z","steps":["trace[648927620] 'process raft request' (duration: 356.071287ms)","trace[648927620] 'compare' (duration: 21.296648ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T18:51:46.716193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:51:46.338464Z","time spent":"377.661681ms","remote":"127.0.0.1:51984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T18:51:48.530891Z","caller":"traceutil/trace.go:171","msg":"trace[1526693949] linearizableReadLoop","detail":"{readStateIndex:477; appliedIndex:476; }","duration":"144.621216ms","start":"2026-02-24T18:51:48.386245Z","end":"2026-02-24T18:51:48.530866Z","steps":["trace[1526693949] 'read index received' (duration: 144.306079ms)","trace[1526693949] 'applied index is now lower than readState.Index' (duration: 313.827µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T18:51:48.531017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.751353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T18:51:48.530997Z","caller":"traceutil/trace.go:171","msg":"trace[1766226097] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"614.922688ms","start":"2026-02-24T18:51:47.916026Z","end":"2026-02-24T18:51:48.530949Z","steps":["trace[1766226097] 'process raft request' (duration: 614.690655ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:51:48.531055Z","caller":"traceutil/trace.go:171","msg":"trace[459930160] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:460; }","duration":"144.846789ms","start":"2026-02-24T18:51:48.386196Z","end":"2026-02-24T18:51:48.531042Z","steps":["trace[459930160] 'agreement among raft nodes before linearized reading' (duration: 144.770545ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:51:48.531145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:51:47.916014Z","time spent":"615.054015ms","remote":"127.0.0.1:51984","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-24T18:51:52.460732Z","caller":"traceutil/trace.go:171","msg":"trace[1546538756] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"152.525133ms","start":"2026-02-24T18:51:52.308188Z","end":"2026-02-24T18:51:52.460713Z","steps":["trace[1546538756] 'process raft request' (duration: 152.219726ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:51:54.849233Z","caller":"traceutil/trace.go:171","msg":"trace[387909879] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:525; }","duration":"136.312146ms","start":"2026-02-24T18:51:54.712894Z","end":"2026-02-24T18:51:54.849206Z","steps":["trace[387909879] 'read index received' (duration: 136.042152ms)","trace[387909879] 'applied index is now lower than readState.Index' (duration: 268.714µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T18:51:54.8493Z","caller":"traceutil/trace.go:171","msg":"trace[2029590043] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"289.242112ms","start":"2026-02-24T18:51:54.560027Z","end":"2026-02-24T18:51:54.84927Z","steps":["trace[2029590043] 'process raft request' (duration: 289.00609ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:51:54.849392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.470465ms","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-24T18:51:54.849436Z","caller":"traceutil/trace.go:171","msg":"trace[819605690] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:508; }","duration":"136.558829ms","start":"2026-02-24T18:51:54.712864Z","end":"2026-02-24T18:51:54.849423Z","steps":["trace[819605690] 'agreement among raft nodes before linearized reading' (duration: 136.445823ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:51:57.028022Z","caller":"traceutil/trace.go:171","msg":"trace[365103381] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"171.274086ms","start":"2026-02-24T18:51:56.856732Z","end":"2026-02-24T18:51:57.028006Z","steps":["trace[365103381] 'process raft request' (duration: 171.114658ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:51:57.028142Z","caller":"traceutil/trace.go:171","msg":"trace[1516719268] linearizableReadLoop","detail":"{readStateIndex:553; appliedIndex:551; }","duration":"170.180741ms","start":"2026-02-24T18:51:56.857953Z","end":"2026-02-24T18:51:57.028133Z","steps":["trace[1516719268] 'read index received' (duration: 168.062285ms)","trace[1516719268] 'applied index is now lower than readState.Index' (duration: 2.117716ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T18:51:57.028277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.306307ms","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-24T18:51:57.028326Z","caller":"traceutil/trace.go:171","msg":"trace[1178733423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:535; }","duration":"170.374871ms","start":"2026-02-24T18:51:56.85794Z","end":"2026-02-24T18:51:57.028315Z","steps":["trace[1178733423] 'agreement among raft nodes before linearized reading' (duration: 170.232674ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:51:57.028418Z","caller":"traceutil/trace.go:171","msg":"trace[994006816] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"111.468615ms","start":"2026-02-24T18:51:56.916941Z","end":"2026-02-24T18:51:57.02841Z","steps":["trace[994006816] 'process raft request' (duration: 111.021693ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:52:00.914031Z","caller":"traceutil/trace.go:171","msg":"trace[695172284] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"357.25553ms","start":"2026-02-24T18:52:00.556754Z","end":"2026-02-24T18:52:00.914009Z","steps":["trace[695172284] 'process raft request' (duration: 357.095402ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:52:00.914169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:52:00.55674Z","time spent":"357.363825ms","remote":"127.0.0.1:51882","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-24T18:52:02.428711Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440200519034884259,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T18:52:02.929259Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440200519034884259,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T18:52:03.429877Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440200519034884259,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T18:52:03.931058Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440200519034884259,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T18:52:04.10853Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.234878148s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T18:52:04.108869Z","caller":"traceutil/trace.go:171","msg":"trace[1108127719] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"2.235324659s","start":"2026-02-24T18:52:01.873522Z","end":"2026-02-24T18:52:04.108846Z","steps":["trace[1108127719] 'process raft request' (duration: 2.235184512s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:52:04.109047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:52:01.873503Z","time spent":"2.235435574s","remote":"127.0.0.1:51984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T18:52:04.38442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000212963s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-24T18:52:04.384536Z","caller":"traceutil/trace.go:171","msg":"trace[2093028469] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000396412s","start":"2026-02-24T18:52:02.38412Z","end":"2026-02-24T18:52:04.384517Z","steps":["trace[2093028469] 'agreement among raft nodes before linearized reading' (duration: 2.000239755s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:52:04.384588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:52:02.384099Z","time spent":"2.000478786s","remote":"127.0.0.1:51698","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-24T18:52:05.247909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.279755ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-24T18:52:05.24803Z","caller":"traceutil/trace.go:171","msg":"trace[252419735] linearizableReadLoop","detail":"{readStateIndex:571; appliedIndex:569; }","duration":"3.319498567s","start":"2026-02-24T18:52:01.928507Z","end":"2026-02-24T18:52:05.248006Z","steps":["trace[252419735] 'read index received' (duration: 2.180186838s)","trace[252419735] 'applied index is now lower than readState.Index' (duration: 1.139310419s)"],"step_count":2} {"level":"warn","ts":"2026-02-24T18:52:05.248366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.319841493s","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-24T18:52:05.248481Z","caller":"traceutil/trace.go:171","msg":"trace[1482566007] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:551; }","duration":"3.319967749s","start":"2026-02-24T18:52:01.92849Z","end":"2026-02-24T18:52:05.248458Z","steps":["trace[1482566007] 'agreement among raft nodes before linearized reading' (duration: 3.319694086s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:52:05.248561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:52:01.928436Z","time spent":"3.320112645s","remote":"127.0.0.1:51984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T18:52:05.248594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.128048537s","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-24T18:52:05.248665Z","caller":"traceutil/trace.go:171","msg":"trace[1223948299] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:551; }","duration":"2.128160652s","start":"2026-02-24T18:52:03.12049Z","end":"2026-02-24T18:52:05.248651Z","steps":["trace[1223948299] 'agreement among raft nodes before linearized reading' (duration: 2.127899301s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:52:05.248892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.75808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T18:52:05.24892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:52:03.120468Z","time spent":"2.128257527s","remote":"127.0.0.1:51984","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-24T18:52:05.248981Z","caller":"traceutil/trace.go:171","msg":"trace[1700385027] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:551; }","duration":"863.888415ms","start":"2026-02-24T18:52:04.385072Z","end":"2026-02-24T18:52:05.24896Z","steps":["trace[1700385027] 'agreement among raft nodes before linearized reading' (duration: 863.700957ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:52:05.249029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:52:04.385056Z","time spent":"863.964869ms","remote":"127.0.0.1:51710","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-24T18:52:05.249033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.125400253s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-24T18:52:05.249115Z","caller":"traceutil/trace.go:171","msg":"trace[1616905373] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:551; }","duration":"2.125523179s","start":"2026-02-24T18:52:03.123573Z","end":"2026-02-24T18:52:05.249096Z","steps":["trace[1616905373] 'agreement among raft nodes before linearized reading' (duration: 2.125258267s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:52:05.249163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T18:52:03.123559Z","time spent":"2.125595962s","remote":"127.0.0.1:51984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-24T18:52:07.016037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.899439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.144\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-24T18:52:07.016136Z","caller":"traceutil/trace.go:171","msg":"trace[1103766526] range","detail":"{range_begin:/registry/masterleases/199.204.45.144; range_end:; response_count:1; response_revision:564; }","duration":"160.042135ms","start":"2026-02-24T18:52:06.856072Z","end":"2026-02-24T18:52:07.016114Z","steps":["trace[1103766526] 'range keys from in-memory index tree' (duration: 159.778674ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:52:07.152409Z","caller":"traceutil/trace.go:171","msg":"trace[1604355457] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"108.42857ms","start":"2026-02-24T18:52:07.043961Z","end":"2026-02-24T18:52:07.152389Z","steps":["trace[1604355457] 'process raft request' (duration: 107.903427ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:52:17.954007Z","caller":"traceutil/trace.go:171","msg":"trace[642247294] linearizableReadLoop","detail":"{readStateIndex:751; appliedIndex:750; }","duration":"176.215286ms","start":"2026-02-24T18:52:17.777764Z","end":"2026-02-24T18:52:17.953979Z","steps":["trace[642247294] 'read index received' (duration: 175.510488ms)","trace[642247294] 'applied index is now lower than readState.Index' (duration: 703.827µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T18:52:17.954104Z","caller":"traceutil/trace.go:171","msg":"trace[283564846] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"177.08449ms","start":"2026-02-24T18:52:17.77698Z","end":"2026-02-24T18:52:17.954065Z","steps":["trace[283564846] 'process raft request' (duration: 176.374672ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:52:17.954371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.580599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-5dd5756b68\" ","response":"range_response_count:1 size:3769"} {"level":"info","ts":"2026-02-24T18:52:17.954467Z","caller":"traceutil/trace.go:171","msg":"trace[1504888518] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-5dd5756b68; range_end:; response_count:1; response_revision:727; }","duration":"176.740406ms","start":"2026-02-24T18:52:17.777704Z","end":"2026-02-24T18:52:17.954444Z","steps":["trace[1504888518] 'agreement among raft nodes before linearized reading' (duration: 176.431174ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:53:24.146015Z","caller":"traceutil/trace.go:171","msg":"trace[1653742058] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"209.112185ms","start":"2026-02-24T18:53:23.936891Z","end":"2026-02-24T18:53:24.146003Z","steps":["trace[1653742058] 'process raft request' (duration: 208.958331ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:53:24.145994Z","caller":"traceutil/trace.go:171","msg":"trace[44993361] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:992; }","duration":"153.295548ms","start":"2026-02-24T18:53:23.992676Z","end":"2026-02-24T18:53:24.145972Z","steps":["trace[44993361] 'read index received' (duration: 153.114674ms)","trace[44993361] 'applied index is now lower than readState.Index' (duration: 179.984µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T18:53:24.146153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.459091ms","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-24T18:53:24.146191Z","caller":"traceutil/trace.go:171","msg":"trace[1858778268] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:955; }","duration":"153.543832ms","start":"2026-02-24T18:53:23.992638Z","end":"2026-02-24T18:53:24.146182Z","steps":["trace[1858778268] 'agreement among raft nodes before linearized reading' (duration: 153.40568ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:53:24.146384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.41332ms","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-24T18:53:24.14647Z","caller":"traceutil/trace.go:171","msg":"trace[1775642907] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:955; }","duration":"153.543873ms","start":"2026-02-24T18:53:23.992907Z","end":"2026-02-24T18:53:24.146451Z","steps":["trace[1775642907] 'agreement among raft nodes before linearized reading' (duration: 153.299118ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:53:32.384992Z","caller":"traceutil/trace.go:171","msg":"trace[1907351424] linearizableReadLoop","detail":"{readStateIndex:1025; appliedIndex:1024; }","duration":"106.684928ms","start":"2026-02-24T18:53:32.278286Z","end":"2026-02-24T18:53:32.384971Z","steps":["trace[1907351424] 'read index received' (duration: 22.988558ms)","trace[1907351424] 'applied index is now lower than readState.Index' (duration: 83.69508ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T18:53:32.385277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.973323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-24T18:53:32.385308Z","caller":"traceutil/trace.go:171","msg":"trace[943482488] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:985; }","duration":"107.058955ms","start":"2026-02-24T18:53:32.278239Z","end":"2026-02-24T18:53:32.385298Z","steps":["trace[943482488] 'agreement among raft nodes before linearized reading' (duration: 106.934273ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:53:32.385573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.181638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-24T18:53:32.385654Z","caller":"traceutil/trace.go:171","msg":"trace[29094555] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:985; }","duration":"107.2922ms","start":"2026-02-24T18:53:32.278344Z","end":"2026-02-24T18:53:32.385636Z","steps":["trace[29094555] 'agreement among raft nodes before linearized reading' (duration: 106.969183ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T18:53:32.385522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.130366ms","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-24T18:53:32.386053Z","caller":"traceutil/trace.go:171","msg":"trace[1198667269] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:985; }","duration":"107.605296ms","start":"2026-02-24T18:53:32.278239Z","end":"2026-02-24T18:53:32.385844Z","steps":["trace[1198667269] 'agreement among raft nodes before linearized reading' (duration: 106.863451ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:53:32.608185Z","caller":"traceutil/trace.go:171","msg":"trace[571627613] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"217.908017ms","start":"2026-02-24T18:53:32.390248Z","end":"2026-02-24T18:53:32.608156Z","steps":["trace[571627613] 'process raft request' (duration: 217.855895ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T18:53:32.60942Z","caller":"traceutil/trace.go:171","msg":"trace[1952255073] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"218.086791ms","start":"2026-02-24T18:53:32.390224Z","end":"2026-02-24T18:53:32.608311Z","steps":["trace[1952255073] 'process raft request' (duration: 180.856658ms)","trace[1952255073] 'compare' (duration: 36.888946ms)"],"step_count":2}