{"level":"warn","ts":"2026-01-30T04:01:05.255799Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-30T04:01:05.255881Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.49: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.49:2380","--initial-cluster=instance=https://199.204.45.49:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.49:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.49: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-01-30T04:01:05.256019Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-30T04:01:05.25603Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-01-30T04:01:05.256379Z","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-01-30T04:01:05.257435Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"]} {"level":"info","ts":"2026-01-30T04:01:05.257515Z","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.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.49: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-01-30T04:01:05.26374Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.041702ms"} {"level":"info","ts":"2026-01-30T04:01:05.285711Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d0ba3c4de8c6ef7a","cluster-id":"e76701b877f442ac"} {"level":"info","ts":"2026-01-30T04:01:05.285831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=()"} {"level":"info","ts":"2026-01-30T04:01:05.285884Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 0"} {"level":"info","ts":"2026-01-30T04:01:05.285914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d0ba3c4de8c6ef7a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-30T04:01:05.285957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 1"} {"level":"info","ts":"2026-01-30T04:01:05.28601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"warn","ts":"2026-01-30T04:01:05.293686Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-30T04:01:05.294858Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-30T04:01:05.298621Z","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-01-30T04:01:05.308588Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d0ba3c4de8c6ef7a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-30T04:01:05.319096Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d0ba3c4de8c6ef7a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-30T04:01:05.319328Z","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-01-30T04:01:05.319373Z","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-01-30T04:01:05.319381Z","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-01-30T04:01:05.329908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"info","ts":"2026-01-30T04:01:05.330023Z","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-01-30T04:01:05.330098Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","added-peer-id":"d0ba3c4de8c6ef7a","added-peer-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-01-30T04:01:05.330317Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d0ba3c4de8c6ef7a","initial-advertise-peer-urls":["https://199.204.45.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-30T04:01:05.330369Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-30T04:01:05.330348Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-01-30T04:01:05.330419Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-01-30T04:01:05.787181Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a is starting a new election at term 1"} {"level":"info","ts":"2026-01-30T04:01:05.78722Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became pre-candidate at term 1"} {"level":"info","ts":"2026-01-30T04:01:05.787349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgPreVoteResp from d0ba3c4de8c6ef7a at term 1"} {"level":"info","ts":"2026-01-30T04:01:05.787417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became candidate at term 2"} {"level":"info","ts":"2026-01-30T04:01:05.78743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgVoteResp from d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-01-30T04:01:05.787439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became leader at term 2"} {"level":"info","ts":"2026-01-30T04:01:05.787447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d0ba3c4de8c6ef7a elected leader d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-01-30T04:01:05.790441Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-30T04:01:05.792632Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d0ba3c4de8c6ef7a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.49:2379]}","request-path":"/0/members/d0ba3c4de8c6ef7a/attributes","cluster-id":"e76701b877f442ac","publish-timeout":"7s"} {"level":"info","ts":"2026-01-30T04:01:05.792798Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-30T04:01:05.794673Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-30T04:01:05.795832Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","cluster-version":"3.5"} {"level":"info","ts":"2026-01-30T04:01:05.795964Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-30T04:01:05.795984Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-01-30T04:01:05.796122Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-30T04:01:05.800514Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-30T04:01:05.800536Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-30T04:01:05.811307Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.49:2379"} {"level":"info","ts":"2026-01-30T04:01:08.519366Z","caller":"traceutil/trace.go:171","msg":"trace[1958881312] transaction","detail":"{read_only:false; response_revision:116; number_of_response:1; }","duration":"155.666008ms","start":"2026-01-30T04:01:08.363658Z","end":"2026-01-30T04:01:08.519324Z","steps":["trace[1958881312] 'process raft request' (duration: 155.402434ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T04:01:18.318574Z","caller":"traceutil/trace.go:171","msg":"trace[1359657044] linearizableReadLoop","detail":"{readStateIndex:414; appliedIndex:413; }","duration":"398.492154ms","start":"2026-01-30T04:01:17.920038Z","end":"2026-01-30T04:01:18.31853Z","steps":["trace[1359657044] 'read index received' (duration: 398.292992ms)","trace[1359657044] 'applied index is now lower than readState.Index' (duration: 198.312µs)"],"step_count":2} {"level":"info","ts":"2026-01-30T04:01:18.318857Z","caller":"traceutil/trace.go:171","msg":"trace[1003928413] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"405.557162ms","start":"2026-01-30T04:01:17.913289Z","end":"2026-01-30T04:01:18.318846Z","steps":["trace[1003928413] 'process raft request' (duration: 405.094947ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:01:18.320446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:01:17.913273Z","time spent":"405.613783ms","remote":"127.0.0.1:54868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T04:01:18.32075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.703738ms","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-01-30T04:01:18.320797Z","caller":"traceutil/trace.go:171","msg":"trace[83300674] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:400; }","duration":"400.766998ms","start":"2026-01-30T04:01:17.920017Z","end":"2026-01-30T04:01:18.320784Z","steps":["trace[83300674] 'agreement among raft nodes before linearized reading' (duration: 400.687227ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:01:18.320828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:01:17.92001Z","time spent":"400.811429ms","remote":"127.0.0.1:54868","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-01-30T04:01:18.321187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.431521ms","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-01-30T04:01:18.321256Z","caller":"traceutil/trace.go:171","msg":"trace[329542620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:400; }","duration":"397.508322ms","start":"2026-01-30T04:01:17.923737Z","end":"2026-01-30T04:01:18.321245Z","steps":["trace[329542620] 'agreement among raft nodes before linearized reading' (duration: 397.413341ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:01:18.321286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:01:17.923732Z","time spent":"397.547072ms","remote":"127.0.0.1:54868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-30T04:01:19.025719Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256276502137080183,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-30T04:01:19.035861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.839842ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T04:01:19.03598Z","caller":"traceutil/trace.go:171","msg":"trace[400496336] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"708.433932ms","start":"2026-01-30T04:01:18.327498Z","end":"2026-01-30T04:01:19.035932Z","steps":["trace[400496336] 'process raft request' (duration: 40.377298ms)","trace[400496336] 'compare' (duration: 667.748321ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T04:01:19.036065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:01:18.327481Z","time spent":"708.533723ms","remote":"127.0.0.1:54868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T04:01:19.038681Z","caller":"traceutil/trace.go:171","msg":"trace[1182403354] linearizableReadLoop","detail":"{readStateIndex:417; appliedIndex:415; }","duration":"514.033132ms","start":"2026-01-30T04:01:18.52464Z","end":"2026-01-30T04:01:19.038673Z","steps":["trace[1182403354] 'read index received' (duration: 513.949101ms)","trace[1182403354] 'applied index is now lower than readState.Index' (duration: 83.631µs)"],"step_count":2} {"level":"info","ts":"2026-01-30T04:01:19.038952Z","caller":"traceutil/trace.go:171","msg":"trace[2140257822] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"708.068718ms","start":"2026-01-30T04:01:18.330877Z","end":"2026-01-30T04:01:19.038945Z","steps":["trace[2140257822] 'process raft request' (duration: 707.741835ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:01:19.039055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:01:18.330867Z","time spent":"708.155709ms","remote":"127.0.0.1:54770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7213,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T04:01:19.03923Z","caller":"traceutil/trace.go:171","msg":"trace[505769552] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"707.244139ms","start":"2026-01-30T04:01:18.33198Z","end":"2026-01-30T04:01:19.039224Z","steps":["trace[505769552] 'process raft request' (duration: 706.676683ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:01:19.039308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:01:18.331974Z","time spent":"707.31604ms","remote":"127.0.0.1:54868","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-01-30T04:01:19.039454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.81435ms","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-01-30T04:01:19.03953Z","caller":"traceutil/trace.go:171","msg":"trace[1908099010] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:403; }","duration":"514.952202ms","start":"2026-01-30T04:01:18.524571Z","end":"2026-01-30T04:01:19.039523Z","steps":["trace[1908099010] 'agreement among raft nodes before linearized reading' (duration: 514.861651ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:01:19.039609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:01:18.524545Z","time spent":"515.057252ms","remote":"127.0.0.1:54868","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-01-30T04:01:19.039763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.83837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T04:01:19.039847Z","caller":"traceutil/trace.go:171","msg":"trace[1137054622] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:403; }","duration":"155.938972ms","start":"2026-01-30T04:01:18.883888Z","end":"2026-01-30T04:01:19.039827Z","steps":["trace[1137054622] 'agreement among raft nodes before linearized reading' (duration: 155.860211ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:01:40.03373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.872982ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T04:01:40.033866Z","caller":"traceutil/trace.go:171","msg":"trace[984799946] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"668.675529ms","start":"2026-01-30T04:01:39.365174Z","end":"2026-01-30T04:01:40.03385Z","steps":["trace[984799946] 'process raft request' (duration: 458.60546ms)","trace[984799946] 'compare' (duration: 209.666806ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T04:01:40.033914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:01:39.365156Z","time spent":"668.734472ms","remote":"127.0.0.1:54580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T04:01:40.667456Z","caller":"traceutil/trace.go:171","msg":"trace[937627870] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:568; }","duration":"1.13698683s","start":"2026-01-30T04:01:39.530449Z","end":"2026-01-30T04:01:40.667436Z","steps":["trace[937627870] 'read index received' (duration: 293.339245ms)","trace[937627870] 'applied index is now lower than readState.Index' (duration: 843.646685ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T04:01:40.667696Z","caller":"traceutil/trace.go:171","msg":"trace[750460821] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"1.254772624s","start":"2026-01-30T04:01:39.412908Z","end":"2026-01-30T04:01:40.667681Z","steps":["trace[750460821] 'process raft request' (duration: 1.254281068s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:01:40.668544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:01:39.41289Z","time spent":"1.25558319s","remote":"127.0.0.1:54608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-30T04:01:40.66818Z","caller":"traceutil/trace.go:171","msg":"trace[2078180137] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"1.048769841s","start":"2026-01-30T04:01:39.619397Z","end":"2026-01-30T04:01:40.668167Z","steps":["trace[2078180137] 'process raft request' (duration: 1.047927092s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:01:40.668785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:01:39.619379Z","time spent":"1.04938784s","remote":"127.0.0.1:54608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-30T04:01:40.668224Z","caller":"traceutil/trace.go:171","msg":"trace[1385605218] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"856.59671ms","start":"2026-01-30T04:01:39.811619Z","end":"2026-01-30T04:01:40.668215Z","steps":["trace[1385605218] 'process raft request' (duration: 855.787543ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:01:40.668922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:01:39.811601Z","time spent":"857.303473ms","remote":"127.0.0.1:54608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-30T04:01:40.668345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.13788459s","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-01-30T04:01:40.669058Z","caller":"traceutil/trace.go:171","msg":"trace[1877531678] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:552; }","duration":"1.138640825s","start":"2026-01-30T04:01:39.530403Z","end":"2026-01-30T04:01:40.669044Z","steps":["trace[1877531678] 'agreement among raft nodes before linearized reading' (duration: 1.137847069s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:01:40.669094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:01:39.530383Z","time spent":"1.138700027s","remote":"127.0.0.1:54868","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-01-30T04:01:40.940905Z","caller":"traceutil/trace.go:171","msg":"trace[1329267972] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"929.758893ms","start":"2026-01-30T04:01:40.01112Z","end":"2026-01-30T04:01:40.940879Z","steps":["trace[1329267972] 'process raft request' (duration: 929.637019ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:01:40.942344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.720965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T04:01:40.942409Z","caller":"traceutil/trace.go:171","msg":"trace[833948396] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:553; }","duration":"732.802328ms","start":"2026-01-30T04:01:40.209593Z","end":"2026-01-30T04:01:40.942396Z","steps":["trace[833948396] 'agreement among raft nodes before linearized reading' (duration: 732.736165ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:01:40.94246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:01:40.011056Z","time spent":"931.292753ms","remote":"127.0.0.1:54608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-30T04:01:40.942589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:01:40.20958Z","time spent":"732.995324ms","remote":"127.0.0.1:54608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-01-30T04:01:40.942117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-01-30T04:01:40.94338Z","caller":"traceutil/trace.go:171","msg":"trace[285769230] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:553; }","duration":"333.523902ms","start":"2026-01-30T04:01:40.609836Z","end":"2026-01-30T04:01:40.94336Z","steps":["trace[285769230] 'agreement among raft nodes before linearized reading' (duration: 332.107666ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:01:40.94361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:01:40.609821Z","time spent":"333.772091ms","remote":"127.0.0.1:54608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5610,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-01-30T04:01:40.942212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.989909ms","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-01-30T04:01:40.944351Z","caller":"traceutil/trace.go:171","msg":"trace[2142742871] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:553; }","duration":"399.16326ms","start":"2026-01-30T04:01:40.545151Z","end":"2026-01-30T04:01:40.944314Z","steps":["trace[2142742871] 'agreement among raft nodes before linearized reading' (duration: 397.000259ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:01:40.944984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:01:40.545137Z","time spent":"399.835283ms","remote":"127.0.0.1:54674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"info","ts":"2026-01-30T04:01:40.941776Z","caller":"traceutil/trace.go:171","msg":"trace[455559867] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:573; }","duration":"273.359132ms","start":"2026-01-30T04:01:40.668389Z","end":"2026-01-30T04:01:40.941748Z","steps":["trace[455559867] 'read index received' (duration: 273.349792ms)","trace[455559867] 'applied index is now lower than readState.Index' (duration: 7.94µs)"],"step_count":2} {"level":"warn","ts":"2026-01-30T04:01:40.942253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.738008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-30T04:01:40.948553Z","caller":"traceutil/trace.go:171","msg":"trace[1735351035] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:553; }","duration":"456.067615ms","start":"2026-01-30T04:01:40.492476Z","end":"2026-01-30T04:01:40.948543Z","steps":["trace[1735351035] 'agreement among raft nodes before linearized reading' (duration: 449.752528ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:01:40.949483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:01:40.492461Z","time spent":"457.008785ms","remote":"127.0.0.1:54868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-30T04:01:40.942313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.324988ms","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-01-30T04:01:40.949896Z","caller":"traceutil/trace.go:171","msg":"trace[1856941230] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:553; }","duration":"539.940277ms","start":"2026-01-30T04:01:40.409946Z","end":"2026-01-30T04:01:40.949887Z","steps":["trace[1856941230] 'agreement among raft nodes before linearized reading' (duration: 532.321078ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:01:40.949963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:01:40.40987Z","time spent":"540.083671ms","remote":"127.0.0.1:54608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-01-30T04:01:40.942302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"906.120563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-01-30T04:01:40.950523Z","caller":"traceutil/trace.go:171","msg":"trace[1391106397] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:553; }","duration":"914.388562ms","start":"2026-01-30T04:01:40.0361Z","end":"2026-01-30T04:01:40.950489Z","steps":["trace[1391106397] 'agreement among raft nodes before linearized reading' (duration: 905.749901ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:01:40.950614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:01:40.036088Z","time spent":"914.483125ms","remote":"127.0.0.1:54756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-01-30T04:01:40.977089Z","caller":"traceutil/trace.go:171","msg":"trace[1075023725] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"300.487336ms","start":"2026-01-30T04:01:40.676579Z","end":"2026-01-30T04:01:40.977067Z","steps":["trace[1075023725] 'process raft request' (duration: 300.150486ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:01:40.977207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:01:40.676562Z","time spent":"300.5813ms","remote":"127.0.0.1:54868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T04:01:40.977435Z","caller":"traceutil/trace.go:171","msg":"trace[579722292] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"294.552174ms","start":"2026-01-30T04:01:40.682873Z","end":"2026-01-30T04:01:40.977425Z","steps":["trace[579722292] 'process raft request' (duration: 293.969235ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:01:40.977703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.810996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-01-30T04:01:40.977737Z","caller":"traceutil/trace.go:171","msg":"trace[692723408] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:555; }","duration":"167.863978ms","start":"2026-01-30T04:01:40.809862Z","end":"2026-01-30T04:01:40.977726Z","steps":["trace[692723408] 'agreement among raft nodes before linearized reading' (duration: 167.764295ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T04:01:48.806844Z","caller":"traceutil/trace.go:171","msg":"trace[1783349196] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"590.632555ms","start":"2026-01-30T04:01:48.216191Z","end":"2026-01-30T04:01:48.806824Z","steps":["trace[1783349196] 'process raft request' (duration: 590.489531ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:01:48.806954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:01:48.216174Z","time spent":"590.724518ms","remote":"127.0.0.1:54770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T04:01:49.167996Z","caller":"traceutil/trace.go:171","msg":"trace[1579792937] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"135.467429ms","start":"2026-01-30T04:01:49.032509Z","end":"2026-01-30T04:01:49.167977Z","steps":["trace[1579792937] 'read index received' (duration: 98.068238ms)","trace[1579792937] 'applied index is now lower than readState.Index' (duration: 37.398401ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T04:01:49.168149Z","caller":"traceutil/trace.go:171","msg":"trace[303313807] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"354.060459ms","start":"2026-01-30T04:01:48.81408Z","end":"2026-01-30T04:01:49.16814Z","steps":["trace[303313807] 'process raft request' (duration: 316.509244ms)","trace[303313807] 'compare' (duration: 36.906516ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T04:01:49.168208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:01:48.814063Z","time spent":"354.101991ms","remote":"127.0.0.1:54868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T04:01:49.168362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.851731ms","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-01-30T04:01:49.168378Z","caller":"traceutil/trace.go:171","msg":"trace[1577971245] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:617; }","duration":"135.891291ms","start":"2026-01-30T04:01:49.032481Z","end":"2026-01-30T04:01:49.168373Z","steps":["trace[1577971245] 'agreement among raft nodes before linearized reading' (duration: 135.807909ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:01:49.168462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.020947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-30T04:01:49.168475Z","caller":"traceutil/trace.go:171","msg":"trace[1185554551] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:617; }","duration":"108.045717ms","start":"2026-01-30T04:01:49.060425Z","end":"2026-01-30T04:01:49.168471Z","steps":["trace[1185554551] 'agreement among raft nodes before linearized reading' (duration: 108.011326ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:01:49.73567Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256276502137080630,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-30T04:01:50.560795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.841721ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T04:01:50.560868Z","caller":"traceutil/trace.go:171","msg":"trace[1041864363] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"1.326293474s","start":"2026-01-30T04:01:49.234561Z","end":"2026-01-30T04:01:50.560854Z","steps":["trace[1041864363] 'read index received' (duration: 745.285278ms)","trace[1041864363] 'applied index is now lower than readState.Index' (duration: 581.005486ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T04:01:50.561013Z","caller":"traceutil/trace.go:171","msg":"trace[635054118] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"1.387434896s","start":"2026-01-30T04:01:49.173569Z","end":"2026-01-30T04:01:50.561004Z","steps":["trace[635054118] 'process raft request' (duration: 806.311886ms)","trace[635054118] 'compare' (duration: 580.483931ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T04:01:50.561051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:01:49.173555Z","time spent":"1.387475196s","remote":"127.0.0.1:54868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T04:01:50.561218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.326660285s","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-01-30T04:01:50.561236Z","caller":"traceutil/trace.go:171","msg":"trace[1786695952] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:619; }","duration":"1.326696485s","start":"2026-01-30T04:01:49.234533Z","end":"2026-01-30T04:01:50.56123Z","steps":["trace[1786695952] 'agreement among raft nodes before linearized reading' (duration: 1.326612493s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:01:50.561254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:01:49.234519Z","time spent":"1.326731237s","remote":"127.0.0.1:54868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-30T04:01:50.561354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.308411979s","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-01-30T04:01:50.561379Z","caller":"traceutil/trace.go:171","msg":"trace[448025198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:619; }","duration":"1.308458009s","start":"2026-01-30T04:01:49.252913Z","end":"2026-01-30T04:01:50.561371Z","steps":["trace[448025198] 'agreement among raft nodes before linearized reading' (duration: 1.308406418s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:01:50.561395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:01:49.2529Z","time spent":"1.308492321s","remote":"127.0.0.1:54868","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-01-30T04:01:50.561721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.308452329s","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-01-30T04:01:50.561765Z","caller":"traceutil/trace.go:171","msg":"trace[970371602] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:619; }","duration":"1.308520002s","start":"2026-01-30T04:01:49.253233Z","end":"2026-01-30T04:01:50.561754Z","steps":["trace[970371602] 'agreement among raft nodes before linearized reading' (duration: 1.308392928s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:01:50.561786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:01:49.253214Z","time spent":"1.308568123s","remote":"127.0.0.1:54868","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-01-30T04:01:50.561915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.800615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T04:01:50.561931Z","caller":"traceutil/trace.go:171","msg":"trace[2068711913] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:619; }","duration":"676.848867ms","start":"2026-01-30T04:01:49.885075Z","end":"2026-01-30T04:01:50.561924Z","steps":["trace[2068711913] 'agreement among raft nodes before linearized reading' (duration: 676.794865ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:01:50.561942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:01:49.88505Z","time spent":"676.889358ms","remote":"127.0.0.1:54568","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-01-30T04:01:50.562031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.258466142s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.49\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-01-30T04:01:50.562043Z","caller":"traceutil/trace.go:171","msg":"trace[757508316] range","detail":"{range_begin:/registry/masterleases/199.204.45.49; range_end:; response_count:1; response_revision:619; }","duration":"1.258494783s","start":"2026-01-30T04:01:49.303545Z","end":"2026-01-30T04:01:50.562039Z","steps":["trace[757508316] 'agreement among raft nodes before linearized reading' (duration: 1.258455372s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:01:50.562055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:01:49.303531Z","time spent":"1.258521063s","remote":"127.0.0.1:54580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.49\" "} {"level":"warn","ts":"2026-01-30T04:03:17.463994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.010915ms","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-01-30T04:03:17.464054Z","caller":"traceutil/trace.go:171","msg":"trace[565158391] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1011; }","duration":"432.097546ms","start":"2026-01-30T04:03:17.031943Z","end":"2026-01-30T04:03:17.464041Z","steps":["trace[565158391] 'range keys from in-memory index tree' (duration: 431.892262ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:03:17.464083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:03:17.031936Z","time spent":"432.140247ms","remote":"127.0.0.1:54868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-30T04:03:17.46425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.186854ms","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-01-30T04:03:17.464274Z","caller":"traceutil/trace.go:171","msg":"trace[936512107] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1011; }","duration":"364.240876ms","start":"2026-01-30T04:03:17.100028Z","end":"2026-01-30T04:03:17.464269Z","steps":["trace[936512107] 'range keys from in-memory index tree' (duration: 364.096644ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:03:17.46429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:03:17.100016Z","time spent":"364.270546ms","remote":"127.0.0.1:54868","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-01-30T04:03:17.464449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.504351ms","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-01-30T04:03:17.464473Z","caller":"traceutil/trace.go:171","msg":"trace[1228259233] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1011; }","duration":"326.559732ms","start":"2026-01-30T04:03:17.137907Z","end":"2026-01-30T04:03:17.464467Z","steps":["trace[1228259233] 'range keys from in-memory index tree' (duration: 326.389999ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:03:17.464497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T04:03:17.137896Z","time spent":"326.597173ms","remote":"127.0.0.1:54868","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-01-30T04:03:17.46458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.639873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-30T04:03:17.464601Z","caller":"traceutil/trace.go:171","msg":"trace[1630192014] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1011; }","duration":"278.686673ms","start":"2026-01-30T04:03:17.185909Z","end":"2026-01-30T04:03:17.464596Z","steps":["trace[1630192014] 'count revisions from in-memory index tree' (duration: 278.584142ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T04:03:17.464688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.972639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-30T04:03:17.46471Z","caller":"traceutil/trace.go:171","msg":"trace[1070272879] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1011; }","duration":"241.03506ms","start":"2026-01-30T04:03:17.22367Z","end":"2026-01-30T04:03:17.464705Z","steps":["trace[1070272879] 'count revisions from in-memory index tree' (duration: 240.912418ms)"],"step_count":1}