{"level":"warn","ts":"2026-02-10T18:49:48.18952Z","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-10T18:49:48.189673Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.125:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.125:2380","--initial-cluster=instance=https://199.19.213.125:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.125:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.125: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-10T18:49:48.189807Z","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-10T18:49:48.18982Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.125:2380"]} {"level":"info","ts":"2026-02-10T18:49:48.18984Z","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-10T18:49:48.199304Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.125:2379"]} {"level":"info","ts":"2026-02-10T18:49:48.199442Z","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.19.213.125:2380"],"listen-peer-urls":["https://199.19.213.125:2380"],"advertise-client-urls":["https://199.19.213.125:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.125:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.125: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-10T18:49:48.207567Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.869073ms"} {"level":"info","ts":"2026-02-10T18:49:48.221773Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b448fdce642f2755","cluster-id":"66f7e5dc9a72c5e3"} {"level":"info","ts":"2026-02-10T18:49:48.221885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b448fdce642f2755 switched to configuration voters=()"} {"level":"info","ts":"2026-02-10T18:49:48.221912Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b448fdce642f2755 became follower at term 0"} {"level":"info","ts":"2026-02-10T18:49:48.221939Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b448fdce642f2755 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-10T18:49:48.22195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b448fdce642f2755 became follower at term 1"} {"level":"info","ts":"2026-02-10T18:49:48.221979Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b448fdce642f2755 switched to configuration voters=(12990912188036097877)"} {"level":"warn","ts":"2026-02-10T18:49:48.231988Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-10T18:49:48.248373Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-10T18:49:48.25051Z","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-10T18:49:48.257248Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b448fdce642f2755","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-10T18:49:48.25963Z","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-10T18:49:48.260248Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b448fdce642f2755","initial-advertise-peer-urls":["https://199.19.213.125:2380"],"listen-peer-urls":["https://199.19.213.125:2380"],"advertise-client-urls":["https://199.19.213.125:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.125:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-10T18:49:48.260272Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-10T18:49:48.260419Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.125:2380"} {"level":"info","ts":"2026-02-10T18:49:48.26043Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.125:2380"} {"level":"info","ts":"2026-02-10T18:49:48.261774Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b448fdce642f2755","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-10T18:49:48.262205Z","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-10T18:49:48.264065Z","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-10T18:49:48.264088Z","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-10T18:49:48.282925Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b448fdce642f2755 switched to configuration voters=(12990912188036097877)"} {"level":"info","ts":"2026-02-10T18:49:48.283208Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"66f7e5dc9a72c5e3","local-member-id":"b448fdce642f2755","added-peer-id":"b448fdce642f2755","added-peer-peer-urls":["https://199.19.213.125:2380"]} {"level":"info","ts":"2026-02-10T18:49:48.825859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b448fdce642f2755 is starting a new election at term 1"} {"level":"info","ts":"2026-02-10T18:49:48.825918Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b448fdce642f2755 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-10T18:49:48.82595Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b448fdce642f2755 received MsgPreVoteResp from b448fdce642f2755 at term 1"} {"level":"info","ts":"2026-02-10T18:49:48.825978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b448fdce642f2755 became candidate at term 2"} {"level":"info","ts":"2026-02-10T18:49:48.825987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b448fdce642f2755 received MsgVoteResp from b448fdce642f2755 at term 2"} {"level":"info","ts":"2026-02-10T18:49:48.825995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b448fdce642f2755 became leader at term 2"} {"level":"info","ts":"2026-02-10T18:49:48.826005Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b448fdce642f2755 elected leader b448fdce642f2755 at term 2"} {"level":"info","ts":"2026-02-10T18:49:48.831103Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b448fdce642f2755","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.125:2379]}","request-path":"/0/members/b448fdce642f2755/attributes","cluster-id":"66f7e5dc9a72c5e3","publish-timeout":"7s"} {"level":"info","ts":"2026-02-10T18:49:48.831212Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-10T18:49:48.831507Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T18:49:48.831665Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-10T18:49:48.83186Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-10T18:49:48.831874Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-10T18:49:48.833475Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-10T18:49:48.866619Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.125:2379"} {"level":"info","ts":"2026-02-10T18:49:48.899031Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"66f7e5dc9a72c5e3","local-member-id":"b448fdce642f2755","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T18:49:48.899128Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T18:49:48.899222Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T18:50:00.368619Z","caller":"traceutil/trace.go:171","msg":"trace[925613774] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"130.306071ms","start":"2026-02-10T18:50:00.238281Z","end":"2026-02-10T18:50:00.368587Z","steps":["trace[925613774] 'process raft request' (duration: 130.192848ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:50:16.337149Z","caller":"traceutil/trace.go:171","msg":"trace[932449797] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"576.42317ms","start":"2026-02-10T18:50:15.76051Z","end":"2026-02-10T18:50:16.336934Z","steps":["trace[932449797] 'process raft request' (duration: 576.266436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:16.337836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:50:15.760495Z","time spent":"576.722077ms","remote":"127.0.0.1:33996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:50:17.300556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.787547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12669"} {"level":"info","ts":"2026-02-10T18:50:17.300634Z","caller":"traceutil/trace.go:171","msg":"trace[366889341] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:476; }","duration":"486.90378ms","start":"2026-02-10T18:50:16.813712Z","end":"2026-02-10T18:50:17.300615Z","steps":["trace[366889341] 'range keys from in-memory index tree' (duration: 486.682804ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:17.300673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:50:16.813699Z","time spent":"486.964702ms","remote":"127.0.0.1:33846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":12692,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"warn","ts":"2026-02-10T18:50:17.300949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.500525ms","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-10T18:50:17.300975Z","caller":"traceutil/trace.go:171","msg":"trace[130580101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:476; }","duration":"375.554456ms","start":"2026-02-10T18:50:16.925413Z","end":"2026-02-10T18:50:17.300967Z","steps":["trace[130580101] 'range keys from in-memory index tree' (duration: 375.421072ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:17.300991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:50:16.9254Z","time spent":"375.586757ms","remote":"127.0.0.1:33996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-10T18:50:17.301159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.925052ms","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-10T18:50:17.301177Z","caller":"traceutil/trace.go:171","msg":"trace[536981397] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:476; }","duration":"275.991655ms","start":"2026-02-10T18:50:17.025179Z","end":"2026-02-10T18:50:17.301171Z","steps":["trace[536981397] 'range keys from in-memory index tree' (duration: 275.843621ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:17.301401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.498008ms","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-10T18:50:17.301422Z","caller":"traceutil/trace.go:171","msg":"trace[626483210] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:476; }","duration":"222.516908ms","start":"2026-02-10T18:50:17.078898Z","end":"2026-02-10T18:50:17.301415Z","steps":["trace[626483210] 'range keys from in-memory index tree' (duration: 222.438606ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:50:18.017608Z","caller":"traceutil/trace.go:171","msg":"trace[1188932029] linearizableReadLoop","detail":"{readStateIndex:498; appliedIndex:494; }","duration":"461.661301ms","start":"2026-02-10T18:50:17.555928Z","end":"2026-02-10T18:50:18.017589Z","steps":["trace[1188932029] 'read index received' (duration: 458.308676ms)","trace[1188932029] 'applied index is now lower than readState.Index' (duration: 3.352165ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:50:18.017645Z","caller":"traceutil/trace.go:171","msg":"trace[1103869839] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"703.771673ms","start":"2026-02-10T18:50:17.313839Z","end":"2026-02-10T18:50:18.01761Z","steps":["trace[1103869839] 'process raft request' (duration: 703.647669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:18.017761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.817995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:50:18.017784Z","caller":"traceutil/trace.go:171","msg":"trace[490152856] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:479; }","duration":"461.892697ms","start":"2026-02-10T18:50:17.555885Z","end":"2026-02-10T18:50:18.017778Z","steps":["trace[490152856] 'agreement among raft nodes before linearized reading' (duration: 461.777694ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:18.017804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:50:17.555863Z","time spent":"461.937737ms","remote":"127.0.0.1:33710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T18:50:18.017884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:50:17.313821Z","time spent":"703.924317ms","remote":"127.0.0.1:33996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:50:18.018004Z","caller":"traceutil/trace.go:171","msg":"trace[514902743] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"702.654653ms","start":"2026-02-10T18:50:17.315342Z","end":"2026-02-10T18:50:18.017997Z","steps":["trace[514902743] 'process raft request' (duration: 702.208982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:18.018069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:50:17.315335Z","time spent":"702.686475ms","remote":"127.0.0.1:33996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:50:18.018126Z","caller":"traceutil/trace.go:171","msg":"trace[2145621721] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"701.214297ms","start":"2026-02-10T18:50:17.316906Z","end":"2026-02-10T18:50:18.01812Z","steps":["trace[2145621721] 'process raft request' (duration: 700.667113ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:18.018151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:50:17.316899Z","time spent":"701.238057ms","remote":"127.0.0.1:33996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:50:19.319177Z","caller":"traceutil/trace.go:171","msg":"trace[70972746] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"102.93083ms","start":"2026-02-10T18:50:19.216231Z","end":"2026-02-10T18:50:19.319161Z","steps":["trace[70972746] 'process raft request' (duration: 102.53149ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:22.061951Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2834343377366270562,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T18:50:22.563075Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2834343377366270562,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T18:50:22.772814Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.338511318s","expected-duration":"1s"} {"level":"info","ts":"2026-02-10T18:50:22.7731Z","caller":"traceutil/trace.go:171","msg":"trace[1034328058] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:538; }","duration":"1.211305213s","start":"2026-02-10T18:50:21.561779Z","end":"2026-02-10T18:50:22.773084Z","steps":["trace[1034328058] 'read index received' (duration: 1.2111786s)","trace[1034328058] 'applied index is now lower than readState.Index' (duration: 125.953µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:50:22.773182Z","caller":"traceutil/trace.go:171","msg":"trace[1197863323] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"1.338955729s","start":"2026-02-10T18:50:21.434219Z","end":"2026-02-10T18:50:22.773175Z","steps":["trace[1197863323] 'process raft request' (duration: 1.338776504s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:22.773275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:50:21.434203Z","time spent":"1.33901725s","remote":"127.0.0.1:33996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:50:22.773614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.001696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.125\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-10T18:50:22.774693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.279819ms","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-10T18:50:22.774726Z","caller":"traceutil/trace.go:171","msg":"trace[314367680] range","detail":"{range_begin:/registry/masterleases/199.19.213.125; range_end:; response_count:1; response_revision:520; }","duration":"523.146145ms","start":"2026-02-10T18:50:22.251569Z","end":"2026-02-10T18:50:22.774715Z","steps":["trace[314367680] 'agreement among raft nodes before linearized reading' (duration: 522.013957ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:22.774877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:50:22.251549Z","time spent":"523.318039ms","remote":"127.0.0.1:33766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.125\" "} {"level":"info","ts":"2026-02-10T18:50:22.77488Z","caller":"traceutil/trace.go:171","msg":"trace[359632121] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:520; }","duration":"416.411752ms","start":"2026-02-10T18:50:22.35837Z","end":"2026-02-10T18:50:22.774782Z","steps":["trace[359632121] 'agreement among raft nodes before linearized reading' (duration: 416.175876ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:22.773644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.976301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-10T18:50:22.775013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:50:22.358356Z","time spent":"416.622077ms","remote":"127.0.0.1:33996","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":"info","ts":"2026-02-10T18:50:22.77507Z","caller":"traceutil/trace.go:171","msg":"trace[1540523496] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:520; }","duration":"723.446197ms","start":"2026-02-10T18:50:22.051614Z","end":"2026-02-10T18:50:22.775061Z","steps":["trace[1540523496] 'agreement among raft nodes before linearized reading' (duration: 721.855918ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:22.775231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:50:22.051598Z","time spent":"723.622571ms","remote":"127.0.0.1:33996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-10T18:50:22.77384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.01275ms","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-10T18:50:22.775401Z","caller":"traceutil/trace.go:171","msg":"trace[1010836079] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:520; }","duration":"714.589869ms","start":"2026-02-10T18:50:22.060802Z","end":"2026-02-10T18:50:22.775392Z","steps":["trace[1010836079] 'agreement among raft nodes before linearized reading' (duration: 712.944919ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:22.775448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:50:22.060795Z","time spent":"714.64469ms","remote":"127.0.0.1:33996","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-10T18:50:22.77398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.230667ms","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-10T18:50:22.775548Z","caller":"traceutil/trace.go:171","msg":"trace[1822003584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:520; }","duration":"721.815577ms","start":"2026-02-10T18:50:22.053727Z","end":"2026-02-10T18:50:22.775542Z","steps":["trace[1822003584] 'agreement among raft nodes before linearized reading' (duration: 720.161436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:22.775591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:50:22.05372Z","time spent":"721.864978ms","remote":"127.0.0.1:33996","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-10T18:50:22.774027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.212247797s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:50:22.775728Z","caller":"traceutil/trace.go:171","msg":"trace[1922833122] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:520; }","duration":"1.213976179s","start":"2026-02-10T18:50:21.561743Z","end":"2026-02-10T18:50:22.775719Z","steps":["trace[1922833122] 'agreement among raft nodes before linearized reading' (duration: 1.212268117s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:22.775802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:50:21.561725Z","time spent":"1.214069191s","remote":"127.0.0.1:33710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-10T18:50:22.910561Z","caller":"traceutil/trace.go:171","msg":"trace[1711165172] linearizableReadLoop","detail":"{readStateIndex:541; appliedIndex:539; }","duration":"129.663126ms","start":"2026-02-10T18:50:22.780884Z","end":"2026-02-10T18:50:22.910547Z","steps":["trace[1711165172] 'read index received' (duration: 83.926972ms)","trace[1711165172] 'applied index is now lower than readState.Index' (duration: 45.735564ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:50:22.910652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.748008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:50:22.910704Z","caller":"traceutil/trace.go:171","msg":"trace[392542200] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:524; }","duration":"129.81809ms","start":"2026-02-10T18:50:22.780879Z","end":"2026-02-10T18:50:22.910697Z","steps":["trace[392542200] 'agreement among raft nodes before linearized reading' (duration: 129.725348ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:50:22.911339Z","caller":"traceutil/trace.go:171","msg":"trace[43274150] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"130.480517ms","start":"2026-02-10T18:50:22.78085Z","end":"2026-02-10T18:50:22.911331Z","steps":["trace[43274150] 'process raft request' (duration: 129.594955ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:50:22.911481Z","caller":"traceutil/trace.go:171","msg":"trace[1023929580] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"130.563928ms","start":"2026-02-10T18:50:22.780911Z","end":"2026-02-10T18:50:22.911474Z","steps":["trace[1023929580] 'process raft request' (duration: 129.590484ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:50:22.911934Z","caller":"traceutil/trace.go:171","msg":"trace[48659126] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"130.962758ms","start":"2026-02-10T18:50:22.780946Z","end":"2026-02-10T18:50:22.911909Z","steps":["trace[48659126] 'process raft request' (duration: 129.571794ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:50:22.912104Z","caller":"traceutil/trace.go:171","msg":"trace[2003673012] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"131.141542ms","start":"2026-02-10T18:50:22.780952Z","end":"2026-02-10T18:50:22.912093Z","steps":["trace[2003673012] 'process raft request' (duration: 129.578984ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:31.053755Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2834343377366270653,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T18:50:31.553934Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2834343377366270653,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T18:50:31.784063Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.672456378s","expected-duration":"1s"} {"level":"info","ts":"2026-02-10T18:50:31.784417Z","caller":"traceutil/trace.go:171","msg":"trace[1340488155] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:584; }","duration":"1.230909924s","start":"2026-02-10T18:50:30.553488Z","end":"2026-02-10T18:50:31.784398Z","steps":["trace[1340488155] 'read index received' (duration: 1.230696979s)","trace[1340488155] 'applied index is now lower than readState.Index' (duration: 212.305µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:50:31.784476Z","caller":"traceutil/trace.go:171","msg":"trace[2039467073] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"1.67300089s","start":"2026-02-10T18:50:30.111469Z","end":"2026-02-10T18:50:31.78447Z","steps":["trace[2039467073] 'process raft request' (duration: 1.672788516s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:31.784562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:50:30.111446Z","time spent":"1.673057831s","remote":"127.0.0.1:33928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:50:31.784664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.23117578s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:50:31.784685Z","caller":"traceutil/trace.go:171","msg":"trace[1549161395] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:564; }","duration":"1.231229431s","start":"2026-02-10T18:50:30.553449Z","end":"2026-02-10T18:50:31.784678Z","steps":["trace[1549161395] 'agreement among raft nodes before linearized reading' (duration: 1.23119712s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:31.784703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:50:30.553434Z","time spent":"1.231265422s","remote":"127.0.0.1:33710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T18:50:31.784867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.28509ms","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-10T18:50:31.784885Z","caller":"traceutil/trace.go:171","msg":"trace[962917891] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:564; }","duration":"807.330301ms","start":"2026-02-10T18:50:30.977549Z","end":"2026-02-10T18:50:31.784879Z","steps":["trace[962917891] 'agreement among raft nodes before linearized reading' (duration: 807.29404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:31.784901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:50:30.977535Z","time spent":"807.362752ms","remote":"127.0.0.1:33996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-10T18:50:31.785024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.193377ms","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-10T18:50:31.785043Z","caller":"traceutil/trace.go:171","msg":"trace[1580063069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:564; }","duration":"697.221947ms","start":"2026-02-10T18:50:31.087815Z","end":"2026-02-10T18:50:31.785036Z","steps":["trace[1580063069] 'agreement among raft nodes before linearized reading' (duration: 697.187646ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:31.785063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:50:31.08781Z","time spent":"697.249298ms","remote":"127.0.0.1:33996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-10T18:50:31.785093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.256266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-10T18:50:31.785166Z","caller":"traceutil/trace.go:171","msg":"trace[1762288872] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:564; }","duration":"752.378719ms","start":"2026-02-10T18:50:31.032769Z","end":"2026-02-10T18:50:31.785147Z","steps":["trace[1762288872] 'agreement among raft nodes before linearized reading' (duration: 752.243906ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:31.785208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:50:31.032747Z","time spent":"752.449841ms","remote":"127.0.0.1:33996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-10T18:50:31.785339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.622977ms","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-10T18:50:31.785375Z","caller":"traceutil/trace.go:171","msg":"trace[15336308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:564; }","duration":"697.680168ms","start":"2026-02-10T18:50:31.087683Z","end":"2026-02-10T18:50:31.785363Z","steps":["trace[15336308] 'agreement among raft nodes before linearized reading' (duration: 697.628876ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:31.785409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:50:31.087671Z","time spent":"697.729748ms","remote":"127.0.0.1:33996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-10T18:50:33.327142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:50:32.220489Z","time spent":"1.106647657s","remote":"127.0.0.1:39224","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-10T18:50:33.328821Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.497708408s","expected-duration":"1s"} {"level":"info","ts":"2026-02-10T18:50:33.329254Z","caller":"traceutil/trace.go:171","msg":"trace[1897500929] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:586; }","duration":"1.531634178s","start":"2026-02-10T18:50:31.797595Z","end":"2026-02-10T18:50:33.329229Z","steps":["trace[1897500929] 'read index received' (duration: 34.187006ms)","trace[1897500929] 'applied index is now lower than readState.Index' (duration: 1.497446142s)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:50:33.32962Z","caller":"traceutil/trace.go:171","msg":"trace[1394658595] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"1.532060528s","start":"2026-02-10T18:50:31.797544Z","end":"2026-02-10T18:50:33.329604Z","steps":["trace[1394658595] 'process raft request' (duration: 1.531455474s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:33.329737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:50:31.797535Z","time spent":"1.532124529s","remote":"127.0.0.1:33996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:50:33.329932Z","caller":"traceutil/trace.go:171","msg":"trace[1127407887] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"1.530934832s","start":"2026-02-10T18:50:31.798985Z","end":"2026-02-10T18:50:33.32992Z","steps":["trace[1127407887] 'process raft request' (duration: 1.530157674s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:33.329988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:50:31.798979Z","time spent":"1.530979534s","remote":"127.0.0.1:33996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:50:33.330107Z","caller":"traceutil/trace.go:171","msg":"trace[379933184] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"1.530298198s","start":"2026-02-10T18:50:31.799798Z","end":"2026-02-10T18:50:33.330096Z","steps":["trace[379933184] 'process raft request' (duration: 1.529380447s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:33.330156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:50:31.799791Z","time spent":"1.530338528s","remote":"127.0.0.1:33996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:50:33.330292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.532692892s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:50:33.330335Z","caller":"traceutil/trace.go:171","msg":"trace[2015661088] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:568; }","duration":"1.532753333s","start":"2026-02-10T18:50:31.797575Z","end":"2026-02-10T18:50:33.330328Z","steps":["trace[2015661088] 'agreement among raft nodes before linearized reading' (duration: 1.532690461s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:33.330353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:50:31.797571Z","time spent":"1.532778803s","remote":"127.0.0.1:33710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-10T18:50:33.338208Z","caller":"traceutil/trace.go:171","msg":"trace[107497318] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"214.495804ms","start":"2026-02-10T18:50:33.123697Z","end":"2026-02-10T18:50:33.338193Z","steps":["trace[107497318] 'process raft request' (duration: 214.234688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:33.339189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.087166295s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.125\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-10T18:50:33.34019Z","caller":"traceutil/trace.go:171","msg":"trace[1561399185] range","detail":"{range_begin:/registry/masterleases/199.19.213.125; range_end:; response_count:1; response_revision:569; }","duration":"1.088214079s","start":"2026-02-10T18:50:32.251968Z","end":"2026-02-10T18:50:33.340182Z","steps":["trace[1561399185] 'agreement among raft nodes before linearized reading' (duration: 1.087163065s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:33.340233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:50:32.251953Z","time spent":"1.088271209s","remote":"127.0.0.1:33766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.125\" "} {"level":"info","ts":"2026-02-10T18:50:33.447273Z","caller":"traceutil/trace.go:171","msg":"trace[1323400363] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:590; }","duration":"101.081414ms","start":"2026-02-10T18:50:33.34617Z","end":"2026-02-10T18:50:33.447251Z","steps":["trace[1323400363] 'read index received' (duration: 101.006293ms)","trace[1323400363] 'applied index is now lower than readState.Index' (duration: 74.221µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:50:33.44735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.165746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:50:33.447368Z","caller":"traceutil/trace.go:171","msg":"trace[1460362236] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:569; }","duration":"101.212677ms","start":"2026-02-10T18:50:33.34615Z","end":"2026-02-10T18:50:33.447362Z","steps":["trace[1460362236] 'agreement among raft nodes before linearized reading' (duration: 101.163726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:35.054739Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2834343377366270673,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T18:50:35.555406Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2834343377366270673,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T18:50:35.607107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.771879694s","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-10T18:50:35.607163Z","caller":"traceutil/trace.go:171","msg":"trace[1569077497] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:570; }","duration":"1.771976007s","start":"2026-02-10T18:50:33.835174Z","end":"2026-02-10T18:50:35.60715Z","steps":["trace[1569077497] 'range keys from in-memory index tree' (duration: 1.771758442s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:35.607183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:50:33.835157Z","time spent":"1.772022297s","remote":"127.0.0.1:33996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-10T18:50:35.607378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.154483ms","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-10T18:50:35.607458Z","caller":"traceutil/trace.go:171","msg":"trace[720728849] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:570; }","duration":"852.243825ms","start":"2026-02-10T18:50:34.755202Z","end":"2026-02-10T18:50:35.607446Z","steps":["trace[720728849] 'range keys from in-memory index tree' (duration: 852.142723ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:35.607572Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.060297466s","expected-duration":"1s"} {"level":"info","ts":"2026-02-10T18:50:35.60773Z","caller":"traceutil/trace.go:171","msg":"trace[1095562118] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:592; }","duration":"1.05321942s","start":"2026-02-10T18:50:34.554505Z","end":"2026-02-10T18:50:35.607725Z","steps":["trace[1095562118] 'read index received' (duration: 1.053108878s)","trace[1095562118] 'applied index is now lower than readState.Index' (duration: 110.142µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:50:35.607813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.053294992s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:50:35.607842Z","caller":"traceutil/trace.go:171","msg":"trace[2073398926] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:571; }","duration":"1.053333103s","start":"2026-02-10T18:50:34.554501Z","end":"2026-02-10T18:50:35.607834Z","steps":["trace[2073398926] 'agreement among raft nodes before linearized reading' (duration: 1.053273431s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:35.607861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:50:34.55446Z","time spent":"1.053396914s","remote":"127.0.0.1:33710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T18:50:35.607892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.456997ms","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-10T18:50:35.60791Z","caller":"traceutil/trace.go:171","msg":"trace[1486447202] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:571; }","duration":"273.501277ms","start":"2026-02-10T18:50:35.334404Z","end":"2026-02-10T18:50:35.607905Z","steps":["trace[1486447202] 'agreement among raft nodes before linearized reading' (duration: 273.429486ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:50:35.607957Z","caller":"traceutil/trace.go:171","msg":"trace[167560420] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"1.060765306s","start":"2026-02-10T18:50:34.547184Z","end":"2026-02-10T18:50:35.607949Z","steps":["trace[167560420] 'process raft request' (duration: 1.06046726s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:35.608014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:50:34.547162Z","time spent":"1.060812228s","remote":"127.0.0.1:33996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:50:35.608027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.553527ms","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-10T18:50:35.608042Z","caller":"traceutil/trace.go:171","msg":"trace[598505942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:571; }","duration":"272.577207ms","start":"2026-02-10T18:50:35.33546Z","end":"2026-02-10T18:50:35.608037Z","steps":["trace[598505942] 'agreement among raft nodes before linearized reading' (duration: 272.538266ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:35.608099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.875304ms","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-10T18:50:35.60811Z","caller":"traceutil/trace.go:171","msg":"trace[532007861] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:571; }","duration":"272.895335ms","start":"2026-02-10T18:50:35.335211Z","end":"2026-02-10T18:50:35.608106Z","steps":["trace[532007861] 'agreement among raft nodes before linearized reading' (duration: 272.861304ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:50:35.719424Z","caller":"traceutil/trace.go:171","msg":"trace[634786384] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"106.380513ms","start":"2026-02-10T18:50:35.612978Z","end":"2026-02-10T18:50:35.719359Z","steps":["trace[634786384] 'process raft request' (duration: 96.380284ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:50:35.721412Z","caller":"traceutil/trace.go:171","msg":"trace[2134850800] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"105.083755ms","start":"2026-02-10T18:50:35.616319Z","end":"2026-02-10T18:50:35.721402Z","steps":["trace[2134850800] 'process raft request' (duration: 104.84599ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:50:35.722317Z","caller":"traceutil/trace.go:171","msg":"trace[2084715045] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"101.758472ms","start":"2026-02-10T18:50:35.619803Z","end":"2026-02-10T18:50:35.721562Z","steps":["trace[2084715045] 'process raft request' (duration: 101.416095ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:37.812445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.885779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:50:37.812505Z","caller":"traceutil/trace.go:171","msg":"trace[33496296] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:583; }","duration":"255.978452ms","start":"2026-02-10T18:50:37.556513Z","end":"2026-02-10T18:50:37.812491Z","steps":["trace[33496296] 'range keys from in-memory index tree' (duration: 255.827138ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:50:37.812694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.669157ms","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-10T18:50:37.812716Z","caller":"traceutil/trace.go:171","msg":"trace[1407790759] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:583; }","duration":"148.720098ms","start":"2026-02-10T18:50:37.663987Z","end":"2026-02-10T18:50:37.812708Z","steps":["trace[1407790759] 'range keys from in-memory index tree' (duration: 148.544054ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:50:38.572254Z","caller":"traceutil/trace.go:171","msg":"trace[1797501787] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"171.313425ms","start":"2026-02-10T18:50:38.400922Z","end":"2026-02-10T18:50:38.572236Z","steps":["trace[1797501787] 'process raft request' (duration: 80.707277ms)","trace[1797501787] 'compare' (duration: 90.193019ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:50:40.735832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.300147ms","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-02-10T18:50:40.736116Z","caller":"traceutil/trace.go:171","msg":"trace[96918044] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:645; }","duration":"258.619113ms","start":"2026-02-10T18:50:40.477476Z","end":"2026-02-10T18:50:40.736095Z","steps":["trace[96918044] 'range keys from in-memory index tree' (duration: 257.828857ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:50:47.368732Z","caller":"traceutil/trace.go:171","msg":"trace[570404079] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"118.196286ms","start":"2026-02-10T18:50:47.250522Z","end":"2026-02-10T18:50:47.368718Z","steps":["trace[570404079] 'process raft request' (duration: 118.087934ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:50:48.632635Z","caller":"traceutil/trace.go:171","msg":"trace[1230450950] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"208.986523ms","start":"2026-02-10T18:50:48.423631Z","end":"2026-02-10T18:50:48.632618Z","steps":["trace[1230450950] 'process raft request' (duration: 208.8316ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:50:48.642211Z","caller":"traceutil/trace.go:171","msg":"trace[1131903218] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"214.503982ms","start":"2026-02-10T18:50:48.427693Z","end":"2026-02-10T18:50:48.642197Z","steps":["trace[1131903218] 'process raft request' (duration: 214.211356ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:52:02.809419Z","caller":"traceutil/trace.go:171","msg":"trace[728668114] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"308.972076ms","start":"2026-02-10T18:52:02.500426Z","end":"2026-02-10T18:52:02.809398Z","steps":["trace[728668114] 'process raft request' (duration: 308.795414ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:02.809537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:02.500412Z","time spent":"309.070127ms","remote":"127.0.0.1:33996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:52:03.186831Z","caller":"traceutil/trace.go:171","msg":"trace[1828631471] linearizableReadLoop","detail":"{readStateIndex:1046; appliedIndex:1045; }","duration":"632.539596ms","start":"2026-02-10T18:52:02.55427Z","end":"2026-02-10T18:52:03.186809Z","steps":["trace[1828631471] 'read index received' (duration: 255.487387ms)","trace[1828631471] 'applied index is now lower than readState.Index' (duration: 377.051279ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:52:03.186956Z","caller":"traceutil/trace.go:171","msg":"trace[215308889] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"685.968124ms","start":"2026-02-10T18:52:02.500977Z","end":"2026-02-10T18:52:03.186945Z","steps":["trace[215308889] 'process raft request' (duration: 592.083844ms)","trace[215308889] 'compare' (duration: 93.645977ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:52:03.187118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:02.500969Z","time spent":"686.012815ms","remote":"127.0.0.1:33996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:52:03.187269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.815152ms","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-10T18:52:03.187332Z","caller":"traceutil/trace.go:171","msg":"trace[1735351416] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1002; }","duration":"513.913304ms","start":"2026-02-10T18:52:02.673402Z","end":"2026-02-10T18:52:03.187315Z","steps":["trace[1735351416] 'agreement among raft nodes before linearized reading' (duration: 513.729192ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:03.18737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:02.673387Z","time spent":"513.974235ms","remote":"127.0.0.1:33996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-10T18:52:03.187466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.377289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:52:03.187503Z","caller":"traceutil/trace.go:171","msg":"trace[1070398657] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1002; }","duration":"129.446109ms","start":"2026-02-10T18:52:03.058046Z","end":"2026-02-10T18:52:03.187493Z","steps":["trace[1070398657] 'agreement among raft nodes before linearized reading' (duration: 129.386789ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:03.187595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.270403ms","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-10T18:52:03.187629Z","caller":"traceutil/trace.go:171","msg":"trace[706456978] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1002; }","duration":"248.332673ms","start":"2026-02-10T18:52:02.939284Z","end":"2026-02-10T18:52:03.187617Z","steps":["trace[706456978] 'agreement among raft nodes before linearized reading' (duration: 248.244102ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:03.187644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.373787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:52:03.187675Z","caller":"traceutil/trace.go:171","msg":"trace[1104928495] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1002; }","duration":"633.435959ms","start":"2026-02-10T18:52:02.554228Z","end":"2026-02-10T18:52:03.187664Z","steps":["trace[1104928495] 'agreement among raft nodes before linearized reading' (duration: 633.396918ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:52:03.187701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:52:02.55421Z","time spent":"633.485629ms","remote":"127.0.0.1:33710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T18:52:03.187879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.812563ms","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-10T18:52:03.187911Z","caller":"traceutil/trace.go:171","msg":"trace[2090247669] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1002; }","duration":"286.868734ms","start":"2026-02-10T18:52:02.901033Z","end":"2026-02-10T18:52:03.187902Z","steps":["trace[2090247669] 'agreement among raft nodes before linearized reading' (duration: 286.783303ms)"],"step_count":1}