{"level":"warn","ts":"2026-02-24T12:55:10.339376Z","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-24T12:55:10.33948Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.169: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.169:2380","--initial-cluster=instance=https://199.19.213.169:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.169:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.169: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-24T12:55:10.339557Z","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-24T12:55:10.339578Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.169:2380"]} {"level":"info","ts":"2026-02-24T12:55:10.339595Z","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-24T12:55:10.340216Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.169:2379"]} {"level":"info","ts":"2026-02-24T12:55:10.340304Z","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.169:2380"],"listen-peer-urls":["https://199.19.213.169:2380"],"advertise-client-urls":["https://199.19.213.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.169: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-24T12:55:10.345823Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.271426ms"} {"level":"info","ts":"2026-02-24T12:55:10.353427Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a742f9f5aa7326e5","cluster-id":"a6b466adbadbbcd5"} {"level":"info","ts":"2026-02-24T12:55:10.353515Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 switched to configuration voters=()"} {"level":"info","ts":"2026-02-24T12:55:10.353549Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 became follower at term 0"} {"level":"info","ts":"2026-02-24T12:55:10.353571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a742f9f5aa7326e5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-24T12:55:10.353584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 became follower at term 1"} {"level":"info","ts":"2026-02-24T12:55:10.353634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 switched to configuration voters=(12052470386318845669)"} {"level":"warn","ts":"2026-02-24T12:55:10.359111Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-24T12:55:10.36069Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-24T12:55:10.362241Z","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-24T12:55:10.364619Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a742f9f5aa7326e5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-24T12:55:10.365724Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a742f9f5aa7326e5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-24T12:55:10.365932Z","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-24T12:55:10.366909Z","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-24T12:55:10.367028Z","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-24T12:55:10.36706Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 switched to configuration voters=(12052470386318845669)"} {"level":"info","ts":"2026-02-24T12:55:10.37109Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a6b466adbadbbcd5","local-member-id":"a742f9f5aa7326e5","added-peer-id":"a742f9f5aa7326e5","added-peer-peer-urls":["https://199.19.213.169:2380"]} {"level":"info","ts":"2026-02-24T12:55:10.373239Z","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-24T12:55:10.373509Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a742f9f5aa7326e5","initial-advertise-peer-urls":["https://199.19.213.169:2380"],"listen-peer-urls":["https://199.19.213.169:2380"],"advertise-client-urls":["https://199.19.213.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-24T12:55:10.373558Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-24T12:55:10.373692Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.169:2380"} {"level":"info","ts":"2026-02-24T12:55:10.373709Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.169:2380"} {"level":"info","ts":"2026-02-24T12:55:10.954554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 is starting a new election at term 1"} {"level":"info","ts":"2026-02-24T12:55:10.954647Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-24T12:55:10.954695Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 received MsgPreVoteResp from a742f9f5aa7326e5 at term 1"} {"level":"info","ts":"2026-02-24T12:55:10.954716Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 became candidate at term 2"} {"level":"info","ts":"2026-02-24T12:55:10.954728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 received MsgVoteResp from a742f9f5aa7326e5 at term 2"} {"level":"info","ts":"2026-02-24T12:55:10.95475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 became leader at term 2"} {"level":"info","ts":"2026-02-24T12:55:10.954763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a742f9f5aa7326e5 elected leader a742f9f5aa7326e5 at term 2"} {"level":"info","ts":"2026-02-24T12:55:10.973663Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T12:55:11.008317Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a742f9f5aa7326e5","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.169:2379]}","request-path":"/0/members/a742f9f5aa7326e5/attributes","cluster-id":"a6b466adbadbbcd5","publish-timeout":"7s"} {"level":"info","ts":"2026-02-24T12:55:11.008593Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T12:55:11.008737Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T12:55:11.008759Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a6b466adbadbbcd5","local-member-id":"a742f9f5aa7326e5","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T12:55:11.008945Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T12:55:11.009004Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T12:55:11.009066Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-24T12:55:11.00908Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-24T12:55:11.012509Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.169:2379"} {"level":"info","ts":"2026-02-24T12:55:11.023761Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-24T12:55:16.759933Z","caller":"traceutil/trace.go:171","msg":"trace[925122928] linearizableReadLoop","detail":"{readStateIndex:285; appliedIndex:283; }","duration":"170.57741ms","start":"2026-02-24T12:55:16.589328Z","end":"2026-02-24T12:55:16.759905Z","steps":["trace[925122928] 'read index received' (duration: 129.874769ms)","trace[925122928] 'applied index is now lower than readState.Index' (duration: 40.701741ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T12:55:16.760002Z","caller":"traceutil/trace.go:171","msg":"trace[1774370280] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"171.941755ms","start":"2026-02-24T12:55:16.588024Z","end":"2026-02-24T12:55:16.759966Z","steps":["trace[1774370280] 'process raft request' (duration: 171.827587ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:55:16.760095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.71907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-24T12:55:16.760147Z","caller":"traceutil/trace.go:171","msg":"trace[144281913] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:273; }","duration":"170.834217ms","start":"2026-02-24T12:55:16.589302Z","end":"2026-02-24T12:55:16.760137Z","steps":["trace[144281913] 'agreement among raft nodes before linearized reading' (duration: 170.711139ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:55:16.760048Z","caller":"traceutil/trace.go:171","msg":"trace[79689453] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"174.188532ms","start":"2026-02-24T12:55:16.585835Z","end":"2026-02-24T12:55:16.760024Z","steps":["trace[79689453] 'process raft request' (duration: 133.361782ms)","trace[79689453] 'compare' (duration: 40.505258ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T12:55:17.868064Z","caller":"traceutil/trace.go:171","msg":"trace[1771650122] linearizableReadLoop","detail":"{readStateIndex:293; appliedIndex:292; }","duration":"129.109655ms","start":"2026-02-24T12:55:17.738923Z","end":"2026-02-24T12:55:17.868032Z","steps":["trace[1771650122] 'read index received' (duration: 128.954924ms)","trace[1771650122] 'applied index is now lower than readState.Index' (duration: 153.441µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T12:55:17.868177Z","caller":"traceutil/trace.go:171","msg":"trace[686120511] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"142.204544ms","start":"2026-02-24T12:55:17.725939Z","end":"2026-02-24T12:55:17.868144Z","steps":["trace[686120511] 'process raft request' (duration: 141.949446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:55:17.868232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.289038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-02-24T12:55:17.868284Z","caller":"traceutil/trace.go:171","msg":"trace[140583317] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:281; }","duration":"129.394455ms","start":"2026-02-24T12:55:17.738873Z","end":"2026-02-24T12:55:17.868268Z","steps":["trace[140583317] 'agreement among raft nodes before linearized reading' (duration: 129.290348ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:55:35.343727Z","caller":"traceutil/trace.go:171","msg":"trace[1040068479] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"482.33623ms","start":"2026-02-24T12:55:34.861338Z","end":"2026-02-24T12:55:35.343674Z","steps":["trace[1040068479] 'process raft request' (duration: 482.019654ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:55:35.344676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:55:34.861315Z","time spent":"482.542537ms","remote":"127.0.0.1:56630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T12:55:39.337071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.083092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T12:55:39.337152Z","caller":"traceutil/trace.go:171","msg":"trace[2115561974] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:465; }","duration":"464.201341ms","start":"2026-02-24T12:55:38.872931Z","end":"2026-02-24T12:55:39.337133Z","steps":["trace[2115561974] 'range keys from in-memory index tree' (duration: 464.029762ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:55:39.337191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:55:38.872913Z","time spent":"464.27021ms","remote":"127.0.0.1:56428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T12:55:39.348669Z","caller":"traceutil/trace.go:171","msg":"trace[1953054966] linearizableReadLoop","detail":"{readStateIndex:483; appliedIndex:482; }","duration":"450.526513ms","start":"2026-02-24T12:55:38.898119Z","end":"2026-02-24T12:55:39.348646Z","steps":["trace[1953054966] 'read index received' (duration: 406.514347ms)","trace[1953054966] 'applied index is now lower than readState.Index' (duration: 44.010706ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T12:55:39.34885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.705412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4021"} {"level":"info","ts":"2026-02-24T12:55:39.348934Z","caller":"traceutil/trace.go:171","msg":"trace[105077586] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:465; }","duration":"450.831711ms","start":"2026-02-24T12:55:38.898083Z","end":"2026-02-24T12:55:39.348915Z","steps":["trace[105077586] 'agreement among raft nodes before linearized reading' (duration: 450.659562ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:55:39.348978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:55:38.898065Z","time spent":"450.904839ms","remote":"127.0.0.1:56562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4045,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-02-24T12:55:40.115826Z","caller":"traceutil/trace.go:171","msg":"trace[1359115259] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"135.184347ms","start":"2026-02-24T12:55:39.980622Z","end":"2026-02-24T12:55:40.115807Z","steps":["trace[1359115259] 'process raft request' (duration: 135.087948ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:55:40.367884Z","caller":"traceutil/trace.go:171","msg":"trace[1207880132] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"153.227609ms","start":"2026-02-24T12:55:40.214628Z","end":"2026-02-24T12:55:40.367855Z","steps":["trace[1207880132] 'process raft request' (duration: 153.09098ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:55:41.024669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.209149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T12:55:41.024765Z","caller":"traceutil/trace.go:171","msg":"trace[768356076] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:469; }","duration":"153.366527ms","start":"2026-02-24T12:55:40.871382Z","end":"2026-02-24T12:55:41.024748Z","steps":["trace[768356076] 'range keys from in-memory index tree' (duration: 153.1048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:55:41.024796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.809553ms","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-24T12:55:41.024874Z","caller":"traceutil/trace.go:171","msg":"trace[799214346] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:469; }","duration":"459.907682ms","start":"2026-02-24T12:55:40.564948Z","end":"2026-02-24T12:55:41.024856Z","steps":["trace[799214346] 'range keys from in-memory index tree' (duration: 459.701924ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:55:41.024919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:55:40.564908Z","time spent":"460.002111ms","remote":"127.0.0.1:56630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-24T12:55:41.268991Z","caller":"traceutil/trace.go:171","msg":"trace[1825520798] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"240.750165ms","start":"2026-02-24T12:55:41.028219Z","end":"2026-02-24T12:55:41.268969Z","steps":["trace[1825520798] 'process raft request' (duration: 240.597466ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:55:41.86962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.798367ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T12:55:41.869761Z","caller":"traceutil/trace.go:171","msg":"trace[2039659775] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:470; }","duration":"331.950086ms","start":"2026-02-24T12:55:41.537789Z","end":"2026-02-24T12:55:41.869739Z","steps":["trace[2039659775] 'range keys from in-memory index tree' (duration: 331.781798ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:55:41.869799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.004485ms","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-24T12:55:41.869915Z","caller":"traceutil/trace.go:171","msg":"trace[1654456285] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:470; }","duration":"434.219602ms","start":"2026-02-24T12:55:41.435655Z","end":"2026-02-24T12:55:41.869875Z","steps":["trace[1654456285] 'range keys from in-memory index tree' (duration: 433.846766ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:55:41.869968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:55:41.435635Z","time spent":"434.322472ms","remote":"127.0.0.1:56630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-24T12:55:42.062805Z","caller":"traceutil/trace.go:171","msg":"trace[1964569551] linearizableReadLoop","detail":"{readStateIndex:489; appliedIndex:488; }","duration":"110.733902ms","start":"2026-02-24T12:55:41.952058Z","end":"2026-02-24T12:55:42.062792Z","steps":["trace[1964569551] 'read index received' (duration: 110.612632ms)","trace[1964569551] 'applied index is now lower than readState.Index' (duration: 120.49µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T12:55:42.06289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.824621ms","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-24T12:55:42.062876Z","caller":"traceutil/trace.go:171","msg":"trace[1977482704] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"190.054914ms","start":"2026-02-24T12:55:41.872789Z","end":"2026-02-24T12:55:42.062844Z","steps":["trace[1977482704] 'process raft request' (duration: 189.911845ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:55:42.06291Z","caller":"traceutil/trace.go:171","msg":"trace[500386816] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:471; }","duration":"110.884771ms","start":"2026-02-24T12:55:41.952018Z","end":"2026-02-24T12:55:42.062903Z","steps":["trace[500386816] 'agreement among raft nodes before linearized reading' (duration: 110.839791ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:55:42.263378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.192633ms","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":"warn","ts":"2026-02-24T12:55:42.263543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.989681ms","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-24T12:55:42.263778Z","caller":"traceutil/trace.go:171","msg":"trace[738234030] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:471; }","duration":"101.64291ms","start":"2026-02-24T12:55:42.162109Z","end":"2026-02-24T12:55:42.263752Z","steps":["trace[738234030] 'range keys from in-memory index tree' (duration: 101.081454ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:55:42.263883Z","caller":"traceutil/trace.go:171","msg":"trace[153790994] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:471; }","duration":"144.360049ms","start":"2026-02-24T12:55:42.11948Z","end":"2026-02-24T12:55:42.26384Z","steps":["trace[153790994] 'range keys from in-memory index tree' (duration: 143.816132ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:55:46.260601Z","caller":"traceutil/trace.go:171","msg":"trace[635120143] linearizableReadLoop","detail":"{readStateIndex:525; appliedIndex:524; }","duration":"185.372475ms","start":"2026-02-24T12:55:46.075213Z","end":"2026-02-24T12:55:46.260585Z","steps":["trace[635120143] 'read index received' (duration: 185.287665ms)","trace[635120143] 'applied index is now lower than readState.Index' (duration: 84.29µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T12:55:46.260722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.499915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-24T12:55:46.260931Z","caller":"traceutil/trace.go:171","msg":"trace[1586501589] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:506; }","duration":"185.712524ms","start":"2026-02-24T12:55:46.075178Z","end":"2026-02-24T12:55:46.260891Z","steps":["trace[1586501589] 'agreement among raft nodes before linearized reading' (duration: 185.478175ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:55:46.260748Z","caller":"traceutil/trace.go:171","msg":"trace[1778829775] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"406.594839ms","start":"2026-02-24T12:55:45.854145Z","end":"2026-02-24T12:55:46.26074Z","steps":["trace[1778829775] 'process raft request' (duration: 406.353259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:55:46.261187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:55:45.854117Z","time spent":"407.027748ms","remote":"127.0.0.1:56630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T12:55:46.949896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.22463ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T12:55:46.95001Z","caller":"traceutil/trace.go:171","msg":"trace[2143440449] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:525; }","duration":"625.284936ms","start":"2026-02-24T12:55:46.324707Z","end":"2026-02-24T12:55:46.949992Z","steps":["trace[2143440449] 'read index received' (duration: 445.648596ms)","trace[2143440449] 'applied index is now lower than readState.Index' (duration: 179.63508ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T12:55:46.950074Z","caller":"traceutil/trace.go:171","msg":"trace[1810675200] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"685.469339ms","start":"2026-02-24T12:55:46.26453Z","end":"2026-02-24T12:55:46.95Z","steps":["trace[1810675200] 'process raft request' (duration: 505.874629ms)","trace[1810675200] 'compare' (duration: 178.841361ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T12:55:46.950138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.432205ms","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-24T12:55:46.950199Z","caller":"traceutil/trace.go:171","msg":"trace[323487553] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:507; }","duration":"625.549775ms","start":"2026-02-24T12:55:46.324635Z","end":"2026-02-24T12:55:46.950185Z","steps":["trace[323487553] 'agreement among raft nodes before linearized reading' (duration: 625.437715ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:55:46.950231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:55:46.324609Z","time spent":"625.616305ms","remote":"127.0.0.1:56630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T12:55:46.950353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.468544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-z7mhn\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-24T12:55:46.950393Z","caller":"traceutil/trace.go:171","msg":"trace[1270413444] range","detail":"{range_begin:/registry/pods/kube-system/cilium-z7mhn; range_end:; response_count:1; response_revision:507; }","duration":"452.521364ms","start":"2026-02-24T12:55:46.497862Z","end":"2026-02-24T12:55:46.950383Z","steps":["trace[1270413444] 'agreement among raft nodes before linearized reading' (duration: 452.452954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:55:46.95042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:55:46.497857Z","time spent":"452.556634ms","remote":"127.0.0.1:56566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16680,"request content":"key:\"/registry/pods/kube-system/cilium-z7mhn\" "} {"level":"warn","ts":"2026-02-24T12:55:46.950527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:55:46.264513Z","time spent":"685.643668ms","remote":"127.0.0.1:56630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T12:55:46.950124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.845507ms","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-24T12:55:46.950625Z","caller":"traceutil/trace.go:171","msg":"trace[1483030921] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:507; }","duration":"459.337186ms","start":"2026-02-24T12:55:46.491269Z","end":"2026-02-24T12:55:46.950606Z","steps":["trace[1483030921] 'agreement among raft nodes before linearized reading' (duration: 458.813177ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:55:46.950679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:55:46.491196Z","time spent":"459.467876ms","remote":"127.0.0.1:56630","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-24T12:55:47.791174Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2802818484185142627,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T12:55:48.110498Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.155839532s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T12:55:48.181449Z","caller":"traceutil/trace.go:171","msg":"trace[2062478364] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"1.226999667s","start":"2026-02-24T12:55:46.954409Z","end":"2026-02-24T12:55:48.181408Z","steps":["trace[2062478364] 'process raft request' (duration: 1.156370951s)","trace[2062478364] 'compare' (duration: 70.106866ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T12:55:48.181582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:55:46.954384Z","time spent":"1.227127807s","remote":"127.0.0.1:56630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T12:55:48.710751Z","caller":"traceutil/trace.go:171","msg":"trace[1295616020] linearizableReadLoop","detail":"{readStateIndex:529; appliedIndex:526; }","duration":"1.420079395s","start":"2026-02-24T12:55:47.29065Z","end":"2026-02-24T12:55:48.710729Z","steps":["trace[1295616020] 'read index received' (duration: 820.142584ms)","trace[1295616020] 'applied index is now lower than readState.Index' (duration: 599.935871ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T12:55:48.710849Z","caller":"traceutil/trace.go:171","msg":"trace[235426110] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"1.756200993s","start":"2026-02-24T12:55:46.954639Z","end":"2026-02-24T12:55:48.71084Z","steps":["trace[235426110] 'process raft request' (duration: 1.682801448s)","trace[235426110] 'compare' (duration: 73.043375ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T12:55:48.710964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:55:46.95462Z","time spent":"1.756247683s","remote":"127.0.0.1:56630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T12:55:48.710964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.420318435s","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-24T12:55:48.711017Z","caller":"traceutil/trace.go:171","msg":"trace[194218948] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:510; }","duration":"1.420416475s","start":"2026-02-24T12:55:47.290587Z","end":"2026-02-24T12:55:48.711004Z","steps":["trace[194218948] 'agreement among raft nodes before linearized reading' (duration: 1.420258405s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:55:48.711051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:55:47.290534Z","time spent":"1.420509855s","remote":"127.0.0.1:56630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-24T12:55:48.711204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"840.035664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T12:55:48.71126Z","caller":"traceutil/trace.go:171","msg":"trace[986400614] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"1.74978173s","start":"2026-02-24T12:55:46.961467Z","end":"2026-02-24T12:55:48.711249Z","steps":["trace[986400614] 'process raft request' (duration: 1.74921649s)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:55:48.711281Z","caller":"traceutil/trace.go:171","msg":"trace[719814373] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:510; }","duration":"840.170324ms","start":"2026-02-24T12:55:47.87109Z","end":"2026-02-24T12:55:48.71126Z","steps":["trace[719814373] 'agreement among raft nodes before linearized reading' (duration: 840.043714ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:55:48.711311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:55:47.87106Z","time spent":"840.245894ms","remote":"127.0.0.1:56428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T12:55:48.711328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:55:46.961454Z","time spent":"1.74983612s","remote":"127.0.0.1:56566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T12:55:50.566386Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.447811597s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-24T12:55:50.634461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.515764335s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T12:55:50.634904Z","caller":"traceutil/trace.go:171","msg":"trace[907525658] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"1.919956129s","start":"2026-02-24T12:55:48.714884Z","end":"2026-02-24T12:55:50.63484Z","steps":["trace[907525658] 'process raft request' (duration: 403.733874ms)","trace[907525658] 'compare' (duration: 1.515491114s)"],"step_count":2} {"level":"info","ts":"2026-02-24T12:55:50.635013Z","caller":"traceutil/trace.go:171","msg":"trace[1004466735] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:529; }","duration":"1.680693384s","start":"2026-02-24T12:55:48.954299Z","end":"2026-02-24T12:55:50.634992Z","steps":["trace[1004466735] 'read index received' (duration: 164.329839ms)","trace[1004466735] 'applied index is now lower than readState.Index' (duration: 1.516360935s)"],"step_count":2} {"level":"warn","ts":"2026-02-24T12:55:50.63514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:55:48.714868Z","time spent":"1.920108979s","remote":"127.0.0.1:56630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T12:55:50.663387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.001040754s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T12:55:50.66349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.026194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T12:55:50.663536Z","caller":"traceutil/trace.go:171","msg":"trace[1299493149] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:511; }","duration":"794.086584ms","start":"2026-02-24T12:55:49.869422Z","end":"2026-02-24T12:55:50.663508Z","steps":["trace[1299493149] 'agreement among raft nodes before linearized reading' (duration: 794.042114ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:55:50.663583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:55:49.869404Z","time spent":"794.158175ms","remote":"127.0.0.1:56430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T12:55:50.66363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.825054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-24T12:55:50.66362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.709283485s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-24T12:55:50.663711Z","caller":"traceutil/trace.go:171","msg":"trace[2115552171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:511; }","duration":"478.948985ms","start":"2026-02-24T12:55:50.184748Z","end":"2026-02-24T12:55:50.663697Z","steps":["trace[2115552171] 'agreement among raft nodes before linearized reading' (duration: 478.693084ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:55:50.663751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:55:50.184733Z","time spent":"479.012585ms","remote":"127.0.0.1:56630","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-24T12:55:50.66374Z","caller":"traceutil/trace.go:171","msg":"trace[1373204159] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:511; }","duration":"1.709461286s","start":"2026-02-24T12:55:48.95425Z","end":"2026-02-24T12:55:50.663712Z","steps":["trace[1373204159] 'agreement among raft nodes before linearized reading' (duration: 1.680840995s)","trace[1373204159] 'range keys from in-memory index tree' (duration: 28.31075ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T12:55:50.663797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:55:48.954227Z","time spent":"1.709557386s","remote":"127.0.0.1:56630","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-24T12:55:50.66343Z","caller":"traceutil/trace.go:171","msg":"trace[1998263859] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:511; }","duration":"1.001115414s","start":"2026-02-24T12:55:49.6623Z","end":"2026-02-24T12:55:50.663416Z","steps":["trace[1998263859] 'agreement among raft nodes before linearized reading' (duration: 1.001058254s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:55:50.663965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:55:49.662281Z","time spent":"1.001583965s","remote":"127.0.0.1:56428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T12:55:53.180452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.753614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-24T12:55:53.180512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.974334ms","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-24T12:55:53.180537Z","caller":"traceutil/trace.go:171","msg":"trace[1599572348] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:526; }","duration":"456.021754ms","start":"2026-02-24T12:55:52.724508Z","end":"2026-02-24T12:55:53.18053Z","steps":["trace[1599572348] 'range keys from in-memory index tree' (duration: 455.922514ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:55:53.180529Z","caller":"traceutil/trace.go:171","msg":"trace[770841215] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:526; }","duration":"504.857524ms","start":"2026-02-24T12:55:52.675654Z","end":"2026-02-24T12:55:53.180512Z","steps":["trace[770841215] 'range keys from in-memory index tree' (duration: 504.668574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:55:53.180557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:55:52.724498Z","time spent":"456.054624ms","remote":"127.0.0.1:56630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T12:55:53.180574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:55:52.675647Z","time spent":"504.916414ms","remote":"127.0.0.1:56630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-24T12:55:53.180632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.224431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-02-24T12:55:53.180717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.712687ms","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-24T12:55:53.180715Z","caller":"traceutil/trace.go:171","msg":"trace[1628808289] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:526; }","duration":"519.339752ms","start":"2026-02-24T12:55:52.661352Z","end":"2026-02-24T12:55:53.180692Z","steps":["trace[1628808289] 'range keys from in-memory index tree' (duration: 519.137061ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:55:53.180756Z","caller":"traceutil/trace.go:171","msg":"trace[928910092] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:526; }","duration":"505.779817ms","start":"2026-02-24T12:55:52.674967Z","end":"2026-02-24T12:55:53.180747Z","steps":["trace[928910092] 'range keys from in-memory index tree' (duration: 505.643837ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:55:53.180768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:55:52.661339Z","time spent":"519.420372ms","remote":"127.0.0.1:56508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":375,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-24T12:55:53.180806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:55:52.674951Z","time spent":"505.844407ms","remote":"127.0.0.1:56630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-24T12:55:57.616656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.55419ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T12:55:57.616999Z","caller":"traceutil/trace.go:171","msg":"trace[1946470725] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"183.369045ms","start":"2026-02-24T12:55:57.433569Z","end":"2026-02-24T12:55:57.616938Z","steps":["trace[1946470725] 'process raft request' (duration: 35.425732ms)","trace[1946470725] 'compare' (duration: 147.373199ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T12:55:57.617903Z","caller":"traceutil/trace.go:171","msg":"trace[1960443326] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"169.149715ms","start":"2026-02-24T12:55:57.448718Z","end":"2026-02-24T12:55:57.617868Z","steps":["trace[1960443326] 'process raft request' (duration: 168.087977ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:56:03.930541Z","caller":"traceutil/trace.go:171","msg":"trace[1473568625] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"136.754057ms","start":"2026-02-24T12:56:03.793725Z","end":"2026-02-24T12:56:03.930479Z","steps":["trace[1473568625] 'process raft request' (duration: 136.556185ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:56:16.971077Z","caller":"traceutil/trace.go:171","msg":"trace[1300037824] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"177.534566ms","start":"2026-02-24T12:56:16.793503Z","end":"2026-02-24T12:56:16.971037Z","steps":["trace[1300037824] 'process raft request' (duration: 177.136077ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T12:57:13.887455Z","caller":"traceutil/trace.go:171","msg":"trace[356647164] linearizableReadLoop","detail":"{readStateIndex:963; appliedIndex:962; }","duration":"422.525506ms","start":"2026-02-24T12:57:13.464898Z","end":"2026-02-24T12:57:13.887423Z","steps":["trace[356647164] 'read index received' (duration: 422.459133ms)","trace[356647164] 'applied index is now lower than readState.Index' (duration: 64.673µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T12:57:13.887537Z","caller":"traceutil/trace.go:171","msg":"trace[551750343] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"584.324084ms","start":"2026-02-24T12:57:13.303146Z","end":"2026-02-24T12:57:13.88747Z","steps":["trace[551750343] 'process raft request' (duration: 584.110894ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:57:13.887727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.800397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2476"} {"level":"info","ts":"2026-02-24T12:57:13.88777Z","caller":"traceutil/trace.go:171","msg":"trace[696752303] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:925; }","duration":"422.914683ms","start":"2026-02-24T12:57:13.464843Z","end":"2026-02-24T12:57:13.887757Z","steps":["trace[696752303] 'agreement among raft nodes before linearized reading' (duration: 422.741525ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:57:13.887789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:57:13.303128Z","time spent":"584.526833ms","remote":"127.0.0.1:56630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T12:57:13.887808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:57:13.464824Z","time spent":"422.975615ms","remote":"127.0.0.1:56566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":2500,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "} {"level":"warn","ts":"2026-02-24T12:57:13.88781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.887513ms","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-24T12:57:13.887895Z","caller":"traceutil/trace.go:171","msg":"trace[1303069856] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:925; }","duration":"102.008849ms","start":"2026-02-24T12:57:13.785863Z","end":"2026-02-24T12:57:13.887872Z","steps":["trace[1303069856] 'agreement among raft nodes before linearized reading' (duration: 101.82444ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:57:13.888418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.9348ms","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-24T12:57:13.888486Z","caller":"traceutil/trace.go:171","msg":"trace[1232875075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:925; }","duration":"101.001703ms","start":"2026-02-24T12:57:13.787464Z","end":"2026-02-24T12:57:13.888466Z","steps":["trace[1232875075] 'agreement among raft nodes before linearized reading' (duration: 100.802504ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:57:22.481712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.271868ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T12:57:22.481897Z","caller":"traceutil/trace.go:171","msg":"trace[910074230] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"423.447733ms","start":"2026-02-24T12:57:22.058431Z","end":"2026-02-24T12:57:22.481879Z","steps":["trace[910074230] 'process raft request' (duration: 233.944224ms)","trace[910074230] 'compare' (duration: 189.153522ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T12:57:22.482004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:57:22.058414Z","time spent":"423.546038ms","remote":"127.0.0.1:56630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T12:57:22.687034Z","caller":"traceutil/trace.go:171","msg":"trace[1372505735] linearizableReadLoop","detail":"{readStateIndex:999; appliedIndex:996; }","duration":"388.045685ms","start":"2026-02-24T12:57:22.29897Z","end":"2026-02-24T12:57:22.687015Z","steps":["trace[1372505735] 'read index received' (duration: 383.455419ms)","trace[1372505735] 'applied index is now lower than readState.Index' (duration: 4.589516ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T12:57:22.687106Z","caller":"traceutil/trace.go:171","msg":"trace[1755148791] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"628.291606ms","start":"2026-02-24T12:57:22.05878Z","end":"2026-02-24T12:57:22.687072Z","steps":["trace[1755148791] 'process raft request' (duration: 623.632926ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:57:22.687289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.299087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T12:57:22.687302Z","caller":"traceutil/trace.go:171","msg":"trace[1283346188] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"628.430271ms","start":"2026-02-24T12:57:22.058803Z","end":"2026-02-24T12:57:22.687233Z","steps":["trace[1283346188] 'process raft request' (duration: 628.161059ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:57:22.687338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:57:22.058732Z","time spent":"628.476454ms","remote":"127.0.0.1:56630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T12:57:22.68744Z","caller":"traceutil/trace.go:171","msg":"trace[2119725738] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"628.482425ms","start":"2026-02-24T12:57:22.05895Z","end":"2026-02-24T12:57:22.687432Z","steps":["trace[2119725738] 'process raft request' (duration: 628.040424ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:57:22.687429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:57:22.058702Z","time spent":"628.662163ms","remote":"127.0.0.1:56630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T12:57:22.687366Z","caller":"traceutil/trace.go:171","msg":"trace[711647519] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:959; }","duration":"388.412653ms","start":"2026-02-24T12:57:22.298929Z","end":"2026-02-24T12:57:22.687342Z","steps":["trace[711647519] 'agreement among raft nodes before linearized reading' (duration: 388.239734ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T12:57:22.68816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:57:22.298908Z","time spent":"389.223411ms","remote":"127.0.0.1:34116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-02-24T12:57:22.687575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T12:57:22.058932Z","time spent":"628.560408ms","remote":"127.0.0.1:56630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"}