{"level":"warn","ts":"2026-02-13T14:18:54.636762Z","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-13T14:18:54.636859Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.141: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.141:2380","--initial-cluster=instance=https://199.204.45.141:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.141:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.141: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-13T14:18:54.636968Z","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-13T14:18:54.636996Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.141:2380"]} {"level":"info","ts":"2026-02-13T14:18:54.637013Z","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-13T14:18:54.638203Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.141:2379"]} {"level":"info","ts":"2026-02-13T14:18:54.638306Z","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":2,"max-cpu-available":2,"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.141:2380"],"listen-peer-urls":["https://199.204.45.141:2380"],"advertise-client-urls":["https://199.204.45.141:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.141:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.141: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-13T14:18:55.055685Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"417.059899ms"} {"level":"info","ts":"2026-02-13T14:18:55.705957Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9ef3b4f68930dc2","cluster-id":"b19a5957e6e65747"} {"level":"info","ts":"2026-02-13T14:18:55.70614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 switched to configuration voters=()"} {"level":"info","ts":"2026-02-13T14:18:55.706227Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became follower at term 0"} {"level":"info","ts":"2026-02-13T14:18:55.706241Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9ef3b4f68930dc2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-13T14:18:55.706316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became follower at term 1"} {"level":"info","ts":"2026-02-13T14:18:55.706411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 switched to configuration voters=(715856078018121154)"} {"level":"warn","ts":"2026-02-13T14:18:55.713189Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-13T14:18:55.717868Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-13T14:18:55.721731Z","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-13T14:18:55.726577Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9ef3b4f68930dc2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-13T14:18:55.728593Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9ef3b4f68930dc2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-13T14:18:55.728796Z","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-13T14:18:55.728863Z","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-13T14:18:55.728909Z","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-13T14:18:55.731352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 switched to configuration voters=(715856078018121154)"} {"level":"info","ts":"2026-02-13T14:18:55.731503Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b19a5957e6e65747","local-member-id":"9ef3b4f68930dc2","added-peer-id":"9ef3b4f68930dc2","added-peer-peer-urls":["https://199.204.45.141:2380"]} {"level":"info","ts":"2026-02-13T14:18:55.731857Z","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-13T14:18:55.732215Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9ef3b4f68930dc2","initial-advertise-peer-urls":["https://199.204.45.141:2380"],"listen-peer-urls":["https://199.204.45.141:2380"],"advertise-client-urls":["https://199.204.45.141:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.141:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-13T14:18:55.732059Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.141:2380"} {"level":"info","ts":"2026-02-13T14:18:55.733129Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.141:2380"} {"level":"info","ts":"2026-02-13T14:18:55.733069Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-13T14:18:56.407854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 is starting a new election at term 1"} {"level":"info","ts":"2026-02-13T14:18:56.407895Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-13T14:18:56.40792Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 received MsgPreVoteResp from 9ef3b4f68930dc2 at term 1"} {"level":"info","ts":"2026-02-13T14:18:56.407931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became candidate at term 2"} {"level":"info","ts":"2026-02-13T14:18:56.407936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 received MsgVoteResp from 9ef3b4f68930dc2 at term 2"} {"level":"info","ts":"2026-02-13T14:18:56.407945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became leader at term 2"} {"level":"info","ts":"2026-02-13T14:18:56.407952Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9ef3b4f68930dc2 elected leader 9ef3b4f68930dc2 at term 2"} {"level":"info","ts":"2026-02-13T14:18:56.408966Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-13T14:18:56.411147Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9ef3b4f68930dc2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.141:2379]}","request-path":"/0/members/9ef3b4f68930dc2/attributes","cluster-id":"b19a5957e6e65747","publish-timeout":"7s"} {"level":"info","ts":"2026-02-13T14:18:56.411167Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-13T14:18:56.411462Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-13T14:18:56.412756Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-13T14:18:56.412898Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b19a5957e6e65747","local-member-id":"9ef3b4f68930dc2","cluster-version":"3.5"} {"level":"info","ts":"2026-02-13T14:18:56.412962Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-13T14:18:56.412988Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-13T14:18:56.414757Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.141:2379"} {"level":"info","ts":"2026-02-13T14:18:56.445859Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-13T14:18:56.445878Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-02-13T14:19:01.322102Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991526767036161103,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T14:19:02.05839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.419664ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-13T14:19:02.059561Z","caller":"traceutil/trace.go:171","msg":"trace[1689451192] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"1.313866838s","start":"2026-02-13T14:19:00.745671Z","end":"2026-02-13T14:19:02.059537Z","steps":["trace[1689451192] 'process raft request' (duration: 679.706673ms)","trace[1689451192] 'compare' (duration: 629.527426ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:19:02.059975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:19:00.745664Z","time spent":"1.313969238s","remote":"127.0.0.1:52552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":373,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-13T14:19:02.463391Z","caller":"traceutil/trace.go:171","msg":"trace[1903150774] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"395.295914ms","start":"2026-02-13T14:19:02.068072Z","end":"2026-02-13T14:19:02.463368Z","steps":["trace[1903150774] 'process raft request' (duration: 395.254424ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:19:02.463517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:19:02.068051Z","time spent":"395.412544ms","remote":"127.0.0.1:52568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":400,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-13T14:19:02.464058Z","caller":"traceutil/trace.go:171","msg":"trace[1267028232] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"1.640961017s","start":"2026-02-13T14:19:00.823086Z","end":"2026-02-13T14:19:02.464047Z","steps":["trace[1267028232] 'process raft request' (duration: 1.573747885s)","trace[1267028232] 'compare' (duration: 66.143655ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:19:02.464108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:19:00.823077Z","time spent":"1.641008147s","remote":"127.0.0.1:52230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-13T14:19:02.464287Z","caller":"traceutil/trace.go:171","msg":"trace[2013660582] transaction","detail":"{read_only:false; number_of_response:0; response_revision:269; }","duration":"1.531077389s","start":"2026-02-13T14:19:00.933201Z","end":"2026-02-13T14:19:02.464279Z","steps":["trace[2013660582] 'process raft request' (duration: 1.529892411s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:19:02.464371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:19:00.933186Z","time spent":"1.53112557s","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-13T14:19:02.464713Z","caller":"traceutil/trace.go:171","msg":"trace[1699340376] transaction","detail":"{read_only:false; response_revision:270; number_of_response:1; }","duration":"1.520113839s","start":"2026-02-13T14:19:00.94459Z","end":"2026-02-13T14:19:02.464704Z","steps":["trace[1699340376] 'process raft request' (duration: 1.518538259s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:19:02.464757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:19:00.944582Z","time spent":"1.520155959s","remote":"127.0.0.1:52354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5476,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-13T14:19:02.464984Z","caller":"traceutil/trace.go:171","msg":"trace[1117601860] transaction","detail":"{read_only:false; response_revision:271; number_of_response:1; }","duration":"1.519376575s","start":"2026-02-13T14:19:00.945591Z","end":"2026-02-13T14:19:02.464967Z","steps":["trace[1117601860] 'process raft request' (duration: 1.517603724s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:19:02.465025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:19:00.945584Z","time spent":"1.519423775s","remote":"127.0.0.1:52354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3943,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-13T14:19:02.465302Z","caller":"traceutil/trace.go:171","msg":"trace[1726167281] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"1.519412715s","start":"2026-02-13T14:19:00.945859Z","end":"2026-02-13T14:19:02.465271Z","steps":["trace[1726167281] 'process raft request' (duration: 1.517369272s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:19:02.465346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:19:00.945854Z","time spent":"1.519473665s","remote":"127.0.0.1:52354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2883,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-13T14:19:02.465525Z","caller":"traceutil/trace.go:171","msg":"trace[1136321466] transaction","detail":"{read_only:false; number_of_response:0; response_revision:272; }","duration":"1.517241831s","start":"2026-02-13T14:19:00.948277Z","end":"2026-02-13T14:19:02.465519Z","steps":["trace[1136321466] 'process raft request' (duration: 1.514998607s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:19:02.465608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:19:00.94827Z","time spent":"1.517278782s","remote":"127.0.0.1:52354","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-13T14:19:02.465852Z","caller":"traceutil/trace.go:171","msg":"trace[1044804011] transaction","detail":"{read_only:false; number_of_response:0; response_revision:272; }","duration":"1.517517402s","start":"2026-02-13T14:19:00.948327Z","end":"2026-02-13T14:19:02.465844Z","steps":["trace[1044804011] 'process raft request' (duration: 1.514972946s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:19:02.465904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:19:00.948324Z","time spent":"1.517563893s","remote":"127.0.0.1:52354","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-13T14:19:02.466033Z","caller":"traceutil/trace.go:171","msg":"trace[1609606521] linearizableReadLoop","detail":"{readStateIndex:282; appliedIndex:274; }","duration":"1.64446751s","start":"2026-02-13T14:19:00.821557Z","end":"2026-02-13T14:19:02.466024Z","steps":["trace[1609606521] 'read index received' (duration: 603.899502ms)","trace[1609606521] 'applied index is now lower than readState.Index' (duration: 1.040566828s)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:19:02.466526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.644946323s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-02-13T14:19:02.46656Z","caller":"traceutil/trace.go:171","msg":"trace[1836875900] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:273; }","duration":"1.645022443s","start":"2026-02-13T14:19:00.821529Z","end":"2026-02-13T14:19:02.466552Z","steps":["trace[1836875900] 'agreement among raft nodes before linearized reading' (duration: 1.644925672s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:19:02.466581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:19:00.821516Z","time spent":"1.645059953s","remote":"127.0.0.1:52606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-02-13T14:19:02.467228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.635014719s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:19:02.468001Z","caller":"traceutil/trace.go:171","msg":"trace[44624257] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:273; }","duration":"1.635833674s","start":"2026-02-13T14:19:00.832156Z","end":"2026-02-13T14:19:02.46799Z","steps":["trace[44624257] 'agreement among raft nodes before linearized reading' (duration: 1.634714967s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:19:02.468054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:19:00.832147Z","time spent":"1.635892785s","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-02-13T14:19:02.467529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.183805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:19:02.468272Z","caller":"traceutil/trace.go:171","msg":"trace[190227103] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:273; }","duration":"451.97838ms","start":"2026-02-13T14:19:02.016286Z","end":"2026-02-13T14:19:02.468264Z","steps":["trace[190227103] 'agreement among raft nodes before linearized reading' (duration: 451.206555ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:19:02.468415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:19:02.016262Z","time spent":"452.14395ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-13T14:19:02.467579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.221107ms","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-13T14:19:02.468625Z","caller":"traceutil/trace.go:171","msg":"trace[1954489594] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:273; }","duration":"549.283914ms","start":"2026-02-13T14:19:01.919333Z","end":"2026-02-13T14:19:02.468617Z","steps":["trace[1954489594] 'agreement among raft nodes before linearized reading' (duration: 548.220447ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:19:02.468827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:19:01.919325Z","time spent":"549.434105ms","remote":"127.0.0.1:52448","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-13T14:19:02.467613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.778421ms","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-13T14:19:02.469083Z","caller":"traceutil/trace.go:171","msg":"trace[429369611] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:273; }","duration":"550.29019ms","start":"2026-02-13T14:19:01.918785Z","end":"2026-02-13T14:19:02.469075Z","steps":["trace[429369611] 'agreement among raft nodes before linearized reading' (duration: 548.80597ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:19:02.469204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:19:01.918759Z","time spent":"550.436351ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-13T14:19:02.46764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.210126359s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:19:02.469503Z","caller":"traceutil/trace.go:171","msg":"trace[1094070806] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:273; }","duration":"1.212014941s","start":"2026-02-13T14:19:01.257479Z","end":"2026-02-13T14:19:02.469494Z","steps":["trace[1094070806] 'agreement among raft nodes before linearized reading' (duration: 1.21014532s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:19:02.469612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:19:01.257464Z","time spent":"1.212138932s","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-13T14:19:18.64616Z","caller":"traceutil/trace.go:171","msg":"trace[1838486664] linearizableReadLoop","detail":"{readStateIndex:481; appliedIndex:480; }","duration":"156.457524ms","start":"2026-02-13T14:19:18.489685Z","end":"2026-02-13T14:19:18.646143Z","steps":["trace[1838486664] 'read index received' (duration: 156.294198ms)","trace[1838486664] 'applied index is now lower than readState.Index' (duration: 162.746µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:19:18.646349Z","caller":"traceutil/trace.go:171","msg":"trace[1557937788] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"221.855508ms","start":"2026-02-13T14:19:18.424486Z","end":"2026-02-13T14:19:18.646342Z","steps":["trace[1557937788] 'process raft request' (duration: 221.539186ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:19:18.646467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.774407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:19:18.646483Z","caller":"traceutil/trace.go:171","msg":"trace[516168110] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:468; }","duration":"156.83882ms","start":"2026-02-13T14:19:18.48964Z","end":"2026-02-13T14:19:18.646479Z","steps":["trace[516168110] 'agreement among raft nodes before linearized reading' (duration: 156.798218ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:19:26.143773Z","caller":"traceutil/trace.go:171","msg":"trace[1020143938] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"402.548008ms","start":"2026-02-13T14:19:25.740953Z","end":"2026-02-13T14:19:26.143501Z","steps":["trace[1020143938] 'process raft request' (duration: 402.369841ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:19:26.144067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:19:25.740939Z","time spent":"402.899001ms","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4211,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:19:29.087391Z","caller":"traceutil/trace.go:171","msg":"trace[1630167357] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"392.491285ms","start":"2026-02-13T14:19:28.694876Z","end":"2026-02-13T14:19:29.087367Z","steps":["trace[1630167357] 'process raft request' (duration: 392.363271ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:19:29.087603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:19:28.694866Z","time spent":"392.654741ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:19:29.088169Z","caller":"traceutil/trace.go:171","msg":"trace[1145229374] linearizableReadLoop","detail":"{readStateIndex:542; appliedIndex:542; }","duration":"370.789825ms","start":"2026-02-13T14:19:28.717354Z","end":"2026-02-13T14:19:29.088144Z","steps":["trace[1145229374] 'read index received' (duration: 370.782234ms)","trace[1145229374] 'applied index is now lower than readState.Index' (duration: 6.181µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:19:29.088491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.126347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-13T14:19:29.088549Z","caller":"traceutil/trace.go:171","msg":"trace[926222462] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:527; }","duration":"371.269822ms","start":"2026-02-13T14:19:28.717267Z","end":"2026-02-13T14:19:29.088517Z","steps":["trace[926222462] 'agreement among raft nodes before linearized reading' (duration: 371.058285ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:19:29.088583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:19:28.717249Z","time spent":"371.324964ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-13T14:19:29.139618Z","caller":"traceutil/trace.go:171","msg":"trace[117391680] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"333.595354ms","start":"2026-02-13T14:19:28.80601Z","end":"2026-02-13T14:19:29.139605Z","steps":["trace[117391680] 'process raft request' (duration: 333.415188ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:19:29.139925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:19:28.805993Z","time spent":"333.799322ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T14:19:29.140177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.268589ms","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-13T14:19:29.14023Z","caller":"traceutil/trace.go:171","msg":"trace[2116753014] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:528; }","duration":"409.349833ms","start":"2026-02-13T14:19:28.730867Z","end":"2026-02-13T14:19:29.140216Z","steps":["trace[2116753014] 'agreement among raft nodes before linearized reading' (duration: 409.142945ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:19:29.140263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:19:28.730855Z","time spent":"409.402164ms","remote":"127.0.0.1:52448","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-13T14:19:29.140365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.733561ms","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-13T14:19:29.140387Z","caller":"traceutil/trace.go:171","msg":"trace[1565879593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:528; }","duration":"408.790152ms","start":"2026-02-13T14:19:28.731591Z","end":"2026-02-13T14:19:29.140381Z","steps":["trace[1565879593] 'agreement among raft nodes before linearized reading' (duration: 408.73387ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:19:29.140403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:19:28.731584Z","time spent":"408.815883ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-13T14:19:32.985502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.965217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:19:32.985557Z","caller":"traceutil/trace.go:171","msg":"trace[354709591] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:561; }","duration":"498.05521ms","start":"2026-02-13T14:19:32.48749Z","end":"2026-02-13T14:19:32.985545Z","steps":["trace[354709591] 'range keys from in-memory index tree' (duration: 497.897905ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:19:32.985624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:19:32.48746Z","time spent":"498.157134ms","remote":"127.0.0.1:52116","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-13T14:19:33.294983Z","caller":"traceutil/trace.go:171","msg":"trace[1601567379] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"343.2044ms","start":"2026-02-13T14:19:32.951772Z","end":"2026-02-13T14:19:33.294976Z","steps":["trace[1601567379] 'process raft request' (duration: 342.917851ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:19:33.295064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:19:32.951756Z","time spent":"343.259482ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:19:33.294796Z","caller":"traceutil/trace.go:171","msg":"trace[1496236314] linearizableReadLoop","detail":"{readStateIndex:578; appliedIndex:577; }","duration":"338.500251ms","start":"2026-02-13T14:19:32.95628Z","end":"2026-02-13T14:19:33.294781Z","steps":["trace[1496236314] 'read index received' (duration: 338.351166ms)","trace[1496236314] 'applied index is now lower than readState.Index' (duration: 148.315µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:19:33.29528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.799954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:19:33.295297Z","caller":"traceutil/trace.go:171","msg":"trace[1105093874] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:562; }","duration":"308.841426ms","start":"2026-02-13T14:19:32.986451Z","end":"2026-02-13T14:19:33.295292Z","steps":["trace[1105093874] 'agreement among raft nodes before linearized reading' (duration: 308.813555ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:19:33.295317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:19:32.98644Z","time spent":"308.874197ms","remote":"127.0.0.1:52120","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-13T14:19:33.295427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.377522ms","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-13T14:19:33.295439Z","caller":"traceutil/trace.go:171","msg":"trace[746739925] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:562; }","duration":"131.398743ms","start":"2026-02-13T14:19:33.164037Z","end":"2026-02-13T14:19:33.295435Z","steps":["trace[746739925] 'agreement among raft nodes before linearized reading' (duration: 131.337011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:19:33.295522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.917539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-13T14:19:33.295533Z","caller":"traceutil/trace.go:171","msg":"trace[334827522] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:562; }","duration":"131.940071ms","start":"2026-02-13T14:19:33.163589Z","end":"2026-02-13T14:19:33.29553Z","steps":["trace[334827522] 'agreement among raft nodes before linearized reading' (duration: 131.894699ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:19:33.2956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.475703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-13T14:19:33.295612Z","caller":"traceutil/trace.go:171","msg":"trace[2134437097] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:562; }","duration":"139.524624ms","start":"2026-02-13T14:19:33.156084Z","end":"2026-02-13T14:19:33.295608Z","steps":["trace[2134437097] 'agreement among raft nodes before linearized reading' (duration: 139.491463ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:19:33.295677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.161753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-13T14:19:33.295689Z","caller":"traceutil/trace.go:171","msg":"trace[162978160] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:562; }","duration":"196.197013ms","start":"2026-02-13T14:19:33.099488Z","end":"2026-02-13T14:19:33.295685Z","steps":["trace[162978160] 'agreement among raft nodes before linearized reading' (duration: 196.164222ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:19:33.296159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.865827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-jlt45\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-13T14:19:33.296185Z","caller":"traceutil/trace.go:171","msg":"trace[1015732931] range","detail":"{range_begin:/registry/pods/kube-system/cilium-jlt45; range_end:; response_count:1; response_revision:562; }","duration":"339.923668ms","start":"2026-02-13T14:19:32.956247Z","end":"2026-02-13T14:19:33.296171Z","steps":["trace[1015732931] 'agreement among raft nodes before linearized reading' (duration: 338.986237ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:19:33.296201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:19:32.956237Z","time spent":"339.95813ms","remote":"127.0.0.1:52354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16679,"request content":"key:\"/registry/pods/kube-system/cilium-jlt45\" "} {"level":"info","ts":"2026-02-13T14:19:33.968279Z","caller":"traceutil/trace.go:171","msg":"trace[1265535284] linearizableReadLoop","detail":"{readStateIndex:583; appliedIndex:579; }","duration":"480.043464ms","start":"2026-02-13T14:19:33.48822Z","end":"2026-02-13T14:19:33.968263Z","steps":["trace[1265535284] 'read index received' (duration: 479.782245ms)","trace[1265535284] 'applied index is now lower than readState.Index' (duration: 260.699µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:19:33.968341Z","caller":"traceutil/trace.go:171","msg":"trace[1744830884] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"658.069929ms","start":"2026-02-13T14:19:33.310245Z","end":"2026-02-13T14:19:33.968315Z","steps":["trace[1744830884] 'process raft request' (duration: 657.940435ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:19:33.968374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.144177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:19:33.968393Z","caller":"traceutil/trace.go:171","msg":"trace[1495192253] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:567; }","duration":"480.214179ms","start":"2026-02-13T14:19:33.488173Z","end":"2026-02-13T14:19:33.968387Z","steps":["trace[1495192253] 'agreement among raft nodes before linearized reading' (duration: 480.156207ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:19:33.968411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:19:33.488155Z","time spent":"480.252361ms","remote":"127.0.0.1:52116","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-13T14:19:33.968449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:19:33.310241Z","time spent":"658.146471ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:19:33.96862Z","caller":"traceutil/trace.go:171","msg":"trace[1930561029] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"658.244655ms","start":"2026-02-13T14:19:33.31037Z","end":"2026-02-13T14:19:33.968614Z","steps":["trace[1930561029] 'process raft request' (duration: 657.833981ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:19:33.96867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:19:33.310365Z","time spent":"658.269826ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:19:33.968719Z","caller":"traceutil/trace.go:171","msg":"trace[798219141] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"658.533354ms","start":"2026-02-13T14:19:33.310176Z","end":"2026-02-13T14:19:33.968709Z","steps":["trace[798219141] 'process raft request' (duration: 657.889893ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:19:33.968761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:19:33.31017Z","time spent":"658.568165ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:19:33.9688Z","caller":"traceutil/trace.go:171","msg":"trace[567383754] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"653.793856ms","start":"2026-02-13T14:19:33.315001Z","end":"2026-02-13T14:19:33.968794Z","steps":["trace[567383754] 'process raft request' (duration: 653.223367ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:19:33.968827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:19:33.314993Z","time spent":"653.819657ms","remote":"127.0.0.1:52354","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-13T14:19:36.660883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.338530499s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-13T14:19:36.660958Z","caller":"traceutil/trace.go:171","msg":"trace[1695551617] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:583; }","duration":"1.308888589s","start":"2026-02-13T14:19:35.352056Z","end":"2026-02-13T14:19:36.660944Z","steps":["trace[1695551617] 'read index received' (duration: 20.531µs)","trace[1695551617] 'applied index is now lower than readState.Index' (duration: 1.308867058s)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:19:36.661098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.309034784s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-13T14:19:36.661117Z","caller":"traceutil/trace.go:171","msg":"trace[1067777511] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:567; }","duration":"1.309082795s","start":"2026-02-13T14:19:35.35203Z","end":"2026-02-13T14:19:36.661112Z","steps":["trace[1067777511] 'agreement among raft nodes before linearized reading' (duration: 1.308972192s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:19:36.661137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:19:35.352015Z","time spent":"1.309117346s","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-13T14:19:36.661257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.063496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-13T14:19:36.661321Z","caller":"traceutil/trace.go:171","msg":"trace[1184188424] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:567; }","duration":"685.173591ms","start":"2026-02-13T14:19:35.976131Z","end":"2026-02-13T14:19:36.661304Z","steps":["trace[1184188424] 'agreement among raft nodes before linearized reading' (duration: 684.958394ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:19:36.661362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:19:35.976113Z","time spent":"685.240992ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-13T14:19:36.661385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.597601ms","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-13T14:19:36.661403Z","caller":"traceutil/trace.go:171","msg":"trace[256551704] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:567; }","duration":"684.636403ms","start":"2026-02-13T14:19:35.976762Z","end":"2026-02-13T14:19:36.661398Z","steps":["trace[256551704] 'agreement among raft nodes before linearized reading' (duration: 684.591221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:19:36.661415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:19:35.976757Z","time spent":"684.655923ms","remote":"127.0.0.1:52448","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-13T14:19:36.661461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.202652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:19:36.661473Z","caller":"traceutil/trace.go:171","msg":"trace[806416708] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:567; }","duration":"174.213772ms","start":"2026-02-13T14:19:36.487256Z","end":"2026-02-13T14:19:36.661469Z","steps":["trace[806416708] 'agreement among raft nodes before linearized reading' (duration: 174.193601ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:19:36.661645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.394347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-13T14:19:36.66168Z","caller":"traceutil/trace.go:171","msg":"trace[373970866] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:567; }","duration":"685.453489ms","start":"2026-02-13T14:19:35.976217Z","end":"2026-02-13T14:19:36.66167Z","steps":["trace[373970866] 'agreement among raft nodes before linearized reading' (duration: 685.368527ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:19:36.661706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:19:35.976202Z","time spent":"685.4976ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-13T14:19:38.265677Z","caller":"traceutil/trace.go:171","msg":"trace[2056898892] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"283.93401ms","start":"2026-02-13T14:19:37.981727Z","end":"2026-02-13T14:19:38.265661Z","steps":["trace[2056898892] 'process raft request' (duration: 283.823707ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:19:41.332159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.73704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:19:41.332256Z","caller":"traceutil/trace.go:171","msg":"trace[1993601612] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:630; }","duration":"123.897435ms","start":"2026-02-13T14:19:41.208339Z","end":"2026-02-13T14:19:41.332236Z","steps":["trace[1993601612] 'range keys from in-memory index tree' (duration: 123.681969ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:19:50.349706Z","caller":"traceutil/trace.go:171","msg":"trace[1466380733] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"113.005712ms","start":"2026-02-13T14:19:50.236682Z","end":"2026-02-13T14:19:50.349688Z","steps":["trace[1466380733] 'process raft request' (duration: 111.647054ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:19:50.35571Z","caller":"traceutil/trace.go:171","msg":"trace[53863143] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"118.119634ms","start":"2026-02-13T14:19:50.237575Z","end":"2026-02-13T14:19:50.355695Z","steps":["trace[53863143] 'process raft request' (duration: 118.073772ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:19:50.355722Z","caller":"traceutil/trace.go:171","msg":"trace[1491128721] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"118.237027ms","start":"2026-02-13T14:19:50.23747Z","end":"2026-02-13T14:19:50.355707Z","steps":["trace[1491128721] 'process raft request' (duration: 118.102373ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:21:03.712318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.196728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:21:03.712408Z","caller":"traceutil/trace.go:171","msg":"trace[2098116296] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1005; }","duration":"222.33375ms","start":"2026-02-13T14:21:03.490057Z","end":"2026-02-13T14:21:03.71239Z","steps":["trace[2098116296] 'range keys from in-memory index tree' (duration: 222.135257ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:21:04.992421Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991526767036162760,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-13T14:21:05.010809Z","caller":"traceutil/trace.go:171","msg":"trace[463819826] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"520.992586ms","start":"2026-02-13T14:21:04.489787Z","end":"2026-02-13T14:21:05.01078Z","steps":["trace[463819826] 'process raft request' (duration: 520.817883ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:21:05.01096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:21:04.489777Z","time spent":"521.100557ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:21:05.126554Z","caller":"traceutil/trace.go:171","msg":"trace[674943953] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"614.724583ms","start":"2026-02-13T14:21:04.511807Z","end":"2026-02-13T14:21:05.126532Z","steps":["trace[674943953] 'process raft request' (duration: 611.714838ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:21:05.128299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:21:04.511783Z","time spent":"616.344308ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:21:05.154382Z","caller":"traceutil/trace.go:171","msg":"trace[639365592] linearizableReadLoop","detail":"{readStateIndex:1046; appliedIndex:1043; }","duration":"662.308535ms","start":"2026-02-13T14:21:04.492048Z","end":"2026-02-13T14:21:05.154357Z","steps":["trace[639365592] 'read index received' (duration: 519.703846ms)","trace[639365592] 'applied index is now lower than readState.Index' (duration: 142.603689ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:21:05.154572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.499138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:21:05.154605Z","caller":"traceutil/trace.go:171","msg":"trace[392199165] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1008; }","duration":"662.57744ms","start":"2026-02-13T14:21:04.492018Z","end":"2026-02-13T14:21:05.154595Z","steps":["trace[392199165] 'agreement among raft nodes before linearized reading' (duration: 662.462008ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:21:05.155534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:21:04.492005Z","time spent":"663.513824ms","remote":"127.0.0.1:52116","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-13T14:21:05.156277Z","caller":"traceutil/trace.go:171","msg":"trace[42551486] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"344.969798ms","start":"2026-02-13T14:21:04.811292Z","end":"2026-02-13T14:21:05.156262Z","steps":["trace[42551486] 'process raft request' (duration: 342.759795ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:21:05.156487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:21:04.811272Z","time spent":"345.11973ms","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5218,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}