{"level":"warn","ts":"2026-04-20T14:07:27.431482Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T14:07:27.431642Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.209: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.209:2380","--initial-cluster=instance=https://199.204.45.209:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.209:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.209: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-04-20T14:07:27.431724Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T14:07:27.431755Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.209:2380"]} {"level":"info","ts":"2026-04-20T14:07:27.431782Z","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-04-20T14:07:27.432421Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.209:2379"]} {"level":"info","ts":"2026-04-20T14:07:27.432517Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.209:2380"],"listen-peer-urls":["https://199.204.45.209:2380"],"advertise-client-urls":["https://199.204.45.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.209: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-04-20T14:07:27.436826Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.077841ms"} {"level":"info","ts":"2026-04-20T14:07:27.442283Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"adfb641115bf6cbd","cluster-id":"3da6b677d4b80c6"} {"level":"info","ts":"2026-04-20T14:07:27.442394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T14:07:27.442435Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became follower at term 0"} {"level":"info","ts":"2026-04-20T14:07:27.442468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft adfb641115bf6cbd [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T14:07:27.4425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became follower at term 1"} {"level":"info","ts":"2026-04-20T14:07:27.44258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd switched to configuration voters=(12536724012257995965)"} {"level":"warn","ts":"2026-04-20T14:07:27.448816Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T14:07:27.449971Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T14:07:27.4509Z","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-04-20T14:07:27.452969Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"adfb641115bf6cbd","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T14:07:27.454733Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"adfb641115bf6cbd","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T14:07:27.461254Z","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-04-20T14:07:27.461516Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"adfb641115bf6cbd","initial-advertise-peer-urls":["https://199.204.45.209:2380"],"listen-peer-urls":["https://199.204.45.209:2380"],"advertise-client-urls":["https://199.204.45.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T14:07:27.461578Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T14:07:27.461754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd switched to configuration voters=(12536724012257995965)"} {"level":"info","ts":"2026-04-20T14:07:27.461893Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.209:2380"} {"level":"info","ts":"2026-04-20T14:07:27.461941Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.209:2380"} {"level":"info","ts":"2026-04-20T14:07:27.46193Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3da6b677d4b80c6","local-member-id":"adfb641115bf6cbd","added-peer-id":"adfb641115bf6cbd","added-peer-peer-urls":["https://199.204.45.209:2380"]} {"level":"info","ts":"2026-04-20T14:07:27.454961Z","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-04-20T14:07:27.462136Z","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-04-20T14:07:27.462151Z","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-04-20T14:07:28.342835Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T14:07:28.342954Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T14:07:28.343003Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd received MsgPreVoteResp from adfb641115bf6cbd at term 1"} {"level":"info","ts":"2026-04-20T14:07:28.343026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became candidate at term 2"} {"level":"info","ts":"2026-04-20T14:07:28.34305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd received MsgVoteResp from adfb641115bf6cbd at term 2"} {"level":"info","ts":"2026-04-20T14:07:28.343073Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became leader at term 2"} {"level":"info","ts":"2026-04-20T14:07:28.343092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: adfb641115bf6cbd elected leader adfb641115bf6cbd at term 2"} {"level":"info","ts":"2026-04-20T14:07:28.345775Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T14:07:28.34579Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"adfb641115bf6cbd","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.209:2379]}","request-path":"/0/members/adfb641115bf6cbd/attributes","cluster-id":"3da6b677d4b80c6","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T14:07:28.345804Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T14:07:28.345963Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T14:07:28.346614Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3da6b677d4b80c6","local-member-id":"adfb641115bf6cbd","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T14:07:28.346695Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T14:07:28.346718Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T14:07:28.348289Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.209:2379"} {"level":"info","ts":"2026-04-20T14:07:28.348633Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T14:07:28.355381Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T14:07:28.355557Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T14:07:33.943387Z","caller":"traceutil/trace.go:171","msg":"trace[1103606839] transaction","detail":"{read_only:false; response_revision:262; number_of_response:1; }","duration":"387.220934ms","start":"2026-04-20T14:07:33.556127Z","end":"2026-04-20T14:07:33.943348Z","steps":["trace[1103606839] 'process raft request' (duration: 336.753836ms)","trace[1103606839] 'compare' (duration: 49.184277ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:07:33.94395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:07:33.556114Z","time spent":"387.377381ms","remote":"127.0.0.1:57426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":219,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T14:07:34.485456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.405095ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:07:34.485728Z","caller":"traceutil/trace.go:171","msg":"trace[1204829735] linearizableReadLoop","detail":"{readStateIndex:271; appliedIndex:269; }","duration":"910.954345ms","start":"2026-04-20T14:07:33.574748Z","end":"2026-04-20T14:07:34.485703Z","steps":["trace[1204829735] 'read index received' (duration: 318.130935ms)","trace[1204829735] 'applied index is now lower than readState.Index' (duration: 592.82168ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:07:34.485862Z","caller":"traceutil/trace.go:171","msg":"trace[2093216242] transaction","detail":"{read_only:false; response_revision:263; number_of_response:1; }","duration":"920.708897ms","start":"2026-04-20T14:07:33.565139Z","end":"2026-04-20T14:07:34.485848Z","steps":["trace[2093216242] 'process raft request' (duration: 547.548107ms)","trace[2093216242] 'compare' (duration: 372.104071ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:07:34.485964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:07:33.565122Z","time spent":"920.810562ms","remote":"127.0.0.1:57572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":435,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T14:07:34.486214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.440849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:07:34.486315Z","caller":"traceutil/trace.go:171","msg":"trace[751372816] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:263; }","duration":"911.584545ms","start":"2026-04-20T14:07:33.574702Z","end":"2026-04-20T14:07:34.486287Z","steps":["trace[751372816] 'agreement among raft nodes before linearized reading' (duration: 911.318332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:07:34.486357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:07:33.574686Z","time spent":"911.665059ms","remote":"127.0.0.1:57178","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-04-20T14:07:34.851448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.378825ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-04-20T14:07:34.851549Z","caller":"traceutil/trace.go:171","msg":"trace[445989467] transaction","detail":"{read_only:false; number_of_response:0; response_revision:263; }","duration":"699.515124ms","start":"2026-04-20T14:07:34.152021Z","end":"2026-04-20T14:07:34.851536Z","steps":["trace[445989467] 'process raft request' (duration: 441.805823ms)","trace[445989467] 'compare' (duration: 257.324233ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:07:34.85159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:07:34.151985Z","time spent":"699.589447ms","remote":"127.0.0.1:57404","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T14:07:34.875696Z","caller":"traceutil/trace.go:171","msg":"trace[1025243103] linearizableReadLoop","detail":"{readStateIndex:274; appliedIndex:271; }","duration":"389.874733ms","start":"2026-04-20T14:07:34.4858Z","end":"2026-04-20T14:07:34.875675Z","steps":["trace[1025243103] 'read index received' (duration: 108.016558ms)","trace[1025243103] 'applied index is now lower than readState.Index' (duration: 281.855485ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:07:34.875794Z","caller":"traceutil/trace.go:171","msg":"trace[1827337725] transaction","detail":"{read_only:false; number_of_response:0; response_revision:263; }","duration":"722.936574ms","start":"2026-04-20T14:07:34.152851Z","end":"2026-04-20T14:07:34.875787Z","steps":["trace[1827337725] 'process raft request' (duration: 722.737715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:07:34.875857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:07:34.152827Z","time spent":"722.997197ms","remote":"127.0.0.1:57404","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T14:07:34.876037Z","caller":"traceutil/trace.go:171","msg":"trace[1894889212] transaction","detail":"{read_only:false; number_of_response:0; response_revision:263; }","duration":"723.111723ms","start":"2026-04-20T14:07:34.152917Z","end":"2026-04-20T14:07:34.876028Z","steps":["trace[1894889212] 'process raft request' (duration: 722.731805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:07:34.876091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:07:34.152894Z","time spent":"723.173165ms","remote":"127.0.0.1:57404","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T14:07:34.87625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.832649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-20T14:07:34.876287Z","caller":"traceutil/trace.go:171","msg":"trace[352082168] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:263; }","duration":"611.946084ms","start":"2026-04-20T14:07:34.26433Z","end":"2026-04-20T14:07:34.876276Z","steps":["trace[352082168] 'agreement among raft nodes before linearized reading' (duration: 611.874581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:07:34.876316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:07:34.264298Z","time spent":"612.011468ms","remote":"127.0.0.1:57492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T14:07:34.876489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.428109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:07:34.87653Z","caller":"traceutil/trace.go:171","msg":"trace[132285430] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:263; }","duration":"726.526154ms","start":"2026-04-20T14:07:34.149993Z","end":"2026-04-20T14:07:34.876519Z","steps":["trace[132285430] 'agreement among raft nodes before linearized reading' (duration: 726.466301ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:07:34.87656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:07:34.149962Z","time spent":"726.591946ms","remote":"127.0.0.1:57492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T14:07:34.876713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.033498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3980"} {"level":"info","ts":"2026-04-20T14:07:34.876747Z","caller":"traceutil/trace.go:171","msg":"trace[275795289] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:263; }","duration":"727.106931ms","start":"2026-04-20T14:07:34.149632Z","end":"2026-04-20T14:07:34.876739Z","steps":["trace[275795289] 'agreement among raft nodes before linearized reading' (duration: 727.047808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:07:34.876773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:07:34.149609Z","time spent":"727.159023ms","remote":"127.0.0.1:57404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":4003,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-04-20T14:07:34.876946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"929.162541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pv-protection-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:07:34.876991Z","caller":"traceutil/trace.go:171","msg":"trace[749880112] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pv-protection-controller; range_end:; response_count:0; response_revision:263; }","duration":"929.215484ms","start":"2026-04-20T14:07:33.947762Z","end":"2026-04-20T14:07:34.876977Z","steps":["trace[749880112] 'agreement among raft nodes before linearized reading' (duration: 929.131739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:07:34.877021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:07:33.947751Z","time spent":"929.262786ms","remote":"127.0.0.1:57426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/pv-protection-controller\" "} {"level":"warn","ts":"2026-04-20T14:07:34.877177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"934.379998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T14:07:34.87721Z","caller":"traceutil/trace.go:171","msg":"trace[1667723670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:263; }","duration":"934.437911ms","start":"2026-04-20T14:07:33.942764Z","end":"2026-04-20T14:07:34.877202Z","steps":["trace[1667723670] 'agreement among raft nodes before linearized reading' (duration: 934.381898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:07:34.877237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:07:33.942749Z","time spent":"934.481182ms","remote":"127.0.0.1:57492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-20T14:07:34.877382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.514096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:07:34.877414Z","caller":"traceutil/trace.go:171","msg":"trace[1244145972] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:263; }","duration":"389.644913ms","start":"2026-04-20T14:07:34.487762Z","end":"2026-04-20T14:07:34.877406Z","steps":["trace[1244145972] 'agreement among raft nodes before linearized reading' (duration: 389.59772ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:07:34.877446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:07:34.487668Z","time spent":"389.772868ms","remote":"127.0.0.1:57164","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-04-20T14:07:53.166982Z","caller":"traceutil/trace.go:171","msg":"trace[884201303] linearizableReadLoop","detail":"{readStateIndex:471; appliedIndex:469; }","duration":"184.727635ms","start":"2026-04-20T14:07:52.982235Z","end":"2026-04-20T14:07:53.166962Z","steps":["trace[884201303] 'read index received' (duration: 58.541414ms)","trace[884201303] 'applied index is now lower than readState.Index' (duration: 126.185491ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:07:53.167123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.848151ms","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-04-20T14:07:53.167147Z","caller":"traceutil/trace.go:171","msg":"trace[1498897067] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:456; }","duration":"184.934665ms","start":"2026-04-20T14:07:52.982204Z","end":"2026-04-20T14:07:53.167139Z","steps":["trace[1498897067] 'agreement among raft nodes before linearized reading' (duration: 184.83616ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:07:53.167259Z","caller":"traceutil/trace.go:171","msg":"trace[1640994404] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"190.862313ms","start":"2026-04-20T14:07:52.97638Z","end":"2026-04-20T14:07:53.167243Z","steps":["trace[1640994404] 'process raft request' (duration: 190.320027ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:07:59.625692Z","caller":"traceutil/trace.go:171","msg":"trace[254880855] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"321.922371ms","start":"2026-04-20T14:07:59.303706Z","end":"2026-04-20T14:07:59.625628Z","steps":["trace[254880855] 'process raft request' (duration: 319.800613ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:07:59.626495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:07:59.303694Z","time spent":"322.385799ms","remote":"127.0.0.1:57404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:07:59.67313Z","caller":"traceutil/trace.go:171","msg":"trace[778269061] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:584; }","duration":"199.690973ms","start":"2026-04-20T14:07:59.473412Z","end":"2026-04-20T14:07:59.673103Z","steps":["trace[778269061] 'read index received' (duration: 150.175664ms)","trace[778269061] 'applied index is now lower than readState.Index' (duration: 49.513889ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:07:59.673363Z","caller":"traceutil/trace.go:171","msg":"trace[809247554] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"297.369946ms","start":"2026-04-20T14:07:59.375964Z","end":"2026-04-20T14:07:59.673334Z","steps":["trace[809247554] 'process raft request' (duration: 296.600101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:07:59.673455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.885742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-20T14:07:59.673493Z","caller":"traceutil/trace.go:171","msg":"trace[719455860] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:564; }","duration":"200.112191ms","start":"2026-04-20T14:07:59.473369Z","end":"2026-04-20T14:07:59.673482Z","steps":["trace[719455860] 'agreement among raft nodes before linearized reading' (duration: 199.828223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:08:00.601625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.599014ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:08:00.601796Z","caller":"traceutil/trace.go:171","msg":"trace[480828246] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"922.572852ms","start":"2026-04-20T14:07:59.679201Z","end":"2026-04-20T14:08:00.601774Z","steps":["trace[480828246] 'process raft request' (duration: 584.703209ms)","trace[480828246] 'compare' (duration: 337.411125ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:08:00.601982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:07:59.679177Z","time spent":"922.728651ms","remote":"127.0.0.1:57492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:08:00.627964Z","caller":"traceutil/trace.go:171","msg":"trace[1368069862] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"877.237163ms","start":"2026-04-20T14:07:59.750697Z","end":"2026-04-20T14:08:00.627934Z","steps":["trace[1368069862] 'process raft request' (duration: 876.900165ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:08:00.628069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:07:59.750676Z","time spent":"877.349591ms","remote":"127.0.0.1:33662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T14:08:16.613602Z","caller":"traceutil/trace.go:171","msg":"trace[834635687] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"278.225021ms","start":"2026-04-20T14:08:16.335352Z","end":"2026-04-20T14:08:16.613577Z","steps":["trace[834635687] 'process raft request' (duration: 278.0282ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:08:20.790509Z","caller":"traceutil/trace.go:171","msg":"trace[1311416993] linearizableReadLoop","detail":"{readStateIndex:778; appliedIndex:777; }","duration":"215.165854ms","start":"2026-04-20T14:08:20.575308Z","end":"2026-04-20T14:08:20.790474Z","steps":["trace[1311416993] 'read index received' (duration: 213.079846ms)","trace[1311416993] 'applied index is now lower than readState.Index' (duration: 2.084808ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:08:20.790606Z","caller":"traceutil/trace.go:171","msg":"trace[1386056428] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"385.491882ms","start":"2026-04-20T14:08:20.405037Z","end":"2026-04-20T14:08:20.790529Z","steps":["trace[1386056428] 'process raft request' (duration: 383.347214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:08:20.790788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.449016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T14:08:20.790844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:08:20.405006Z","time spent":"385.683755ms","remote":"127.0.0.1:57426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T14:08:20.790892Z","caller":"traceutil/trace.go:171","msg":"trace[824480053] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:751; }","duration":"215.612937ms","start":"2026-04-20T14:08:20.575259Z","end":"2026-04-20T14:08:20.790872Z","steps":["trace[824480053] 'agreement among raft nodes before linearized reading' (duration: 215.379545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:08:22.034472Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.230876278s","expected-duration":"1s"} {"level":"info","ts":"2026-04-20T14:08:22.035517Z","caller":"traceutil/trace.go:171","msg":"trace[532194562] linearizableReadLoop","detail":"{readStateIndex:780; appliedIndex:778; }","duration":"1.239131889s","start":"2026-04-20T14:08:20.796354Z","end":"2026-04-20T14:08:22.035486Z","steps":["trace[532194562] 'read index received' (duration: 7.275391ms)","trace[532194562] 'applied index is now lower than readState.Index' (duration: 1.231855458s)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:08:22.035532Z","caller":"traceutil/trace.go:171","msg":"trace[1788866236] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"1.239482332s","start":"2026-04-20T14:08:20.796016Z","end":"2026-04-20T14:08:22.035499Z","steps":["trace[1788866236] 'process raft request' (duration: 1.238889036s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:08:22.035651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.2392788s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:08:22.035681Z","caller":"traceutil/trace.go:171","msg":"trace[1789574555] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:753; }","duration":"1.239357672s","start":"2026-04-20T14:08:20.796315Z","end":"2026-04-20T14:08:22.035672Z","steps":["trace[1789574555] 'agreement among raft nodes before linearized reading' (duration: 1.239262561s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:08:22.035714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:08:20.796288Z","time spent":"1.239417872s","remote":"127.0.0.1:57164","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-04-20T14:08:22.035721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:08:20.796004Z","time spent":"1.239643594s","remote":"127.0.0.1:57304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1535,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T14:08:22.037107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.545572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-20T14:08:22.037197Z","caller":"traceutil/trace.go:171","msg":"trace[682244556] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:754; }","duration":"855.711354ms","start":"2026-04-20T14:08:21.18146Z","end":"2026-04-20T14:08:22.037171Z","steps":["trace[682244556] 'agreement among raft nodes before linearized reading' (duration: 855.438282ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:08:22.037244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.300348ms","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-04-20T14:08:22.037307Z","caller":"traceutil/trace.go:171","msg":"trace[1532701808] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:754; }","duration":"606.388658ms","start":"2026-04-20T14:08:21.430898Z","end":"2026-04-20T14:08:22.037287Z","steps":["trace[1532701808] 'agreement among raft nodes before linearized reading' (duration: 606.180466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:08:22.037339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:08:21.430882Z","time spent":"606.451359ms","remote":"127.0.0.1:57492","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-04-20T14:08:22.037359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.12493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T14:08:22.037377Z","caller":"traceutil/trace.go:171","msg":"trace[4239066] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"964.159539ms","start":"2026-04-20T14:08:21.073196Z","end":"2026-04-20T14:08:22.037356Z","steps":["trace[4239066] 'process raft request' (duration: 963.551484ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:08:22.037264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:08:21.181437Z","time spent":"855.809565ms","remote":"127.0.0.1:57492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-20T14:08:22.037399Z","caller":"traceutil/trace.go:171","msg":"trace[1544690401] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:754; }","duration":"630.21922ms","start":"2026-04-20T14:08:21.407172Z","end":"2026-04-20T14:08:22.037391Z","steps":["trace[1544690401] 'agreement among raft nodes before linearized reading' (duration: 630.113889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:08:22.037432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.765239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.209\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-20T14:08:22.037444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:08:21.407143Z","time spent":"630.286972ms","remote":"127.0.0.1:57492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-20T14:08:22.037452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:08:21.073157Z","time spent":"964.246679ms","remote":"127.0.0.1:57492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:08:22.037467Z","caller":"traceutil/trace.go:171","msg":"trace[937841931] range","detail":"{range_begin:/registry/masterleases/199.204.45.209; range_end:; response_count:1; response_revision:754; }","duration":"627.811259ms","start":"2026-04-20T14:08:21.409645Z","end":"2026-04-20T14:08:22.037456Z","steps":["trace[937841931] 'agreement among raft nodes before linearized reading' (duration: 627.612487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:08:22.037491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:08:21.409614Z","time spent":"627.8713ms","remote":"127.0.0.1:57206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.209\" "} {"level":"warn","ts":"2026-04-20T14:08:22.213029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.589929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-k8l9m\" ","response":"range_response_count:1 size:4495"} {"level":"info","ts":"2026-04-20T14:08:22.213972Z","caller":"traceutil/trace.go:171","msg":"trace[1225667082] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-k8l9m; range_end:; response_count:1; response_revision:778; }","duration":"101.569627ms","start":"2026-04-20T14:08:22.112378Z","end":"2026-04-20T14:08:22.213948Z","steps":["trace[1225667082] 'agreement among raft nodes before linearized reading' (duration: 100.469817ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:08:22.431827Z","caller":"traceutil/trace.go:171","msg":"trace[867825482] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"206.315356ms","start":"2026-04-20T14:08:22.225481Z","end":"2026-04-20T14:08:22.431797Z","steps":["trace[867825482] 'process raft request' (duration: 205.70452ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:08:22.436492Z","caller":"traceutil/trace.go:171","msg":"trace[891299035] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"208.801159ms","start":"2026-04-20T14:08:22.227669Z","end":"2026-04-20T14:08:22.43647Z","steps":["trace[891299035] 'process raft request' (duration: 208.285695ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:08:25.861638Z","caller":"traceutil/trace.go:171","msg":"trace[1060116947] linearizableReadLoop","detail":"{readStateIndex:831; appliedIndex:830; }","duration":"285.252489ms","start":"2026-04-20T14:08:25.576361Z","end":"2026-04-20T14:08:25.861613Z","steps":["trace[1060116947] 'read index received' (duration: 284.605762ms)","trace[1060116947] 'applied index is now lower than readState.Index' (duration: 645.727µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:08:25.861784Z","caller":"traceutil/trace.go:171","msg":"trace[1618041579] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"998.054555ms","start":"2026-04-20T14:08:24.863694Z","end":"2026-04-20T14:08:25.861748Z","steps":["trace[1618041579] 'process raft request' (duration: 997.241856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:08:25.86237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:08:24.863688Z","time spent":"998.558251ms","remote":"127.0.0.1:57492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:08:25.862175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.787846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:08:25.862661Z","caller":"traceutil/trace.go:171","msg":"trace[458430301] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:803; }","duration":"286.215191ms","start":"2026-04-20T14:08:25.576311Z","end":"2026-04-20T14:08:25.862526Z","steps":["trace[458430301] 'agreement among raft nodes before linearized reading' (duration: 285.389091ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:08:26.567606Z","caller":"traceutil/trace.go:171","msg":"trace[1146092909] linearizableReadLoop","detail":"{readStateIndex:832; appliedIndex:831; }","duration":"494.502246ms","start":"2026-04-20T14:08:26.073074Z","end":"2026-04-20T14:08:26.567577Z","steps":["trace[1146092909] 'read index received' (duration: 494.404795ms)","trace[1146092909] 'applied index is now lower than readState.Index' (duration: 96.221µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:08:26.567711Z","caller":"traceutil/trace.go:171","msg":"trace[1068310894] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"600.960423ms","start":"2026-04-20T14:08:25.966743Z","end":"2026-04-20T14:08:26.567703Z","steps":["trace[1068310894] 'process raft request' (duration: 600.69891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:08:26.567799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:08:25.966686Z","time spent":"601.040204ms","remote":"127.0.0.1:57402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5170,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:08:26.568159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.085183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-20T14:08:26.568188Z","caller":"traceutil/trace.go:171","msg":"trace[1409139729] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:804; }","duration":"495.134243ms","start":"2026-04-20T14:08:26.073046Z","end":"2026-04-20T14:08:26.56818Z","steps":["trace[1409139729] 'agreement among raft nodes before linearized reading' (duration: 495.041232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:08:26.568222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:08:26.073031Z","time spent":"495.179814ms","remote":"127.0.0.1:57492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T14:08:26.568444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.482996ms","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-04-20T14:08:26.568637Z","caller":"traceutil/trace.go:171","msg":"trace[1287796353] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:803; }","duration":"541.962873ms","start":"2026-04-20T14:08:26.02665Z","end":"2026-04-20T14:08:26.568612Z","steps":["trace[1287796353] 'range keys from in-memory index tree' (duration: 540.467356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:08:26.574948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.241183ms","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-04-20T14:08:26.575015Z","caller":"traceutil/trace.go:171","msg":"trace[443240284] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:805; }","duration":"500.336154ms","start":"2026-04-20T14:08:26.074664Z","end":"2026-04-20T14:08:26.575Z","steps":["trace[443240284] 'agreement among raft nodes before linearized reading' (duration: 500.146541ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:08:26.575047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:08:26.074653Z","time spent":"500.387984ms","remote":"127.0.0.1:57492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T14:08:26.575334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.169777ms","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-04-20T14:08:26.575363Z","caller":"traceutil/trace.go:171","msg":"trace[1307951834] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:805; }","duration":"333.242529ms","start":"2026-04-20T14:08:26.242114Z","end":"2026-04-20T14:08:26.575356Z","steps":["trace[1307951834] 'agreement among raft nodes before linearized reading' (duration: 333.162738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:08:26.575386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:08:26.242091Z","time spent":"333.288729ms","remote":"127.0.0.1:57492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T14:08:26.575524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.895207ms","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-04-20T14:08:26.575551Z","caller":"traceutil/trace.go:171","msg":"trace[1196523536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:805; }","duration":"499.943258ms","start":"2026-04-20T14:08:26.075601Z","end":"2026-04-20T14:08:26.575544Z","steps":["trace[1196523536] 'agreement among raft nodes before linearized reading' (duration: 499.876437ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:08:26.575575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:08:26.075593Z","time spent":"499.977159ms","remote":"127.0.0.1:57492","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\" "}