{"level":"warn","ts":"2026-02-05T05:26:51.376596Z","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-05T05:26:51.376698Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.72: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.72:2380","--initial-cluster=instance=https://199.204.45.72:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.72:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.72: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-05T05:26:51.376774Z","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-05T05:26:51.376784Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-02-05T05:26:51.378085Z","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-05T05:26:51.385002Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"]} {"level":"info","ts":"2026-02-05T05:26:51.38517Z","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.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.72: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-05T05:26:51.393462Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.115046ms"} {"level":"info","ts":"2026-02-05T05:26:51.40771Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"50e03cebfd94cbba","cluster-id":"120c03c1d5b80f94"} {"level":"info","ts":"2026-02-05T05:26:51.407774Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=()"} {"level":"info","ts":"2026-02-05T05:26:51.407848Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 0"} {"level":"info","ts":"2026-02-05T05:26:51.407893Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 50e03cebfd94cbba [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-05T05:26:51.407905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 1"} {"level":"info","ts":"2026-02-05T05:26:51.407965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"warn","ts":"2026-02-05T05:26:51.417386Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-05T05:26:51.422204Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-05T05:26:51.426823Z","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-05T05:26:51.430526Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"50e03cebfd94cbba","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-05T05:26:51.436692Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"50e03cebfd94cbba","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-05T05:26:51.436812Z","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-05T05:26:51.436846Z","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-05T05:26:51.436857Z","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-05T05:26:51.437178Z","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-05T05:26:51.4375Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"50e03cebfd94cbba","initial-advertise-peer-urls":["https://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-05T05:26:51.437525Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-05T05:26:51.437812Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-02-05T05:26:51.437828Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-02-05T05:26:51.448494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"info","ts":"2026-02-05T05:26:51.448654Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","added-peer-id":"50e03cebfd94cbba","added-peer-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-02-05T05:26:51.909284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba is starting a new election at term 1"} {"level":"info","ts":"2026-02-05T05:26:51.909328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became pre-candidate at term 1"} {"level":"info","ts":"2026-02-05T05:26:51.909341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgPreVoteResp from 50e03cebfd94cbba at term 1"} {"level":"info","ts":"2026-02-05T05:26:51.909355Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became candidate at term 2"} {"level":"info","ts":"2026-02-05T05:26:51.90936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgVoteResp from 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-02-05T05:26:51.909371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became leader at term 2"} {"level":"info","ts":"2026-02-05T05:26:51.909378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 50e03cebfd94cbba elected leader 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-02-05T05:26:51.911101Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T05:26:51.918671Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"50e03cebfd94cbba","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.72:2379]}","request-path":"/0/members/50e03cebfd94cbba/attributes","cluster-id":"120c03c1d5b80f94","publish-timeout":"7s"} {"level":"info","ts":"2026-02-05T05:26:51.918769Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T05:26:51.925634Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T05:26:51.926896Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-05T05:26:51.928294Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.72:2379"} {"level":"info","ts":"2026-02-05T05:26:51.935066Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-05T05:26:51.935083Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-05T05:26:51.969634Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T05:26:51.969723Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T05:26:51.969744Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T05:27:05.83773Z","caller":"traceutil/trace.go:171","msg":"trace[1580537714] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"132.149725ms","start":"2026-02-05T05:27:05.705558Z","end":"2026-02-05T05:27:05.837707Z","steps":["trace[1580537714] 'process raft request' (duration: 132.018433ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:20.789918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.772307ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-05T05:27:20.789996Z","caller":"traceutil/trace.go:171","msg":"trace[233695636] linearizableReadLoop","detail":"{readStateIndex:524; appliedIndex:523; }","duration":"297.255707ms","start":"2026-02-05T05:27:20.492727Z","end":"2026-02-05T05:27:20.789983Z","steps":["trace[233695636] 'read index received' (duration: 125.367247ms)","trace[233695636] 'applied index is now lower than readState.Index' (duration: 171.88737ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:27:20.790133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.39865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-05T05:27:20.790151Z","caller":"traceutil/trace.go:171","msg":"trace[545209759] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:510; }","duration":"297.45367ms","start":"2026-02-05T05:27:20.49269Z","end":"2026-02-05T05:27:20.790144Z","steps":["trace[545209759] 'agreement among raft nodes before linearized reading' (duration: 297.363438ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:27:20.917522Z","caller":"traceutil/trace.go:171","msg":"trace[1840909094] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"118.215246ms","start":"2026-02-05T05:27:20.799281Z","end":"2026-02-05T05:27:20.917496Z","steps":["trace[1840909094] 'process raft request' (duration: 118.079913ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:27:20.925364Z","caller":"traceutil/trace.go:171","msg":"trace[633257152] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"124.543849ms","start":"2026-02-05T05:27:20.800802Z","end":"2026-02-05T05:27:20.925346Z","steps":["trace[633257152] 'process raft request' (duration: 124.433596ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:27:23.058386Z","caller":"traceutil/trace.go:171","msg":"trace[607651827] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"135.431551ms","start":"2026-02-05T05:27:22.922939Z","end":"2026-02-05T05:27:23.058371Z","steps":["trace[607651827] 'process raft request' (duration: 135.342699ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:27:26.07865Z","caller":"traceutil/trace.go:171","msg":"trace[2024609494] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"586.638063ms","start":"2026-02-05T05:27:25.491996Z","end":"2026-02-05T05:27:26.078634Z","steps":["trace[2024609494] 'process raft request' (duration: 586.544581ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:26.078974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:25.491987Z","time spent":"586.707755ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:27:27.950116Z","caller":"traceutil/trace.go:171","msg":"trace[200266582] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"285.083383ms","start":"2026-02-05T05:27:27.665008Z","end":"2026-02-05T05:27:27.950091Z","steps":["trace[200266582] 'process raft request' (duration: 284.957509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:28.207556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.847041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:27:28.207615Z","caller":"traceutil/trace.go:171","msg":"trace[573435721] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:530; }","duration":"127.949504ms","start":"2026-02-05T05:27:28.079651Z","end":"2026-02-05T05:27:28.2076Z","steps":["trace[573435721] 'range keys from in-memory index tree' (duration: 127.78336ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:28.207735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.778915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-05T05:27:28.207812Z","caller":"traceutil/trace.go:171","msg":"trace[1161400683] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:530; }","duration":"125.879888ms","start":"2026-02-05T05:27:28.081916Z","end":"2026-02-05T05:27:28.207796Z","steps":["trace[1161400683] 'range keys from in-memory index tree' (duration: 125.705504ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:27:42.483431Z","caller":"traceutil/trace.go:171","msg":"trace[1444895050] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"622.473066ms","start":"2026-02-05T05:27:41.860941Z","end":"2026-02-05T05:27:42.483414Z","steps":["trace[1444895050] 'process raft request' (duration: 622.374324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:42.483546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:41.860926Z","time spent":"622.561388ms","remote":"127.0.0.1:40560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2260,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T05:27:43.11465Z","caller":"traceutil/trace.go:171","msg":"trace[910080621] linearizableReadLoop","detail":"{readStateIndex:682; appliedIndex:680; }","duration":"1.10053703s","start":"2026-02-05T05:27:42.0141Z","end":"2026-02-05T05:27:43.114637Z","steps":["trace[910080621] 'read index received' (duration: 469.708157ms)","trace[910080621] 'applied index is now lower than readState.Index' (duration: 630.828353ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:27:43.114782Z","caller":"traceutil/trace.go:171","msg":"trace[1371418712] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"1.244745098s","start":"2026-02-05T05:27:41.870031Z","end":"2026-02-05T05:27:43.114776Z","steps":["trace[1371418712] 'process raft request' (duration: 1.244513993s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:43.114879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:41.870019Z","time spent":"1.24478333s","remote":"127.0.0.1:40560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2260,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T05:27:43.115011Z","caller":"traceutil/trace.go:171","msg":"trace[1855675445] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"1.224742421s","start":"2026-02-05T05:27:41.890263Z","end":"2026-02-05T05:27:43.115005Z","steps":["trace[1855675445] 'process raft request' (duration: 1.224354842s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:43.115052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:41.890248Z","time spent":"1.224792371s","remote":"127.0.0.1:40560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2260,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-05T05:27:43.115179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.101077132s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-05T05:27:43.115194Z","caller":"traceutil/trace.go:171","msg":"trace[2134782758] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:663; }","duration":"1.101120403s","start":"2026-02-05T05:27:42.014069Z","end":"2026-02-05T05:27:43.11519Z","steps":["trace[2134782758] 'agreement among raft nodes before linearized reading' (duration: 1.101038171s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:43.115209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:42.014056Z","time spent":"1.101149103s","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-05T05:27:43.115288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.032162419s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:27:43.115302Z","caller":"traceutil/trace.go:171","msg":"trace[990018015] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:663; }","duration":"1.032201169s","start":"2026-02-05T05:27:42.083095Z","end":"2026-02-05T05:27:43.115296Z","steps":["trace[990018015] 'agreement among raft nodes before linearized reading' (duration: 1.032182239s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:43.115313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:42.083081Z","time spent":"1.03222945s","remote":"127.0.0.1:57224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T05:27:43.115465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.605912ms","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-05T05:27:43.115478Z","caller":"traceutil/trace.go:171","msg":"trace[875010389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:663; }","duration":"786.628002ms","start":"2026-02-05T05:27:42.328846Z","end":"2026-02-05T05:27:43.115474Z","steps":["trace[875010389] 'agreement among raft nodes before linearized reading' (duration: 786.586851ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:43.115491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:42.328842Z","time spent":"786.646042ms","remote":"127.0.0.1:57464","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-05T05:27:43.115571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.268616ms","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-05T05:27:43.115583Z","caller":"traceutil/trace.go:171","msg":"trace[562634585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:663; }","duration":"787.289656ms","start":"2026-02-05T05:27:42.328289Z","end":"2026-02-05T05:27:43.115579Z","steps":["trace[562634585] 'agreement among raft nodes before linearized reading' (duration: 787.253835ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:43.115616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:42.328284Z","time spent":"787.328958ms","remote":"127.0.0.1:57464","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-05T05:27:43.115693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.750948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-05T05:27:43.115704Z","caller":"traceutil/trace.go:171","msg":"trace[1965366953] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:663; }","duration":"800.779599ms","start":"2026-02-05T05:27:42.314922Z","end":"2026-02-05T05:27:43.115701Z","steps":["trace[1965366953] 'agreement among raft nodes before linearized reading' (duration: 800.744788ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:43.115716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:27:42.314907Z","time spent":"800.806059ms","remote":"127.0.0.1:57464","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":"info","ts":"2026-02-05T05:27:43.27397Z","caller":"traceutil/trace.go:171","msg":"trace[1869116310] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"146.431709ms","start":"2026-02-05T05:27:43.127524Z","end":"2026-02-05T05:27:43.273956Z","steps":["trace[1869116310] 'process raft request' (duration: 146.333136ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:27:43.274091Z","caller":"traceutil/trace.go:171","msg":"trace[40378614] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"146.51023ms","start":"2026-02-05T05:27:43.127575Z","end":"2026-02-05T05:27:43.274086Z","steps":["trace[40378614] 'process raft request' (duration: 146.358877ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:27:43.274172Z","caller":"traceutil/trace.go:171","msg":"trace[1762044203] linearizableReadLoop","detail":"{readStateIndex:686; appliedIndex:682; }","duration":"145.093118ms","start":"2026-02-05T05:27:43.129074Z","end":"2026-02-05T05:27:43.274168Z","steps":["trace[1762044203] 'read index received' (duration: 41.579871ms)","trace[1762044203] 'applied index is now lower than readState.Index' (duration: 103.512747ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:27:43.274211Z","caller":"traceutil/trace.go:171","msg":"trace[944501442] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"146.601803ms","start":"2026-02-05T05:27:43.127606Z","end":"2026-02-05T05:27:43.274208Z","steps":["trace[944501442] 'process raft request' (duration: 146.4617ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:43.27432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.233452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:27:43.274337Z","caller":"traceutil/trace.go:171","msg":"trace[1853572563] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:667; }","duration":"145.275033ms","start":"2026-02-05T05:27:43.129057Z","end":"2026-02-05T05:27:43.274332Z","steps":["trace[1853572563] 'agreement among raft nodes before linearized reading' (duration: 145.235562ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:27:43.274447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.284986ms","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-05T05:27:43.274462Z","caller":"traceutil/trace.go:171","msg":"trace[310182672] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:667; }","duration":"138.308708ms","start":"2026-02-05T05:27:43.136149Z","end":"2026-02-05T05:27:43.274457Z","steps":["trace[310182672] 'agreement among raft nodes before linearized reading' (duration: 138.249716ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:27:48.490494Z","caller":"traceutil/trace.go:171","msg":"trace[838917767] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"167.797508ms","start":"2026-02-05T05:27:48.322675Z","end":"2026-02-05T05:27:48.490473Z","steps":["trace[838917767] 'process raft request' (duration: 167.667845ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:28:09.612582Z","caller":"traceutil/trace.go:171","msg":"trace[1498563177] linearizableReadLoop","detail":"{readStateIndex:810; appliedIndex:809; }","duration":"238.647254ms","start":"2026-02-05T05:28:09.37391Z","end":"2026-02-05T05:28:09.612557Z","steps":["trace[1498563177] 'read index received' (duration: 168.748869ms)","trace[1498563177] 'applied index is now lower than readState.Index' (duration: 69.897445ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:28:09.613217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.264047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-05T05:28:09.614752Z","caller":"traceutil/trace.go:171","msg":"trace[1675701553] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:784; }","duration":"240.013444ms","start":"2026-02-05T05:28:09.373886Z","end":"2026-02-05T05:28:09.613899Z","steps":["trace[1675701553] 'agreement among raft nodes before linearized reading' (duration: 239.098784ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:09.615261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.833272ms","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-05T05:28:09.615357Z","caller":"traceutil/trace.go:171","msg":"trace[505103655] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:784; }","duration":"215.905353ms","start":"2026-02-05T05:28:09.399407Z","end":"2026-02-05T05:28:09.615312Z","steps":["trace[505103655] 'agreement among raft nodes before linearized reading' (duration: 215.791301ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:28:09.613373Z","caller":"traceutil/trace.go:171","msg":"trace[235551862] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"248.416062ms","start":"2026-02-05T05:28:09.364942Z","end":"2026-02-05T05:28:09.613358Z","steps":["trace[235551862] 'process raft request' (duration: 177.786081ms)","trace[235551862] 'compare' (duration: 69.423105ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:28:09.615905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.389118ms","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-05T05:28:09.615994Z","caller":"traceutil/trace.go:171","msg":"trace[1530315471] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:784; }","duration":"205.456749ms","start":"2026-02-05T05:28:09.410497Z","end":"2026-02-05T05:28:09.615953Z","steps":["trace[1530315471] 'agreement among raft nodes before linearized reading' (duration: 205.356007ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:09.956757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.985734ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:28:09.957374Z","caller":"traceutil/trace.go:171","msg":"trace[1863940175] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"335.021611ms","start":"2026-02-05T05:28:09.622314Z","end":"2026-02-05T05:28:09.957335Z","steps":["trace[1863940175] 'process raft request' (duration: 214.716507ms)","trace[1863940175] 'compare' (duration: 118.83219ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:28:09.957746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:09.62229Z","time spent":"335.168615ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:28:09.959222Z","caller":"traceutil/trace.go:171","msg":"trace[950812791] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"335.90118ms","start":"2026-02-05T05:28:09.623302Z","end":"2026-02-05T05:28:09.959203Z","steps":["trace[950812791] 'process raft request' (duration: 333.905026ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:09.96014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:09.623284Z","time spent":"336.78725ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:28:10.198356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.241427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:28:10.198448Z","caller":"traceutil/trace.go:171","msg":"trace[887893667] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:787; }","duration":"118.379291ms","start":"2026-02-05T05:28:10.080052Z","end":"2026-02-05T05:28:10.198431Z","steps":["trace[887893667] 'range keys from in-memory index tree' (duration: 118.133325ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:28:21.916276Z","caller":"traceutil/trace.go:171","msg":"trace[173714198] linearizableReadLoop","detail":"{readStateIndex:899; appliedIndex:898; }","duration":"221.731123ms","start":"2026-02-05T05:28:21.694527Z","end":"2026-02-05T05:28:21.916258Z","steps":["trace[173714198] 'read index received' (duration: 221.56657ms)","trace[173714198] 'applied index is now lower than readState.Index' (duration: 163.773µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:28:21.916426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.884487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-05T05:28:21.916453Z","caller":"traceutil/trace.go:171","msg":"trace[613913822] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:868; }","duration":"221.947607ms","start":"2026-02-05T05:28:21.694499Z","end":"2026-02-05T05:28:21.916446Z","steps":["trace[613913822] 'agreement among raft nodes before linearized reading' (duration: 221.832215ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:28:21.916475Z","caller":"traceutil/trace.go:171","msg":"trace[802333946] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"254.779139ms","start":"2026-02-05T05:28:21.661675Z","end":"2026-02-05T05:28:21.916455Z","steps":["trace[802333946] 'process raft request' (duration: 254.462542ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:22.75427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.753136ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:28:22.754341Z","caller":"traceutil/trace.go:171","msg":"trace[769894991] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:868; }","duration":"265.830608ms","start":"2026-02-05T05:28:22.4885Z","end":"2026-02-05T05:28:22.754331Z","steps":["trace[769894991] 'range keys from in-memory index tree' (duration: 265.741316ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:22.754571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.598359ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:28:22.754611Z","caller":"traceutil/trace.go:171","msg":"trace[2066536650] linearizableReadLoop","detail":"{readStateIndex:900; appliedIndex:899; }","duration":"733.289705ms","start":"2026-02-05T05:28:22.021316Z","end":"2026-02-05T05:28:22.754605Z","steps":["trace[2066536650] 'read index received' (duration: 415.614284ms)","trace[2066536650] 'applied index is now lower than readState.Index' (duration: 317.674921ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:28:22.754711Z","caller":"traceutil/trace.go:171","msg":"trace[229884475] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"835.335375ms","start":"2026-02-05T05:28:21.919369Z","end":"2026-02-05T05:28:22.754704Z","steps":["trace[229884475] 'process raft request' (duration: 517.569592ms)","trace[229884475] 'compare' (duration: 317.274842ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:28:22.754739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:21.919353Z","time spent":"835.371275ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:28:22.754869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.55217ms","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-05T05:28:22.754885Z","caller":"traceutil/trace.go:171","msg":"trace[312672124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:869; }","duration":"733.591822ms","start":"2026-02-05T05:28:22.021288Z","end":"2026-02-05T05:28:22.75488Z","steps":["trace[312672124] 'agreement among raft nodes before linearized reading' (duration: 733.53525ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:22.754899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:22.021275Z","time spent":"733.621192ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T05:28:22.754972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.01605ms","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-05T05:28:22.754986Z","caller":"traceutil/trace.go:171","msg":"trace[1531007181] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:869; }","duration":"733.05102ms","start":"2026-02-05T05:28:22.02193Z","end":"2026-02-05T05:28:22.754982Z","steps":["trace[1531007181] 'agreement among raft nodes before linearized reading' (duration: 733.016679ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:22.754997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:22.021926Z","time spent":"733.06827ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T05:28:22.755101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.362233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:28:22.755114Z","caller":"traceutil/trace.go:171","msg":"trace[1436693689] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:869; }","duration":"677.391524ms","start":"2026-02-05T05:28:22.077718Z","end":"2026-02-05T05:28:22.75511Z","steps":["trace[1436693689] 'agreement among raft nodes before linearized reading' (duration: 677.370543ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:22.755125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:22.077711Z","time spent":"677.411535ms","remote":"127.0.0.1:57224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T05:28:23.001258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.669474ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:28:23.001325Z","caller":"traceutil/trace.go:171","msg":"trace[145407786] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"239.796465ms","start":"2026-02-05T05:28:22.761515Z","end":"2026-02-05T05:28:23.001312Z","steps":["trace[145407786] 'process raft request' (duration: 106.992929ms)","trace[145407786] 'compare' (duration: 132.50266ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:28:25.531607Z","caller":"traceutil/trace.go:171","msg":"trace[1371986779] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"119.375925ms","start":"2026-02-05T05:28:25.412212Z","end":"2026-02-05T05:28:25.531588Z","steps":["trace[1371986779] 'process raft request' (duration: 94.618798ms)","trace[1371986779] 'compare' (duration: 24.673615ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:28:26.070028Z","caller":"traceutil/trace.go:171","msg":"trace[1643690228] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"138.472469ms","start":"2026-02-05T05:28:25.931542Z","end":"2026-02-05T05:28:26.070014Z","steps":["trace[1643690228] 'process raft request' (duration: 138.389107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:27.442072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.778583ms","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-05T05:28:27.442119Z","caller":"traceutil/trace.go:171","msg":"trace[1443604772] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:882; }","duration":"419.855126ms","start":"2026-02-05T05:28:27.022254Z","end":"2026-02-05T05:28:27.44211Z","steps":["trace[1443604772] 'range keys from in-memory index tree' (duration: 419.727832ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:27.442136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:27.022242Z","time spent":"419.889926ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T05:28:27.442411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.314814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:28:27.442433Z","caller":"traceutil/trace.go:171","msg":"trace[216164291] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:882; }","duration":"366.357355ms","start":"2026-02-05T05:28:27.076071Z","end":"2026-02-05T05:28:27.442428Z","steps":["trace[216164291] 'range keys from in-memory index tree' (duration: 366.278714ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:27.442447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:27.076058Z","time spent":"366.385316ms","remote":"127.0.0.1:57224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T05:28:35.660929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.350926ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:28:35.661003Z","caller":"traceutil/trace.go:171","msg":"trace[1573174120] linearizableReadLoop","detail":"{readStateIndex:938; appliedIndex:937; }","duration":"158.583944ms","start":"2026-02-05T05:28:35.502407Z","end":"2026-02-05T05:28:35.660991Z","steps":["trace[1573174120] 'read index received' (duration: 24.57µs)","trace[1573174120] 'applied index is now lower than readState.Index' (duration: 158.558394ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:28:35.661059Z","caller":"traceutil/trace.go:171","msg":"trace[1738644553] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"352.298212ms","start":"2026-02-05T05:28:35.308755Z","end":"2026-02-05T05:28:35.661053Z","steps":["trace[1738644553] 'process raft request' (duration: 159.755271ms)","trace[1738644553] 'compare' (duration: 192.167923ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:28:35.661089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:35.308743Z","time spent":"352.328792ms","remote":"127.0.0.1:57256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:28:35.661285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.87612ms","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-05T05:28:35.661299Z","caller":"traceutil/trace.go:171","msg":"trace[915501088] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:904; }","duration":"158.913092ms","start":"2026-02-05T05:28:35.502381Z","end":"2026-02-05T05:28:35.661294Z","steps":["trace[915501088] 'agreement among raft nodes before linearized reading' (duration: 158.804209ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:35.661399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.703731ms","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-02-05T05:28:35.661412Z","caller":"traceutil/trace.go:171","msg":"trace[1868829653] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:904; }","duration":"148.740562ms","start":"2026-02-05T05:28:35.512667Z","end":"2026-02-05T05:28:35.661408Z","steps":["trace[1868829653] 'agreement among raft nodes before linearized reading' (duration: 148.702411ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:28:36.371378Z","caller":"traceutil/trace.go:171","msg":"trace[544517360] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"702.450387ms","start":"2026-02-05T05:28:35.668913Z","end":"2026-02-05T05:28:36.371363Z","steps":["trace[544517360] 'process raft request' (duration: 702.343635ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:36.371469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:35.668897Z","time spent":"702.523808ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:28:36.420619Z","caller":"traceutil/trace.go:171","msg":"trace[517173758] linearizableReadLoop","detail":"{readStateIndex:941; appliedIndex:940; }","duration":"439.677234ms","start":"2026-02-05T05:28:35.980933Z","end":"2026-02-05T05:28:36.42061Z","steps":["trace[517173758] 'read index received' (duration: 390.823276ms)","trace[517173758] 'applied index is now lower than readState.Index' (duration: 48.853468ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:28:36.420769Z","caller":"traceutil/trace.go:171","msg":"trace[462923028] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"532.22979ms","start":"2026-02-05T05:28:35.888534Z","end":"2026-02-05T05:28:36.420764Z","steps":["trace[462923028] 'process raft request' (duration: 532.033545ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:36.420815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:35.888517Z","time spent":"532.26591ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:28:36.420901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.96535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:28:36.420918Z","caller":"traceutil/trace.go:171","msg":"trace[677727978] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:907; }","duration":"439.983271ms","start":"2026-02-05T05:28:35.98093Z","end":"2026-02-05T05:28:36.420913Z","steps":["trace[677727978] 'agreement among raft nodes before linearized reading' (duration: 439.95408ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:36.420932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:35.980896Z","time spent":"440.032652ms","remote":"127.0.0.1:57374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-02-05T05:28:36.421006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.349028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:28:36.421019Z","caller":"traceutil/trace.go:171","msg":"trace[262132600] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:907; }","duration":"343.383158ms","start":"2026-02-05T05:28:36.077633Z","end":"2026-02-05T05:28:36.421016Z","steps":["trace[262132600] 'agreement among raft nodes before linearized reading' (duration: 343.362798ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:36.42103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:36.077623Z","time spent":"343.404598ms","remote":"127.0.0.1:57224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T05:28:36.421171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.618961ms","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-05T05:28:36.421186Z","caller":"traceutil/trace.go:171","msg":"trace[1810883138] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:907; }","duration":"323.648701ms","start":"2026-02-05T05:28:36.097533Z","end":"2026-02-05T05:28:36.421182Z","steps":["trace[1810883138] 'agreement among raft nodes before linearized reading' (duration: 323.594909ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:36.421201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:36.097526Z","time spent":"323.670262ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-05T05:28:37.132446Z","caller":"traceutil/trace.go:171","msg":"trace[149384808] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"146.038634ms","start":"2026-02-05T05:28:36.986392Z","end":"2026-02-05T05:28:37.132431Z","steps":["trace[149384808] 'process raft request' (duration: 145.971202ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:28:37.875317Z","caller":"traceutil/trace.go:171","msg":"trace[693684385] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"158.837011ms","start":"2026-02-05T05:28:37.716457Z","end":"2026-02-05T05:28:37.875294Z","steps":["trace[693684385] 'process raft request' (duration: 158.714928ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:54.442958Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680217649308965040,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T05:28:54.558816Z","caller":"traceutil/trace.go:171","msg":"trace[1659401899] linearizableReadLoop","detail":"{readStateIndex:1060; appliedIndex:1059; }","duration":"616.398062ms","start":"2026-02-05T05:28:53.942397Z","end":"2026-02-05T05:28:54.558796Z","steps":["trace[1659401899] 'read index received' (duration: 616.215528ms)","trace[1659401899] 'applied index is now lower than readState.Index' (duration: 181.784µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:28:54.559421Z","caller":"traceutil/trace.go:171","msg":"trace[1212655183] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"617.484235ms","start":"2026-02-05T05:28:53.94192Z","end":"2026-02-05T05:28:54.559405Z","steps":["trace[1212655183] 'process raft request' (duration: 616.75329ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:54.559536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:53.941835Z","time spent":"617.621188ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:28:54.559726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.319792ms","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-02-05T05:28:54.559756Z","caller":"traceutil/trace.go:171","msg":"trace[1388230414] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1023; }","duration":"617.365373ms","start":"2026-02-05T05:28:53.942382Z","end":"2026-02-05T05:28:54.559747Z","steps":["trace[1388230414] 'agreement among raft nodes before linearized reading' (duration: 617.309901ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:54.559785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:53.942376Z","time spent":"617.402033ms","remote":"127.0.0.1:57672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":29,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-02-05T05:28:54.561424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.172391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:28:54.561615Z","caller":"traceutil/trace.go:171","msg":"trace[2091444531] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1023; }","duration":"481.870898ms","start":"2026-02-05T05:28:54.079731Z","end":"2026-02-05T05:28:54.561602Z","steps":["trace[2091444531] 'agreement among raft nodes before linearized reading' (duration: 480.184991ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:54.561805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:54.079716Z","time spent":"482.075192ms","remote":"127.0.0.1:57224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T05:28:54.562736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.413882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-02-05T05:28:54.563157Z","caller":"traceutil/trace.go:171","msg":"trace[127513618] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1023; }","duration":"243.830092ms","start":"2026-02-05T05:28:54.319308Z","end":"2026-02-05T05:28:54.563138Z","steps":["trace[127513618] 'agreement among raft nodes before linearized reading' (duration: 243.193367ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:54.562923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.416752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:28:54.566327Z","caller":"traceutil/trace.go:171","msg":"trace[2002576970] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1023; }","duration":"323.847066ms","start":"2026-02-05T05:28:54.242466Z","end":"2026-02-05T05:28:54.566313Z","steps":["trace[2002576970] 'agreement among raft nodes before linearized reading' (duration: 320.395961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:54.566708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:54.242454Z","time spent":"324.235974ms","remote":"127.0.0.1:57502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-02-05T05:28:55.013033Z","caller":"traceutil/trace.go:171","msg":"trace[1032514755] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"439.428099ms","start":"2026-02-05T05:28:54.573588Z","end":"2026-02-05T05:28:55.013016Z","steps":["trace[1032514755] 'process raft request' (duration: 420.722354ms)","trace[1032514755] 'compare' (duration: 18.46918ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:28:55.013123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:54.573577Z","time spent":"439.502331ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":495,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:28:55.071456Z","caller":"traceutil/trace.go:171","msg":"trace[97668639] linearizableReadLoop","detail":"{readStateIndex:1063; appliedIndex:1060; }","duration":"101.791284ms","start":"2026-02-05T05:28:54.969644Z","end":"2026-02-05T05:28:55.071435Z","steps":["trace[97668639] 'read index received' (duration: 24.717235ms)","trace[97668639] 'applied index is now lower than readState.Index' (duration: 77.073129ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:28:55.071878Z","caller":"traceutil/trace.go:171","msg":"trace[1428861570] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"498.157892ms","start":"2026-02-05T05:28:54.573706Z","end":"2026-02-05T05:28:55.071864Z","steps":["trace[1428861570] 'process raft request' (duration: 497.59604ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:28:55.071992Z","caller":"traceutil/trace.go:171","msg":"trace[61400788] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"498.239323ms","start":"2026-02-05T05:28:54.573743Z","end":"2026-02-05T05:28:55.071982Z","steps":["trace[61400788] 'process raft request' (duration: 497.654691ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:55.072229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:54.573702Z","time spent":"498.354476ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:28:55.073015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:54.57374Z","time spent":"498.435347ms","remote":"127.0.0.1:57464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:28:55.071819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.150882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-05T05:28:55.073385Z","caller":"traceutil/trace.go:171","msg":"trace[943830993] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1026; }","duration":"103.772098ms","start":"2026-02-05T05:28:54.969598Z","end":"2026-02-05T05:28:55.07337Z","steps":["trace[943830993] 'agreement among raft nodes before linearized reading' (duration: 102.014709ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:28:55.217065Z","caller":"traceutil/trace.go:171","msg":"trace[912397748] linearizableReadLoop","detail":"{readStateIndex:1064; appliedIndex:1063; }","duration":"136.053878ms","start":"2026-02-05T05:28:55.080988Z","end":"2026-02-05T05:28:55.217042Z","steps":["trace[912397748] 'read index received' (duration: 55.989223ms)","trace[912397748] 'applied index is now lower than readState.Index' (duration: 80.063665ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:28:55.217426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.413575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:28:55.217874Z","caller":"traceutil/trace.go:171","msg":"trace[1766641707] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1027; }","duration":"136.829844ms","start":"2026-02-05T05:28:55.080971Z","end":"2026-02-05T05:28:55.217801Z","steps":["trace[1766641707] 'agreement among raft nodes before linearized reading' (duration: 136.323723ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:28:55.217468Z","caller":"traceutil/trace.go:171","msg":"trace[916292440] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"136.964498ms","start":"2026-02-05T05:28:55.080491Z","end":"2026-02-05T05:28:55.217455Z","steps":["trace[916292440] 'process raft request' (duration: 56.539365ms)","trace[916292440] 'compare' (duration: 79.915142ms)"],"step_count":2}