{"level":"warn","ts":"2026-02-05T17:38:45.349536Z","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-05T17:38:45.349662Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.131: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.131:2380","--initial-cluster=instance=https://199.204.45.131:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.131:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.131: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-05T17:38:45.349895Z","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-05T17:38:45.349911Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.131:2380"]} {"level":"info","ts":"2026-02-05T17:38:45.349947Z","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-05T17:38:45.351679Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.131:2379"]} {"level":"info","ts":"2026-02-05T17:38:45.35177Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.131:2380"],"listen-peer-urls":["https://199.204.45.131:2380"],"advertise-client-urls":["https://199.204.45.131:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.131:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.131: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-05T17:38:45.355916Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.977667ms"} {"level":"info","ts":"2026-02-05T17:38:45.3899Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2fdf33955ed9ec6c","cluster-id":"e3dd6b4ed8cb907a"} {"level":"info","ts":"2026-02-05T17:38:45.389959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2fdf33955ed9ec6c switched to configuration voters=()"} {"level":"info","ts":"2026-02-05T17:38:45.390022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2fdf33955ed9ec6c became follower at term 0"} {"level":"info","ts":"2026-02-05T17:38:45.390038Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2fdf33955ed9ec6c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-05T17:38:45.390045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2fdf33955ed9ec6c became follower at term 1"} {"level":"info","ts":"2026-02-05T17:38:45.390086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2fdf33955ed9ec6c switched to configuration voters=(3449532556223573100)"} {"level":"warn","ts":"2026-02-05T17:38:45.397036Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-05T17:38:45.399574Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-05T17:38:45.401708Z","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-05T17:38:45.41039Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2fdf33955ed9ec6c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-05T17:38:45.426837Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2fdf33955ed9ec6c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-05T17:38:45.426994Z","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-05T17:38:45.42723Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2fdf33955ed9ec6c","initial-advertise-peer-urls":["https://199.204.45.131:2380"],"listen-peer-urls":["https://199.204.45.131:2380"],"advertise-client-urls":["https://199.204.45.131:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.131:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-05T17:38:45.427257Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-05T17:38:45.428761Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.131:2380"} {"level":"info","ts":"2026-02-05T17:38:45.428777Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.131:2380"} {"level":"info","ts":"2026-02-05T17:38:45.431297Z","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-05T17:38:45.431333Z","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-05T17:38:45.43134Z","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-05T17:38:45.43251Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2fdf33955ed9ec6c switched to configuration voters=(3449532556223573100)"} {"level":"info","ts":"2026-02-05T17:38:45.434462Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e3dd6b4ed8cb907a","local-member-id":"2fdf33955ed9ec6c","added-peer-id":"2fdf33955ed9ec6c","added-peer-peer-urls":["https://199.204.45.131:2380"]} {"level":"info","ts":"2026-02-05T17:38:46.190657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2fdf33955ed9ec6c is starting a new election at term 1"} {"level":"info","ts":"2026-02-05T17:38:46.190694Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2fdf33955ed9ec6c became pre-candidate at term 1"} {"level":"info","ts":"2026-02-05T17:38:46.190715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2fdf33955ed9ec6c received MsgPreVoteResp from 2fdf33955ed9ec6c at term 1"} {"level":"info","ts":"2026-02-05T17:38:46.190726Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2fdf33955ed9ec6c became candidate at term 2"} {"level":"info","ts":"2026-02-05T17:38:46.190741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2fdf33955ed9ec6c received MsgVoteResp from 2fdf33955ed9ec6c at term 2"} {"level":"info","ts":"2026-02-05T17:38:46.19075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2fdf33955ed9ec6c became leader at term 2"} {"level":"info","ts":"2026-02-05T17:38:46.190801Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2fdf33955ed9ec6c elected leader 2fdf33955ed9ec6c at term 2"} {"level":"info","ts":"2026-02-05T17:38:46.192871Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T17:38:46.196122Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2fdf33955ed9ec6c","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.131:2379]}","request-path":"/0/members/2fdf33955ed9ec6c/attributes","cluster-id":"e3dd6b4ed8cb907a","publish-timeout":"7s"} {"level":"info","ts":"2026-02-05T17:38:46.198151Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e3dd6b4ed8cb907a","local-member-id":"2fdf33955ed9ec6c","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T17:38:46.198205Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T17:38:46.198222Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T17:38:46.19823Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T17:38:46.19845Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T17:38:46.199757Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-05T17:38:46.201284Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.131:2379"} {"level":"info","ts":"2026-02-05T17:38:46.206809Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-05T17:38:46.206846Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-05T17:38:54.290669Z","caller":"traceutil/trace.go:171","msg":"trace[775186895] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"131.627889ms","start":"2026-02-05T17:38:54.159007Z","end":"2026-02-05T17:38:54.290635Z","steps":["trace[775186895] 'process raft request' (duration: 131.043773ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:38:54.567387Z","caller":"traceutil/trace.go:171","msg":"trace[152837386] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"107.84946ms","start":"2026-02-05T17:38:54.459514Z","end":"2026-02-05T17:38:54.567363Z","steps":["trace[152837386] 'process raft request' (duration: 107.662345ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:38:59.973702Z","caller":"traceutil/trace.go:171","msg":"trace[898224810] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"263.219237ms","start":"2026-02-05T17:38:59.710442Z","end":"2026-02-05T17:38:59.973661Z","steps":["trace[898224810] 'process raft request' (duration: 263.082063ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:00.934888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.260037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:39:00.935006Z","caller":"traceutil/trace.go:171","msg":"trace[43697462] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:405; }","duration":"286.430131ms","start":"2026-02-05T17:39:00.648542Z","end":"2026-02-05T17:39:00.934972Z","steps":["trace[43697462] 'range keys from in-memory index tree' (duration: 286.209295ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:00.935281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.19137ms","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-05T17:39:00.935311Z","caller":"traceutil/trace.go:171","msg":"trace[1341664837] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:405; }","duration":"849.254161ms","start":"2026-02-05T17:39:00.086048Z","end":"2026-02-05T17:39:00.935302Z","steps":["trace[1341664837] 'range keys from in-memory index tree' (duration: 849.110557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:00.935338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:00.086031Z","time spent":"849.298772ms","remote":"127.0.0.1:50602","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-05T17:39:00.935485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.822599ms","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-05T17:39:00.935512Z","caller":"traceutil/trace.go:171","msg":"trace[931701623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:405; }","duration":"845.868ms","start":"2026-02-05T17:39:00.089635Z","end":"2026-02-05T17:39:00.935503Z","steps":["trace[931701623] 'range keys from in-memory index tree' (duration: 845.741977ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:00.935576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:00.089619Z","time spent":"845.949783ms","remote":"127.0.0.1:50602","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":"info","ts":"2026-02-05T17:39:16.09879Z","caller":"traceutil/trace.go:171","msg":"trace[484760594] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"427.259826ms","start":"2026-02-05T17:39:15.671481Z","end":"2026-02-05T17:39:16.098741Z","steps":["trace[484760594] 'process raft request' (duration: 426.651459ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:16.099292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:15.671472Z","time spent":"427.378449ms","remote":"127.0.0.1:50502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4254,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:39:17.277771Z","caller":"traceutil/trace.go:171","msg":"trace[117075959] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"125.297876ms","start":"2026-02-05T17:39:17.152457Z","end":"2026-02-05T17:39:17.277755Z","steps":["trace[117075959] 'process raft request' (duration: 125.162152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:18.380006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.758372ms","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-05T17:39:18.380057Z","caller":"traceutil/trace.go:171","msg":"trace[905028938] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:516; }","duration":"267.839865ms","start":"2026-02-05T17:39:18.112203Z","end":"2026-02-05T17:39:18.380043Z","steps":["trace[905028938] 'range keys from in-memory index tree' (duration: 267.673049ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:18.67789Z","caller":"traceutil/trace.go:171","msg":"trace[961503365] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"309.550408ms","start":"2026-02-05T17:39:18.368324Z","end":"2026-02-05T17:39:18.677874Z","steps":["trace[961503365] 'process raft request' (duration: 309.405954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:18.678011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:18.368308Z","time spent":"309.63638ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:39:18.955302Z","caller":"traceutil/trace.go:171","msg":"trace[511258544] linearizableReadLoop","detail":"{readStateIndex:537; appliedIndex:535; }","duration":"368.827224ms","start":"2026-02-05T17:39:18.58646Z","end":"2026-02-05T17:39:18.955287Z","steps":["trace[511258544] 'read index received' (duration: 91.321344ms)","trace[511258544] 'applied index is now lower than readState.Index' (duration: 277.50485ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:39:18.955383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.912646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:39:18.9554Z","caller":"traceutil/trace.go:171","msg":"trace[515910066] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:518; }","duration":"368.974878ms","start":"2026-02-05T17:39:18.586421Z","end":"2026-02-05T17:39:18.955396Z","steps":["trace[515910066] 'agreement among raft nodes before linearized reading' (duration: 368.918666ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:18.955416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:18.5864Z","time spent":"369.012778ms","remote":"127.0.0.1:50328","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-05T17:39:18.955563Z","caller":"traceutil/trace.go:171","msg":"trace[1443197734] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"571.995114ms","start":"2026-02-05T17:39:18.383562Z","end":"2026-02-05T17:39:18.955557Z","steps":["trace[1443197734] 'process raft request' (duration: 499.533977ms)","trace[1443197734] 'compare' (duration: 72.112348ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:39:18.955606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:18.383544Z","time spent":"572.031025ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:39:19.24469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.491807ms","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-05T17:39:19.244747Z","caller":"traceutil/trace.go:171","msg":"trace[1508933584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:518; }","duration":"103.59332ms","start":"2026-02-05T17:39:19.141141Z","end":"2026-02-05T17:39:19.244734Z","steps":["trace[1508933584] 'range keys from in-memory index tree' (duration: 103.420986ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:22.383416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.99285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:39:22.383495Z","caller":"traceutil/trace.go:171","msg":"trace[1578494070] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:525; }","duration":"798.103212ms","start":"2026-02-05T17:39:21.585375Z","end":"2026-02-05T17:39:22.383479Z","steps":["trace[1578494070] 'range keys from in-memory index tree' (duration: 797.932429ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:22.383532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:21.585363Z","time spent":"798.161025ms","remote":"127.0.0.1:50328","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-05T17:39:22.383783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.201006ms","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-05T17:39:22.383814Z","caller":"traceutil/trace.go:171","msg":"trace[1934442229] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:525; }","duration":"790.242987ms","start":"2026-02-05T17:39:21.59356Z","end":"2026-02-05T17:39:22.383803Z","steps":["trace[1934442229] 'range keys from in-memory index tree' (duration: 790.085143ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:22.383839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:21.593554Z","time spent":"790.279738ms","remote":"127.0.0.1:50602","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-05T17:39:22.384511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.624511ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:39:22.384568Z","caller":"traceutil/trace.go:171","msg":"trace[1992766213] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"517.976003ms","start":"2026-02-05T17:39:21.86658Z","end":"2026-02-05T17:39:22.384556Z","steps":["trace[1992766213] 'process raft request' (duration: 251.082564ms)","trace[1992766213] 'compare' (duration: 266.443247ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:39:22.384613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:21.866558Z","time spent":"518.032775ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:39:23.40734Z","caller":"traceutil/trace.go:171","msg":"trace[1513761149] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"303.523812ms","start":"2026-02-05T17:39:23.103797Z","end":"2026-02-05T17:39:23.407321Z","steps":["trace[1513761149] 'process raft request' (duration: 303.262035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:23.407449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:23.103784Z","time spent":"303.609245ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:39:23.542037Z","caller":"traceutil/trace.go:171","msg":"trace[1046194268] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"124.494344ms","start":"2026-02-05T17:39:23.417521Z","end":"2026-02-05T17:39:23.542015Z","steps":["trace[1046194268] 'process raft request' (duration: 124.450783ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:23.542362Z","caller":"traceutil/trace.go:171","msg":"trace[288971822] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"125.655145ms","start":"2026-02-05T17:39:23.416696Z","end":"2026-02-05T17:39:23.542351Z","steps":["trace[288971822] 'process raft request' (duration: 124.598707ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:24.514698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.1049ms","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-05T17:39:24.514749Z","caller":"traceutil/trace.go:171","msg":"trace[1356742380] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:538; }","duration":"121.202973ms","start":"2026-02-05T17:39:24.393533Z","end":"2026-02-05T17:39:24.514736Z","steps":["trace[1356742380] 'range keys from in-memory index tree' (duration: 120.989188ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:28.449424Z","caller":"traceutil/trace.go:171","msg":"trace[125199009] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"179.724008ms","start":"2026-02-05T17:39:28.269679Z","end":"2026-02-05T17:39:28.449403Z","steps":["trace[125199009] 'process raft request' (duration: 179.487732ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:28.522479Z","caller":"traceutil/trace.go:171","msg":"trace[549773635] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:625; }","duration":"103.917689ms","start":"2026-02-05T17:39:28.418545Z","end":"2026-02-05T17:39:28.522463Z","steps":["trace[549773635] 'read index received' (duration: 30.584029ms)","trace[549773635] 'applied index is now lower than readState.Index' (duration: 73.33273ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:39:28.522682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.143256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-02-05T17:39:28.522712Z","caller":"traceutil/trace.go:171","msg":"trace[1776438367] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:607; }","duration":"104.203278ms","start":"2026-02-05T17:39:28.418498Z","end":"2026-02-05T17:39:28.522701Z","steps":["trace[1776438367] 'agreement among raft nodes before linearized reading' (duration: 104.043354ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:28.522902Z","caller":"traceutil/trace.go:171","msg":"trace[237108914] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"238.230192ms","start":"2026-02-05T17:39:28.284665Z","end":"2026-02-05T17:39:28.522895Z","steps":["trace[237108914] 'process raft request' (duration: 237.202304ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:28.523284Z","caller":"traceutil/trace.go:171","msg":"trace[931532926] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"109.982756ms","start":"2026-02-05T17:39:28.413278Z","end":"2026-02-05T17:39:28.523261Z","steps":["trace[931532926] 'process raft request' (duration: 108.874676ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:40.86698Z","caller":"traceutil/trace.go:171","msg":"trace[850713863] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"267.124395ms","start":"2026-02-05T17:39:40.599837Z","end":"2026-02-05T17:39:40.866961Z","steps":["trace[850713863] 'process raft request' (duration: 267.029333ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:41.392392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.360188ms","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-05T17:39:41.392466Z","caller":"traceutil/trace.go:171","msg":"trace[1808156243] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:712; }","duration":"338.481091ms","start":"2026-02-05T17:39:41.053968Z","end":"2026-02-05T17:39:41.392449Z","steps":["trace[1808156243] 'range keys from in-memory index tree' (duration: 338.183533ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:41.392516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:41.053943Z","time spent":"338.565674ms","remote":"127.0.0.1:50602","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":"info","ts":"2026-02-05T17:39:41.414272Z","caller":"traceutil/trace.go:171","msg":"trace[1148714744] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"227.303383ms","start":"2026-02-05T17:39:41.186951Z","end":"2026-02-05T17:39:41.414254Z","steps":["trace[1148714744] 'process raft request' (duration: 227.212281ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:41.779521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.81198ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:39:41.779608Z","caller":"traceutil/trace.go:171","msg":"trace[2004740444] linearizableReadLoop","detail":"{readStateIndex:740; appliedIndex:739; }","duration":"193.010323ms","start":"2026-02-05T17:39:41.586583Z","end":"2026-02-05T17:39:41.779593Z","steps":["trace[2004740444] 'read index received' (duration: 71.082599ms)","trace[2004740444] 'applied index is now lower than readState.Index' (duration: 121.926664ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:39:41.779873Z","caller":"traceutil/trace.go:171","msg":"trace[14118573] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"383.549967ms","start":"2026-02-05T17:39:41.396308Z","end":"2026-02-05T17:39:41.779858Z","steps":["trace[14118573] 'process raft request' (duration: 261.324385ms)","trace[14118573] 'compare' (duration: 121.644076ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:39:41.780042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:41.396293Z","time spent":"383.613378ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:39:41.7803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.715522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:39:41.7806Z","caller":"traceutil/trace.go:171","msg":"trace[1546606521] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:714; }","duration":"194.044971ms","start":"2026-02-05T17:39:41.586547Z","end":"2026-02-05T17:39:41.780591Z","steps":["trace[1546606521] 'agreement among raft nodes before linearized reading' (duration: 193.737683ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:42.411256Z","caller":"traceutil/trace.go:171","msg":"trace[1756797122] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"626.387385ms","start":"2026-02-05T17:39:41.784847Z","end":"2026-02-05T17:39:42.411235Z","steps":["trace[1756797122] 'process raft request' (duration: 626.280012ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:42.411373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:41.784839Z","time spent":"626.476088ms","remote":"127.0.0.1:50602","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-05T17:39:42.411873Z","caller":"traceutil/trace.go:171","msg":"trace[1707141493] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"624.503294ms","start":"2026-02-05T17:39:41.787308Z","end":"2026-02-05T17:39:42.411811Z","steps":["trace[1707141493] 'process raft request' (duration: 623.876187ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:42.412209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:41.787295Z","time spent":"624.799942ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:39:42.413116Z","caller":"traceutil/trace.go:171","msg":"trace[730644651] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"628.579345ms","start":"2026-02-05T17:39:41.784521Z","end":"2026-02-05T17:39:42.4131Z","steps":["trace[730644651] 'process raft request' (duration: 530.596348ms)","trace[730644651] 'compare' (duration: 95.387736ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:39:42.413365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:41.784508Z","time spent":"628.815752ms","remote":"127.0.0.1:50602","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-02-05T17:39:44.120282Z","caller":"traceutil/trace.go:171","msg":"trace[1013488490] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"295.732789ms","start":"2026-02-05T17:39:43.824533Z","end":"2026-02-05T17:39:44.120265Z","steps":["trace[1013488490] 'process raft request' (duration: 295.638937ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:44.537826Z","caller":"traceutil/trace.go:171","msg":"trace[327607015] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"110.638394ms","start":"2026-02-05T17:39:44.427161Z","end":"2026-02-05T17:39:44.537799Z","steps":["trace[327607015] 'process raft request' (duration: 110.574232ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:44.538224Z","caller":"traceutil/trace.go:171","msg":"trace[14350929] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"113.871832ms","start":"2026-02-05T17:39:44.424341Z","end":"2026-02-05T17:39:44.538213Z","steps":["trace[14350929] 'process raft request' (duration: 113.290696ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:45.140317Z","caller":"traceutil/trace.go:171","msg":"trace[1987706479] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"260.595345ms","start":"2026-02-05T17:39:44.879697Z","end":"2026-02-05T17:39:45.140292Z","steps":["trace[1987706479] 'process raft request' (duration: 260.455171ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:50.642562Z","caller":"traceutil/trace.go:171","msg":"trace[424259449] linearizableReadLoop","detail":"{readStateIndex:770; appliedIndex:769; }","duration":"411.023909ms","start":"2026-02-05T17:39:50.23152Z","end":"2026-02-05T17:39:50.642543Z","steps":["trace[424259449] 'read index received' (duration: 410.883845ms)","trace[424259449] 'applied index is now lower than readState.Index' (duration: 139.434µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:39:50.64265Z","caller":"traceutil/trace.go:171","msg":"trace[1220467010] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"502.95805ms","start":"2026-02-05T17:39:50.139686Z","end":"2026-02-05T17:39:50.642644Z","steps":["trace[1220467010] 'process raft request' (duration: 502.762365ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:50.642725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:50.139677Z","time spent":"502.990691ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:39:50.6428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.591491ms","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-05T17:39:50.642822Z","caller":"traceutil/trace.go:171","msg":"trace[1071886604] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:742; }","duration":"122.631423ms","start":"2026-02-05T17:39:50.520185Z","end":"2026-02-05T17:39:50.642816Z","steps":["trace[1071886604] 'agreement among raft nodes before linearized reading' (duration: 122.53812ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:50.642872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.351298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T17:39:50.642883Z","caller":"traceutil/trace.go:171","msg":"trace[939365572] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:742; }","duration":"411.3863ms","start":"2026-02-05T17:39:50.231493Z","end":"2026-02-05T17:39:50.642879Z","steps":["trace[939365572] 'agreement among raft nodes before linearized reading' (duration: 411.366239ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:50.642895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:50.231479Z","time spent":"411.41163ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":2,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"info","ts":"2026-02-05T17:39:50.829856Z","caller":"traceutil/trace.go:171","msg":"trace[886144911] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"182.33791ms","start":"2026-02-05T17:39:50.647504Z","end":"2026-02-05T17:39:50.829842Z","steps":["trace[886144911] 'process raft request' (duration: 172.294375ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:50.845195Z","caller":"traceutil/trace.go:171","msg":"trace[1080179203] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"196.643102ms","start":"2026-02-05T17:39:50.648539Z","end":"2026-02-05T17:39:50.845182Z","steps":["trace[1080179203] 'process raft request' (duration: 196.314853ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:50.845284Z","caller":"traceutil/trace.go:171","msg":"trace[1475176723] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"195.593003ms","start":"2026-02-05T17:39:50.649687Z","end":"2026-02-05T17:39:50.84528Z","steps":["trace[1475176723] 'process raft request' (duration: 195.240004ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:40:03.247744Z","caller":"traceutil/trace.go:171","msg":"trace[148951954] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"319.288015ms","start":"2026-02-05T17:40:02.928439Z","end":"2026-02-05T17:40:03.247727Z","steps":["trace[148951954] 'process raft request' (duration: 317.614809ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:03.247851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:40:02.928429Z","time spent":"319.373847ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:40:03.790316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.85615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:40:03.790386Z","caller":"traceutil/trace.go:171","msg":"trace[223808352] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:781; }","duration":"203.967743ms","start":"2026-02-05T17:40:03.586402Z","end":"2026-02-05T17:40:03.79037Z","steps":["trace[223808352] 'range keys from in-memory index tree' (duration: 203.768258ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:03.790629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.290055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:40:03.79066Z","caller":"traceutil/trace.go:171","msg":"trace[2068048638] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:781; }","duration":"106.324226ms","start":"2026-02-05T17:40:03.684326Z","end":"2026-02-05T17:40:03.790651Z","steps":["trace[2068048638] 'count revisions from in-memory index tree' (duration: 106.154221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:05.358738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.992448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-05T17:40:05.358799Z","caller":"traceutil/trace.go:171","msg":"trace[106721394] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:784; }","duration":"105.086422ms","start":"2026-02-05T17:40:05.253701Z","end":"2026-02-05T17:40:05.358787Z","steps":["trace[106721394] 'range keys from in-memory index tree' (duration: 104.904566ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:05.358971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.467236ms","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-05T17:40:05.359019Z","caller":"traceutil/trace.go:171","msg":"trace[497493578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:784; }","duration":"107.545039ms","start":"2026-02-05T17:40:05.251461Z","end":"2026-02-05T17:40:05.359006Z","steps":["trace[497493578] 'range keys from in-memory index tree' (duration: 107.344983ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:40:06.965537Z","caller":"traceutil/trace.go:171","msg":"trace[739261457] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"212.651879ms","start":"2026-02-05T17:40:06.752859Z","end":"2026-02-05T17:40:06.965511Z","steps":["trace[739261457] 'process raft request' (duration: 212.504555ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:40:07.294756Z","caller":"traceutil/trace.go:171","msg":"trace[659126632] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"324.528014ms","start":"2026-02-05T17:40:06.970206Z","end":"2026-02-05T17:40:07.294734Z","steps":["trace[659126632] 'process raft request' (duration: 247.684873ms)","trace[659126632] 'compare' (duration: 76.613655ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:40:07.294907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:40:06.97018Z","time spent":"324.665819ms","remote":"127.0.0.1:50602","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-02-05T17:40:07.943483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.276432ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:40:07.943584Z","caller":"traceutil/trace.go:171","msg":"trace[665409559] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:819; }","duration":"579.205516ms","start":"2026-02-05T17:40:07.364361Z","end":"2026-02-05T17:40:07.943567Z","steps":["trace[665409559] 'read index received' (duration: 141.723968ms)","trace[665409559] 'applied index is now lower than readState.Index' (duration: 437.480388ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:40:07.943775Z","caller":"traceutil/trace.go:171","msg":"trace[1709416198] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"972.691377ms","start":"2026-02-05T17:40:06.971072Z","end":"2026-02-05T17:40:07.943763Z","steps":["trace[1709416198] 'process raft request' (duration: 535.060105ms)","trace[1709416198] 'compare' (duration: 437.003125ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:40:07.943835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:40:06.971059Z","time spent":"972.743549ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:40:07.944133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.771242ms","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-05T17:40:07.944164Z","caller":"traceutil/trace.go:171","msg":"trace[962701365] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:789; }","duration":"579.843084ms","start":"2026-02-05T17:40:07.364311Z","end":"2026-02-05T17:40:07.944155Z","steps":["trace[962701365] 'agreement among raft nodes before linearized reading' (duration: 579.727221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:07.944192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:40:07.364293Z","time spent":"579.892475ms","remote":"127.0.0.1:50602","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-05T17:40:07.944363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.019494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-05T17:40:07.94439Z","caller":"traceutil/trace.go:171","msg":"trace[1111419393] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:789; }","duration":"578.076085ms","start":"2026-02-05T17:40:07.366306Z","end":"2026-02-05T17:40:07.944382Z","steps":["trace[1111419393] 'agreement among raft nodes before linearized reading' (duration: 577.997583ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:07.944806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.866059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T17:40:07.944842Z","caller":"traceutil/trace.go:171","msg":"trace[1203298647] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:789; }","duration":"185.940181ms","start":"2026-02-05T17:40:07.758893Z","end":"2026-02-05T17:40:07.944833Z","steps":["trace[1203298647] 'agreement among raft nodes before linearized reading' (duration: 185.849559ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:07.945003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.954797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:40:07.945032Z","caller":"traceutil/trace.go:171","msg":"trace[296507162] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:789; }","duration":"134.018147ms","start":"2026-02-05T17:40:07.811006Z","end":"2026-02-05T17:40:07.945025Z","steps":["trace[296507162] 'agreement among raft nodes before linearized reading' (duration: 133.967006ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:07.945458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.20979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:40:07.945494Z","caller":"traceutil/trace.go:171","msg":"trace[1314197986] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:789; }","duration":"358.274692ms","start":"2026-02-05T17:40:07.587207Z","end":"2026-02-05T17:40:07.945482Z","steps":["trace[1314197986] 'agreement among raft nodes before linearized reading' (duration: 358.224641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:07.945529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:40:07.587189Z","time spent":"358.332204ms","remote":"127.0.0.1:50328","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-05T17:40:07.94443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:40:07.36629Z","time spent":"578.134407ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-05T17:40:08.176964Z","caller":"traceutil/trace.go:171","msg":"trace[1260699675] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"222.816465ms","start":"2026-02-05T17:40:07.954128Z","end":"2026-02-05T17:40:08.176944Z","steps":["trace[1260699675] 'process raft request' (duration: 222.758033ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:40:08.177185Z","caller":"traceutil/trace.go:171","msg":"trace[729815129] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"223.831553ms","start":"2026-02-05T17:40:07.953333Z","end":"2026-02-05T17:40:08.177165Z","steps":["trace[729815129] 'process raft request' (duration: 139.484157ms)","trace[729815129] 'compare' (duration: 83.943205ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:40:08.831402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.651877ms","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-05T17:40:08.831585Z","caller":"traceutil/trace.go:171","msg":"trace[1809892284] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:791; }","duration":"328.875683ms","start":"2026-02-05T17:40:08.502691Z","end":"2026-02-05T17:40:08.831567Z","steps":["trace[1809892284] 'range keys from in-memory index tree' (duration: 328.632146ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:40:20.312065Z","caller":"traceutil/trace.go:171","msg":"trace[1528980578] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"270.815201ms","start":"2026-02-05T17:40:20.041232Z","end":"2026-02-05T17:40:20.312047Z","steps":["trace[1528980578] 'process raft request' (duration: 270.700398ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:40:20.314321Z","caller":"traceutil/trace.go:171","msg":"trace[206459279] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"189.029455ms","start":"2026-02-05T17:40:20.125285Z","end":"2026-02-05T17:40:20.314314Z","steps":["trace[206459279] 'process raft request' (duration: 188.812709ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:40:21.281886Z","caller":"traceutil/trace.go:171","msg":"trace[2124057690] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"186.478496ms","start":"2026-02-05T17:40:21.095391Z","end":"2026-02-05T17:40:21.28187Z","steps":["trace[2124057690] 'process raft request' (duration: 186.398444ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:21.938394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.95662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:40:21.938441Z","caller":"traceutil/trace.go:171","msg":"trace[961371480] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:878; }","duration":"356.045073ms","start":"2026-02-05T17:40:21.582385Z","end":"2026-02-05T17:40:21.93843Z","steps":["trace[961371480] 'range keys from in-memory index tree' (duration: 355.911529ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:21.938463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:40:21.582373Z","time spent":"356.086023ms","remote":"127.0.0.1:50328","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-05T17:40:21.938491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.809604ms","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-05T17:40:21.93854Z","caller":"traceutil/trace.go:171","msg":"trace[1634088692] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:878; }","duration":"586.868285ms","start":"2026-02-05T17:40:21.351657Z","end":"2026-02-05T17:40:21.938525Z","steps":["trace[1634088692] 'range keys from in-memory index tree' (duration: 586.703191ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:21.938563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:40:21.351621Z","time spent":"586.937687ms","remote":"127.0.0.1:50602","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":"info","ts":"2026-02-05T17:40:22.358664Z","caller":"traceutil/trace.go:171","msg":"trace[149077073] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"415.406328ms","start":"2026-02-05T17:40:21.943244Z","end":"2026-02-05T17:40:22.358651Z","steps":["trace[149077073] 'process raft request' (duration: 415.284285ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:22.358755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:40:21.943234Z","time spent":"415.47319ms","remote":"127.0.0.1:50602","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-02-05T17:40:22.653972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.484318ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:40:22.655057Z","caller":"traceutil/trace.go:171","msg":"trace[585822245] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"291.024551ms","start":"2026-02-05T17:40:22.36402Z","end":"2026-02-05T17:40:22.655045Z","steps":["trace[585822245] 'process raft request' (duration: 100.415502ms)","trace[585822245] 'compare' (duration: 189.424887ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:40:22.655125Z","caller":"traceutil/trace.go:171","msg":"trace[1129213974] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"289.559251ms","start":"2026-02-05T17:40:22.365561Z","end":"2026-02-05T17:40:22.655121Z","steps":["trace[1129213974] 'process raft request' (duration: 288.478272ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:40:22.655175Z","caller":"traceutil/trace.go:171","msg":"trace[214586181] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"288.352229ms","start":"2026-02-05T17:40:22.366819Z","end":"2026-02-05T17:40:22.655171Z","steps":["trace[214586181] 'process raft request' (duration: 287.245149ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:40:23.464913Z","caller":"traceutil/trace.go:171","msg":"trace[509710111] linearizableReadLoop","detail":"{readStateIndex:919; appliedIndex:918; }","duration":"152.905462ms","start":"2026-02-05T17:40:23.311992Z","end":"2026-02-05T17:40:23.464898Z","steps":["trace[509710111] 'read index received' (duration: 152.768228ms)","trace[509710111] 'applied index is now lower than readState.Index' (duration: 136.684µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:40:23.465017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.008375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T17:40:23.465035Z","caller":"traceutil/trace.go:171","msg":"trace[1976266716] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:883; }","duration":"153.071317ms","start":"2026-02-05T17:40:23.311959Z","end":"2026-02-05T17:40:23.46503Z","steps":["trace[1976266716] 'agreement among raft nodes before linearized reading' (duration: 152.996235ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:40:23.465145Z","caller":"traceutil/trace.go:171","msg":"trace[672715161] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"178.702944ms","start":"2026-02-05T17:40:23.286421Z","end":"2026-02-05T17:40:23.465124Z","steps":["trace[672715161] 'process raft request' (duration: 178.382366ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:23.749453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.488292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:40:23.749524Z","caller":"traceutil/trace.go:171","msg":"trace[1858919669] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:883; }","duration":"166.610536ms","start":"2026-02-05T17:40:23.582894Z","end":"2026-02-05T17:40:23.749504Z","steps":["trace[1858919669] 'range keys from in-memory index tree' (duration: 166.41151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:23.749736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.363187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T17:40:23.749764Z","caller":"traceutil/trace.go:171","msg":"trace[677124017] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:883; }","duration":"138.415107ms","start":"2026-02-05T17:40:23.61134Z","end":"2026-02-05T17:40:23.749755Z","steps":["trace[677124017] 'count revisions from in-memory index tree' (duration: 138.305025ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:40:32.629448Z","caller":"traceutil/trace.go:171","msg":"trace[1565024203] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"140.71509ms","start":"2026-02-05T17:40:32.488719Z","end":"2026-02-05T17:40:32.629434Z","steps":["trace[1565024203] 'process raft request' (duration: 140.402312ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:34.083356Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17036163215619134954,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T17:40:34.425005Z","caller":"traceutil/trace.go:171","msg":"trace[1677681290] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"927.794156ms","start":"2026-02-05T17:40:33.497197Z","end":"2026-02-05T17:40:34.424991Z","steps":["trace[1677681290] 'process raft request' (duration: 927.710903ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:34.425094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:40:33.497187Z","time spent":"927.859617ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:40:34.425347Z","caller":"traceutil/trace.go:171","msg":"trace[1400727018] linearizableReadLoop","detail":"{readStateIndex:949; appliedIndex:949; }","duration":"842.948576ms","start":"2026-02-05T17:40:33.582393Z","end":"2026-02-05T17:40:34.425341Z","steps":["trace[1400727018] 'read index received' (duration: 842.946086ms)","trace[1400727018] 'applied index is now lower than readState.Index' (duration: 2.16µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:40:34.425374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"842.979777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:40:34.425389Z","caller":"traceutil/trace.go:171","msg":"trace[667903512] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:911; }","duration":"843.017618ms","start":"2026-02-05T17:40:33.582367Z","end":"2026-02-05T17:40:34.425384Z","steps":["trace[667903512] 'agreement among raft nodes before linearized reading' (duration: 842.992938ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:34.425401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:40:33.582346Z","time spent":"843.052599ms","remote":"127.0.0.1:50328","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-05T17:40:36.828625Z","caller":"traceutil/trace.go:171","msg":"trace[801403456] linearizableReadLoop","detail":"{readStateIndex:956; appliedIndex:955; }","duration":"246.077137ms","start":"2026-02-05T17:40:36.582526Z","end":"2026-02-05T17:40:36.828603Z","steps":["trace[801403456] 'read index received' (duration: 245.920313ms)","trace[801403456] 'applied index is now lower than readState.Index' (duration: 155.944µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:40:36.830443Z","caller":"traceutil/trace.go:171","msg":"trace[1972278731] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"399.506284ms","start":"2026-02-05T17:40:36.430926Z","end":"2026-02-05T17:40:36.830432Z","steps":["trace[1972278731] 'process raft request' (duration: 397.572641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:36.830658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.524349ms","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-05T17:40:36.831509Z","caller":"traceutil/trace.go:171","msg":"trace[1168678169] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:917; }","duration":"175.394493ms","start":"2026-02-05T17:40:36.656105Z","end":"2026-02-05T17:40:36.8315Z","steps":["trace[1168678169] 'agreement among raft nodes before linearized reading' (duration: 174.482328ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:36.830556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:40:36.430917Z","time spent":"399.587346ms","remote":"127.0.0.1:50602","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-05T17:40:36.83097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.440861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:40:36.834467Z","caller":"traceutil/trace.go:171","msg":"trace[1551160803] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:917; }","duration":"251.958208ms","start":"2026-02-05T17:40:36.582499Z","end":"2026-02-05T17:40:36.834457Z","steps":["trace[1551160803] 'agreement among raft nodes before linearized reading' (duration: 248.452412ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:40:41.086281Z","caller":"traceutil/trace.go:171","msg":"trace[699893271] linearizableReadLoop","detail":"{readStateIndex:977; appliedIndex:976; }","duration":"171.17668ms","start":"2026-02-05T17:40:40.915082Z","end":"2026-02-05T17:40:41.086259Z","steps":["trace[699893271] 'read index received' (duration: 135.417956ms)","trace[699893271] 'applied index is now lower than readState.Index' (duration: 35.757794ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:40:41.08637Z","caller":"traceutil/trace.go:171","msg":"trace[1065147764] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"221.191241ms","start":"2026-02-05T17:40:40.86516Z","end":"2026-02-05T17:40:41.086351Z","steps":["trace[1065147764] 'process raft request' (duration: 185.383437ms)","trace[1065147764] 'compare' (duration: 35.624649ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:40:41.08651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.209767ms","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-05T17:40:41.086541Z","caller":"traceutil/trace.go:171","msg":"trace[1052297349] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:937; }","duration":"194.272998ms","start":"2026-02-05T17:40:40.892259Z","end":"2026-02-05T17:40:41.086532Z","steps":["trace[1052297349] 'agreement among raft nodes before linearized reading' (duration: 194.110634ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:41.086697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.260827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-05T17:40:41.086721Z","caller":"traceutil/trace.go:171","msg":"trace[1437985977] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:937; }","duration":"198.331969ms","start":"2026-02-05T17:40:40.888382Z","end":"2026-02-05T17:40:41.086714Z","steps":["trace[1437985977] 'agreement among raft nodes before linearized reading' (duration: 198.232536ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:41.15702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.566839ms","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-05T17:40:41.157064Z","caller":"traceutil/trace.go:171","msg":"trace[1355946962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:938; }","duration":"236.633342ms","start":"2026-02-05T17:40:40.920421Z","end":"2026-02-05T17:40:41.157054Z","steps":["trace[1355946962] 'agreement among raft nodes before linearized reading' (duration: 236.512028ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:40:41.157121Z","caller":"traceutil/trace.go:171","msg":"trace[1753785653] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"233.702012ms","start":"2026-02-05T17:40:40.923404Z","end":"2026-02-05T17:40:41.157106Z","steps":["trace[1753785653] 'process raft request' (duration: 233.452285ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:41.97881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.33943ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:40:41.978937Z","caller":"traceutil/trace.go:171","msg":"trace[1352329309] linearizableReadLoop","detail":"{readStateIndex:981; appliedIndex:978; }","duration":"390.295545ms","start":"2026-02-05T17:40:41.58863Z","end":"2026-02-05T17:40:41.978925Z","steps":["trace[1352329309] 'read index received' (duration: 142.704095ms)","trace[1352329309] 'applied index is now lower than readState.Index' (duration: 247.59042ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:40:41.979011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.388728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:40:41.979028Z","caller":"traceutil/trace.go:171","msg":"trace[531841595] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:941; }","duration":"390.431969ms","start":"2026-02-05T17:40:41.58859Z","end":"2026-02-05T17:40:41.979022Z","steps":["trace[531841595] 'agreement among raft nodes before linearized reading' (duration: 390.368837ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:41.979048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:40:41.588574Z","time spent":"390.468671ms","remote":"127.0.0.1:50328","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-05T17:40:41.979212Z","caller":"traceutil/trace.go:171","msg":"trace[1058351459] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"874.881357ms","start":"2026-02-05T17:40:41.104323Z","end":"2026-02-05T17:40:41.979204Z","steps":["trace[1058351459] 'process raft request' (duration: 154.079205ms)","trace[1058351459] 'compare' (duration: 719.855106ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:40:41.979247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:40:41.104306Z","time spent":"874.920888ms","remote":"127.0.0.1:50602","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-05T17:40:41.979316Z","caller":"traceutil/trace.go:171","msg":"trace[363101181] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"874.090265ms","start":"2026-02-05T17:40:41.10522Z","end":"2026-02-05T17:40:41.979311Z","steps":["trace[363101181] 'process raft request' (duration: 873.649943ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:41.979345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:40:41.105214Z","time spent":"874.113615ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:40:41.979415Z","caller":"traceutil/trace.go:171","msg":"trace[1525585994] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"792.998267ms","start":"2026-02-05T17:40:41.186411Z","end":"2026-02-05T17:40:41.979409Z","steps":["trace[1525585994] 'process raft request' (duration: 792.495354ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:41.979442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:40:41.186398Z","time spent":"793.029058ms","remote":"127.0.0.1:50602","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-02-05T17:40:42.83517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.152408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:40:42.835257Z","caller":"traceutil/trace.go:171","msg":"trace[518002265] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:941; }","duration":"679.284362ms","start":"2026-02-05T17:40:42.155954Z","end":"2026-02-05T17:40:42.835239Z","steps":["trace[518002265] 'count revisions from in-memory index tree' (duration: 679.037545ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:42.835295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:40:42.155935Z","time spent":"679.350083ms","remote":"127.0.0.1:50570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-02-05T17:40:42.83564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.831005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T17:40:42.835672Z","caller":"traceutil/trace.go:171","msg":"trace[969856073] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:941; }","duration":"478.889976ms","start":"2026-02-05T17:40:42.356771Z","end":"2026-02-05T17:40:42.835661Z","steps":["trace[969856073] 'count revisions from in-memory index tree' (duration: 478.742662ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:42.835699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:40:42.356737Z","time spent":"478.954598ms","remote":"127.0.0.1:40656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":2,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-02-05T17:40:43.670511Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17036163215619135029,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T17:40:43.777169Z","caller":"traceutil/trace.go:171","msg":"trace[1805522671] linearizableReadLoop","detail":"{readStateIndex:982; appliedIndex:981; }","duration":"607.262629ms","start":"2026-02-05T17:40:43.16989Z","end":"2026-02-05T17:40:43.777153Z","steps":["trace[1805522671] 'read index received' (duration: 607.084365ms)","trace[1805522671] 'applied index is now lower than readState.Index' (duration: 175.924µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:40:43.777423Z","caller":"traceutil/trace.go:171","msg":"trace[163494280] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"851.598962ms","start":"2026-02-05T17:40:42.925817Z","end":"2026-02-05T17:40:43.777416Z","steps":["trace[163494280] 'process raft request' (duration: 851.211961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:43.777498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:40:42.925804Z","time spent":"851.640142ms","remote":"127.0.0.1:50602","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-05T17:40:43.777684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.800995ms","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-05T17:40:43.777703Z","caller":"traceutil/trace.go:171","msg":"trace[121794979] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:942; }","duration":"607.857746ms","start":"2026-02-05T17:40:43.169839Z","end":"2026-02-05T17:40:43.777697Z","steps":["trace[121794979] 'agreement among raft nodes before linearized reading' (duration: 607.776144ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:43.77772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:40:43.169821Z","time spent":"607.894667ms","remote":"127.0.0.1:50602","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-05T17:40:43.777798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.576022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:40:43.777812Z","caller":"traceutil/trace.go:171","msg":"trace[275227381] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:942; }","duration":"192.615943ms","start":"2026-02-05T17:40:43.585192Z","end":"2026-02-05T17:40:43.777808Z","steps":["trace[275227381] 'agreement among raft nodes before linearized reading' (duration: 192.591943ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:43.777957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.808825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T17:40:43.777973Z","caller":"traceutil/trace.go:171","msg":"trace[631122761] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:942; }","duration":"139.862637ms","start":"2026-02-05T17:40:43.638106Z","end":"2026-02-05T17:40:43.777969Z","steps":["trace[631122761] 'agreement among raft nodes before linearized reading' (duration: 139.816685ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:44.359199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.407348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-05T17:40:44.359278Z","caller":"traceutil/trace.go:171","msg":"trace[1735311368] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:943; }","duration":"372.537611ms","start":"2026-02-05T17:40:43.986723Z","end":"2026-02-05T17:40:44.359261Z","steps":["trace[1735311368] 'range keys from in-memory index tree' (duration: 372.198823ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:44.359321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:40:43.986705Z","time spent":"372.606654ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-05T17:40:44.359607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.320728ms","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-05T17:40:44.359648Z","caller":"traceutil/trace.go:171","msg":"trace[1643744308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:943; }","duration":"371.37913ms","start":"2026-02-05T17:40:43.988253Z","end":"2026-02-05T17:40:44.359632Z","steps":["trace[1643744308] 'range keys from in-memory index tree' (duration: 371.225126ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:44.359679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:40:43.988242Z","time spent":"371.426471ms","remote":"127.0.0.1:50602","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-05T17:40:44.35988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.528644ms","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-05T17:40:44.359908Z","caller":"traceutil/trace.go:171","msg":"trace[904515949] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:943; }","duration":"371.587176ms","start":"2026-02-05T17:40:43.988312Z","end":"2026-02-05T17:40:44.359899Z","steps":["trace[904515949] 'range keys from in-memory index tree' (duration: 371.39454ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:44.359933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:40:43.988296Z","time spent":"371.631167ms","remote":"127.0.0.1:50602","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":"info","ts":"2026-02-05T17:40:48.565159Z","caller":"traceutil/trace.go:171","msg":"trace[349084463] linearizableReadLoop","detail":"{readStateIndex:1016; appliedIndex:1015; }","duration":"175.023284ms","start":"2026-02-05T17:40:48.390119Z","end":"2026-02-05T17:40:48.565143Z","steps":["trace[349084463] 'read index received' (duration: 76.42146ms)","trace[349084463] 'applied index is now lower than readState.Index' (duration: 98.601294ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:40:48.565306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.179779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-05T17:40:48.565324Z","caller":"traceutil/trace.go:171","msg":"trace[2001208497] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:975; }","duration":"175.2299ms","start":"2026-02-05T17:40:48.390087Z","end":"2026-02-05T17:40:48.565317Z","steps":["trace[2001208497] 'agreement among raft nodes before linearized reading' (duration: 175.121627ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:40:48.565396Z","caller":"traceutil/trace.go:171","msg":"trace[552569390] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"183.830084ms","start":"2026-02-05T17:40:48.381544Z","end":"2026-02-05T17:40:48.565374Z","steps":["trace[552569390] 'process raft request' (duration: 183.516166ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:40:50.663488Z","caller":"traceutil/trace.go:171","msg":"trace[795512833] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"189.194321ms","start":"2026-02-05T17:40:50.47427Z","end":"2026-02-05T17:40:50.663465Z","steps":["trace[795512833] 'process raft request' (duration: 188.850872ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:40:51.571576Z","caller":"traceutil/trace.go:171","msg":"trace[317351414] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"228.252433ms","start":"2026-02-05T17:40:51.343305Z","end":"2026-02-05T17:40:51.571558Z","steps":["trace[317351414] 'process raft request' (duration: 135.717034ms)","trace[317351414] 'compare' (duration: 92.457697ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:40:51.845781Z","caller":"traceutil/trace.go:171","msg":"trace[928264944] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"245.475802ms","start":"2026-02-05T17:40:51.600292Z","end":"2026-02-05T17:40:51.845768Z","steps":["trace[928264944] 'process raft request' (duration: 235.594914ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:58.418088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.540343ms","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-05T17:40:58.418177Z","caller":"traceutil/trace.go:171","msg":"trace[891601850] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1038; }","duration":"468.657327ms","start":"2026-02-05T17:40:57.949501Z","end":"2026-02-05T17:40:58.418159Z","steps":["trace[891601850] 'range keys from in-memory index tree' (duration: 468.42707ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:58.418213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:40:57.949487Z","time spent":"468.716809ms","remote":"127.0.0.1:50602","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-05T17:40:58.418251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.365837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-05T17:40:58.418285Z","caller":"traceutil/trace.go:171","msg":"trace[1575991447] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1038; }","duration":"544.45447ms","start":"2026-02-05T17:40:57.873823Z","end":"2026-02-05T17:40:58.418278Z","steps":["trace[1575991447] 'range keys from in-memory index tree' (duration: 544.292105ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:58.4183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:40:57.873809Z","time spent":"544.486661ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-05T17:40:58.418416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.40566ms","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-05T17:40:58.418441Z","caller":"traceutil/trace.go:171","msg":"trace[1348105759] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1038; }","duration":"468.444851ms","start":"2026-02-05T17:40:57.949992Z","end":"2026-02-05T17:40:58.418437Z","steps":["trace[1348105759] 'range keys from in-memory index tree' (duration: 468.362699ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:58.418455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:40:57.949974Z","time spent":"468.475652ms","remote":"127.0.0.1:50602","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":"info","ts":"2026-02-05T17:40:59.052025Z","caller":"traceutil/trace.go:171","msg":"trace[1035419502] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"626.740151ms","start":"2026-02-05T17:40:58.425257Z","end":"2026-02-05T17:40:59.051998Z","steps":["trace[1035419502] 'process raft request' (duration: 626.522965ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:59.052182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:40:58.425243Z","time spent":"626.864734ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:40:59.183232Z","caller":"traceutil/trace.go:171","msg":"trace[572981201] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"757.759497ms","start":"2026-02-05T17:40:58.425457Z","end":"2026-02-05T17:40:59.183216Z","steps":["trace[572981201] 'process raft request' (duration: 755.847665ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:59.18349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:40:58.425449Z","time spent":"757.84699ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:40:59.183711Z","caller":"traceutil/trace.go:171","msg":"trace[148786362] linearizableReadLoop","detail":"{readStateIndex:1084; appliedIndex:1082; }","duration":"597.386672ms","start":"2026-02-05T17:40:58.586315Z","end":"2026-02-05T17:40:59.183702Z","steps":["trace[148786362] 'read index received' (duration: 465.695357ms)","trace[148786362] 'applied index is now lower than readState.Index' (duration: 131.690265ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:40:59.183925Z","caller":"traceutil/trace.go:171","msg":"trace[784891933] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"758.425436ms","start":"2026-02-05T17:40:58.42549Z","end":"2026-02-05T17:40:59.183916Z","steps":["trace[784891933] 'process raft request' (duration: 757.699736ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:59.183972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:40:58.42548Z","time spent":"758.468697ms","remote":"127.0.0.1:50602","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-05T17:40:59.184335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.020539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:40:59.184369Z","caller":"traceutil/trace.go:171","msg":"trace[1969179691] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1041; }","duration":"598.120622ms","start":"2026-02-05T17:40:58.586238Z","end":"2026-02-05T17:40:59.184359Z","steps":["trace[1969179691] 'agreement among raft nodes before linearized reading' (duration: 598.071011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:59.184646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:40:58.586226Z","time spent":"598.276806ms","remote":"127.0.0.1:50328","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-05T17:40:59.185078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.042833ms","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-05T17:40:59.185111Z","caller":"traceutil/trace.go:171","msg":"trace[2071251462] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1041; }","duration":"485.105425ms","start":"2026-02-05T17:40:58.699998Z","end":"2026-02-05T17:40:59.185103Z","steps":["trace[2071251462] 'agreement among raft nodes before linearized reading' (duration: 485.005832ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:59.185844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:40:58.699986Z","time spent":"485.144366ms","remote":"127.0.0.1:50602","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-05T17:40:59.186182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.093535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-02-05T17:40:59.186217Z","caller":"traceutil/trace.go:171","msg":"trace[1722554521] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1041; }","duration":"438.143747ms","start":"2026-02-05T17:40:58.748062Z","end":"2026-02-05T17:40:59.186205Z","steps":["trace[1722554521] 'agreement among raft nodes before linearized reading' (duration: 437.909571ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:59.186238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:40:58.748054Z","time spent":"438.178998ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":550,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-05T17:40:59.186546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.300653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-05T17:40:59.186573Z","caller":"traceutil/trace.go:171","msg":"trace[665386874] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1041; }","duration":"459.329254ms","start":"2026-02-05T17:40:58.727238Z","end":"2026-02-05T17:40:59.186567Z","steps":["trace[665386874] 'agreement among raft nodes before linearized reading' (duration: 459.258762ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:59.186739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:40:58.727215Z","time spent":"459.373465ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-05T17:40:59.187001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.095924ms","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-05T17:40:59.187029Z","caller":"traceutil/trace.go:171","msg":"trace[1542900311] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1041; }","duration":"471.137706ms","start":"2026-02-05T17:40:58.715884Z","end":"2026-02-05T17:40:59.187022Z","steps":["trace[1542900311] 'agreement among raft nodes before linearized reading' (duration: 471.062333ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:59.187048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:40:58.715878Z","time spent":"471.166296ms","remote":"127.0.0.1:50602","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-05T17:40:59.719376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.838088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-02-05T17:40:59.719535Z","caller":"traceutil/trace.go:171","msg":"trace[1691491116] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1045; }","duration":"413.044674ms","start":"2026-02-05T17:40:59.30647Z","end":"2026-02-05T17:40:59.719515Z","steps":["trace[1691491116] 'range keys from in-memory index tree' (duration: 412.492359ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:59.719611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:40:59.30645Z","time spent":"413.148087ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-05T17:40:59.719934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.510437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.131\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-05T17:40:59.719998Z","caller":"traceutil/trace.go:171","msg":"trace[1440979302] range","detail":"{range_begin:/registry/masterleases/199.204.45.131; range_end:; response_count:1; response_revision:1045; }","duration":"323.61143ms","start":"2026-02-05T17:40:59.396375Z","end":"2026-02-05T17:40:59.719987Z","steps":["trace[1440979302] 'range keys from in-memory index tree' (duration: 323.358513ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:59.720552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:40:59.396356Z","time spent":"324.182546ms","remote":"127.0.0.1:50350","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.131\" "} {"level":"warn","ts":"2026-02-05T17:40:59.720748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.247955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:40:59.720892Z","caller":"traceutil/trace.go:171","msg":"trace[1825444598] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1045; }","duration":"231.43167ms","start":"2026-02-05T17:40:59.489446Z","end":"2026-02-05T17:40:59.720877Z","steps":["trace[1825444598] 'count revisions from in-memory index tree' (duration: 231.166493ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:59.72155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.777769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:40:59.721682Z","caller":"traceutil/trace.go:171","msg":"trace[166775546] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1045; }","duration":"134.972124ms","start":"2026-02-05T17:40:59.58669Z","end":"2026-02-05T17:40:59.721662Z","steps":["trace[166775546] 'range keys from in-memory index tree' (duration: 134.669966ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:41:27.474262Z","caller":"traceutil/trace.go:171","msg":"trace[1120112945] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"115.371395ms","start":"2026-02-05T17:41:27.358862Z","end":"2026-02-05T17:41:27.474233Z","steps":["trace[1120112945] 'process raft request' (duration: 52.743697ms)","trace[1120112945] 'compare' (duration: 62.377851ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:44:08.301976Z","caller":"traceutil/trace.go:171","msg":"trace[55595965] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"100.150794ms","start":"2026-02-05T17:44:08.201799Z","end":"2026-02-05T17:44:08.30195Z","steps":["trace[55595965] 'process raft request' (duration: 99.750453ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:44:10.921128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.99819ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:44:10.92121Z","caller":"traceutil/trace.go:171","msg":"trace[773553728] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"474.25517ms","start":"2026-02-05T17:44:10.446941Z","end":"2026-02-05T17:44:10.921196Z","steps":["trace[773553728] 'process raft request' (duration: 78.123477ms)","trace[773553728] 'compare' (duration: 395.862726ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:44:10.921252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:44:10.446932Z","time spent":"474.299801ms","remote":"127.0.0.1:50602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}