{"level":"warn","ts":"2026-02-02T18:06:02.119461Z","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-02T18:06:02.119587Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.67: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.67:2380","--initial-cluster=instance=https://199.204.45.67:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.67:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.67: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-02T18:06:02.119664Z","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-02T18:06:02.11969Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.67:2380"]} {"level":"info","ts":"2026-02-02T18:06:02.11971Z","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-02T18:06:02.120338Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.67:2379"]} {"level":"info","ts":"2026-02-02T18:06:02.122082Z","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.67:2380"],"listen-peer-urls":["https://199.204.45.67:2380"],"advertise-client-urls":["https://199.204.45.67:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.67:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.67: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-02T18:06:02.126223Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.822515ms"} {"level":"info","ts":"2026-02-02T18:06:02.140476Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cddd08233fdda28a","cluster-id":"72e3bf4964adc703"} {"level":"info","ts":"2026-02-02T18:06:02.14093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a switched to configuration voters=()"} {"level":"info","ts":"2026-02-02T18:06:02.14099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a became follower at term 0"} {"level":"info","ts":"2026-02-02T18:06:02.141028Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cddd08233fdda28a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-02T18:06:02.141071Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a became follower at term 1"} {"level":"info","ts":"2026-02-02T18:06:02.141142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a switched to configuration voters=(14834021695116649098)"} {"level":"warn","ts":"2026-02-02T18:06:02.147847Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-02T18:06:02.149378Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-02T18:06:02.150579Z","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-02T18:06:02.153596Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cddd08233fdda28a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-02T18:06:02.155709Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cddd08233fdda28a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-02T18:06:02.155802Z","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-02T18:06:02.155824Z","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-02T18:06:02.155831Z","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-02T18:06:02.156101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a switched to configuration voters=(14834021695116649098)"} {"level":"info","ts":"2026-02-02T18:06:02.156153Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"72e3bf4964adc703","local-member-id":"cddd08233fdda28a","added-peer-id":"cddd08233fdda28a","added-peer-peer-urls":["https://199.204.45.67:2380"]} {"level":"info","ts":"2026-02-02T18:06:02.180015Z","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-02T18:06:02.180817Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cddd08233fdda28a","initial-advertise-peer-urls":["https://199.204.45.67:2380"],"listen-peer-urls":["https://199.204.45.67:2380"],"advertise-client-urls":["https://199.204.45.67:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.67:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-02T18:06:02.180955Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-02T18:06:02.180265Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.67:2380"} {"level":"info","ts":"2026-02-02T18:06:02.183925Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.67:2380"} {"level":"info","ts":"2026-02-02T18:06:02.943826Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a is starting a new election at term 1"} {"level":"info","ts":"2026-02-02T18:06:02.94388Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a became pre-candidate at term 1"} {"level":"info","ts":"2026-02-02T18:06:02.943899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a received MsgPreVoteResp from cddd08233fdda28a at term 1"} {"level":"info","ts":"2026-02-02T18:06:02.944014Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a became candidate at term 2"} {"level":"info","ts":"2026-02-02T18:06:02.944057Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a received MsgVoteResp from cddd08233fdda28a at term 2"} {"level":"info","ts":"2026-02-02T18:06:02.94411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a became leader at term 2"} {"level":"info","ts":"2026-02-02T18:06:02.944123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cddd08233fdda28a elected leader cddd08233fdda28a at term 2"} {"level":"info","ts":"2026-02-02T18:06:02.947876Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cddd08233fdda28a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.67:2379]}","request-path":"/0/members/cddd08233fdda28a/attributes","cluster-id":"72e3bf4964adc703","publish-timeout":"7s"} {"level":"info","ts":"2026-02-02T18:06:02.947899Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-02T18:06:02.948242Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T18:06:02.948613Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-02T18:06:02.949542Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"72e3bf4964adc703","local-member-id":"cddd08233fdda28a","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T18:06:02.94964Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T18:06:02.949666Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T18:06:02.950389Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-02T18:06:02.951916Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.67:2379"} {"level":"info","ts":"2026-02-02T18:06:02.95269Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-02T18:06:02.954679Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-02-02T18:06:22.994923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.51154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T18:06:22.995014Z","caller":"traceutil/trace.go:171","msg":"trace[648164747] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:457; }","duration":"385.669784ms","start":"2026-02-02T18:06:22.609328Z","end":"2026-02-02T18:06:22.994998Z","steps":["trace[648164747] 'range keys from in-memory index tree' (duration: 385.427977ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:06:22.995077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:06:22.60929Z","time spent":"385.778717ms","remote":"127.0.0.1:32992","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-02T18:06:22.99528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.305955ms","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-02T18:06:22.995315Z","caller":"traceutil/trace.go:171","msg":"trace[541333979] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:457; }","duration":"156.387887ms","start":"2026-02-02T18:06:22.838916Z","end":"2026-02-02T18:06:22.995304Z","steps":["trace[541333979] 'range keys from in-memory index tree' (duration: 156.219133ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T18:06:23.588768Z","caller":"traceutil/trace.go:171","msg":"trace[448952900] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"588.444657ms","start":"2026-02-02T18:06:23.000308Z","end":"2026-02-02T18:06:23.588753Z","steps":["trace[448952900] 'process raft request' (duration: 588.364855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:06:23.589063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:06:23.000295Z","time spent":"588.513219ms","remote":"127.0.0.1:33268","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-02T18:06:24.281505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.972221ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T18:06:24.281601Z","caller":"traceutil/trace.go:171","msg":"trace[1154746560] linearizableReadLoop","detail":"{readStateIndex:469; appliedIndex:468; }","duration":"703.710843ms","start":"2026-02-02T18:06:23.577875Z","end":"2026-02-02T18:06:24.281585Z","steps":["trace[1154746560] 'read index received' (duration: 11.47356ms)","trace[1154746560] 'applied index is now lower than readState.Index' (duration: 692.235713ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T18:06:24.281843Z","caller":"traceutil/trace.go:171","msg":"trace[9196279] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"1.281296856s","start":"2026-02-02T18:06:23.000538Z","end":"2026-02-02T18:06:24.281835Z","steps":["trace[9196279] 'process raft request' (duration: 918.785941ms)","trace[9196279] 'compare' (duration: 361.540839ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T18:06:24.281882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:06:23.000528Z","time spent":"1.281333558s","remote":"127.0.0.1:33268","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-02T18:06:24.282006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.131214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-02T18:06:24.282025Z","caller":"traceutil/trace.go:171","msg":"trace[1260736954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:459; }","duration":"704.177186ms","start":"2026-02-02T18:06:23.577841Z","end":"2026-02-02T18:06:24.282019Z","steps":["trace[1260736954] 'agreement among raft nodes before linearized reading' (duration: 704.139525ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:06:24.282042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:06:23.577827Z","time spent":"704.211457ms","remote":"127.0.0.1:33268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-02T18:06:24.28215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.470721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-02T18:06:24.282167Z","caller":"traceutil/trace.go:171","msg":"trace[1210558454] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:459; }","duration":"313.527063ms","start":"2026-02-02T18:06:23.968635Z","end":"2026-02-02T18:06:24.282162Z","steps":["trace[1210558454] 'agreement among raft nodes before linearized reading' (duration: 313.495322ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:06:24.282186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:06:23.968621Z","time spent":"313.561143ms","remote":"127.0.0.1:33268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-02T18:06:35.820881Z","caller":"traceutil/trace.go:171","msg":"trace[625109027] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"174.090768ms","start":"2026-02-02T18:06:35.646776Z","end":"2026-02-02T18:06:35.820867Z","steps":["trace[625109027] 'process raft request' (duration: 174.067068ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T18:06:35.821091Z","caller":"traceutil/trace.go:171","msg":"trace[660469414] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"175.8576ms","start":"2026-02-02T18:06:35.645226Z","end":"2026-02-02T18:06:35.821084Z","steps":["trace[660469414] 'process raft request' (duration: 175.532853ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:06:43.108317Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11712345440177594814,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-02T18:06:43.305356Z","caller":"traceutil/trace.go:171","msg":"trace[594149130] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"833.923981ms","start":"2026-02-02T18:06:42.471411Z","end":"2026-02-02T18:06:43.305335Z","steps":["trace[594149130] 'process raft request' (duration: 828.308839ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:06:43.305503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:06:42.471396Z","time spent":"834.031634ms","remote":"127.0.0.1:33268","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-02T18:06:43.30692Z","caller":"traceutil/trace.go:171","msg":"trace[617191920] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:577; }","duration":"699.452959ms","start":"2026-02-02T18:06:42.607454Z","end":"2026-02-02T18:06:43.306907Z","steps":["trace[617191920] 'read index received' (duration: 692.278633ms)","trace[617191920] 'applied index is now lower than readState.Index' (duration: 7.173596ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T18:06:43.306998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.550461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T18:06:43.307024Z","caller":"traceutil/trace.go:171","msg":"trace[165846106] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:565; }","duration":"699.627282ms","start":"2026-02-02T18:06:42.607389Z","end":"2026-02-02T18:06:43.307016Z","steps":["trace[165846106] 'agreement among raft nodes before linearized reading' (duration: 699.564121ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:06:43.307053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:06:42.607368Z","time spent":"699.678564ms","remote":"127.0.0.1:32992","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-02T18:06:43.307278Z","caller":"traceutil/trace.go:171","msg":"trace[1591690724] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"834.873552ms","start":"2026-02-02T18:06:42.472395Z","end":"2026-02-02T18:06:43.307269Z","steps":["trace[1591690724] 'process raft request' (duration: 834.454183ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:06:43.307355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:06:42.472387Z","time spent":"834.932604ms","remote":"127.0.0.1:33268","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-02T18:06:45.458662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.250461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-02T18:06:45.458713Z","caller":"traceutil/trace.go:171","msg":"trace[2095957177] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:565; }","duration":"147.352504ms","start":"2026-02-02T18:06:45.311349Z","end":"2026-02-02T18:06:45.458702Z","steps":["trace[2095957177] 'range keys from in-memory index tree' (duration: 147.149649ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:06:45.458883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.996392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T18:06:45.458899Z","caller":"traceutil/trace.go:171","msg":"trace[2073711038] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:565; }","duration":"852.037163ms","start":"2026-02-02T18:06:44.606857Z","end":"2026-02-02T18:06:45.458894Z","steps":["trace[2073711038] 'range keys from in-memory index tree' (duration: 851.95786ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:06:45.458911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:06:44.606844Z","time spent":"852.063043ms","remote":"127.0.0.1:32992","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-02T18:06:45.458955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.543683204s","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-02T18:06:45.459001Z","caller":"traceutil/trace.go:171","msg":"trace[1614276991] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:565; }","duration":"1.543762976s","start":"2026-02-02T18:06:43.915225Z","end":"2026-02-02T18:06:45.458988Z","steps":["trace[1614276991] 'range keys from in-memory index tree' (duration: 1.543552091s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:06:45.459033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:06:43.915209Z","time spent":"1.543819237s","remote":"127.0.0.1:33268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-02T18:06:45.459278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"987.155638ms","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-02T18:06:45.459293Z","caller":"traceutil/trace.go:171","msg":"trace[73508972] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:565; }","duration":"987.191288ms","start":"2026-02-02T18:06:44.472097Z","end":"2026-02-02T18:06:45.459288Z","steps":["trace[73508972] 'range keys from in-memory index tree' (duration: 987.089847ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:06:45.459306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:06:44.472083Z","time spent":"987.22017ms","remote":"127.0.0.1:33268","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-02T18:06:45.459317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.022788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-02T18:06:45.459334Z","caller":"traceutil/trace.go:171","msg":"trace[1411484656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:565; }","duration":"148.063669ms","start":"2026-02-02T18:06:45.311266Z","end":"2026-02-02T18:06:45.45933Z","steps":["trace[1411484656] 'range keys from in-memory index tree' (duration: 147.972677ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T18:06:45.570943Z","caller":"traceutil/trace.go:171","msg":"trace[792679207] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"104.968367ms","start":"2026-02-02T18:06:45.465962Z","end":"2026-02-02T18:06:45.57093Z","steps":["trace[792679207] 'process raft request' (duration: 104.947266ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T18:06:45.571144Z","caller":"traceutil/trace.go:171","msg":"trace[1793349128] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"105.211992ms","start":"2026-02-02T18:06:45.465928Z","end":"2026-02-02T18:06:45.57114Z","steps":["trace[1793349128] 'process raft request' (duration: 103.811902ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T18:06:45.571203Z","caller":"traceutil/trace.go:171","msg":"trace[1796050161] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"105.260283ms","start":"2026-02-02T18:06:45.465939Z","end":"2026-02-02T18:06:45.571199Z","steps":["trace[1796050161] 'process raft request' (duration: 104.952526ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:57.193783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.941115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T18:07:57.194166Z","caller":"traceutil/trace.go:171","msg":"trace[382999601] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:929; }","duration":"583.364494ms","start":"2026-02-02T18:07:56.610678Z","end":"2026-02-02T18:07:57.194042Z","steps":["trace[382999601] 'range keys from in-memory index tree' (duration: 581.886184ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:57.194387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:56.610665Z","time spent":"583.706199ms","remote":"127.0.0.1:32992","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-02T18:07:57.198073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.614536ms","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-02T18:07:57.19822Z","caller":"traceutil/trace.go:171","msg":"trace[1373923175] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:929; }","duration":"680.763138ms","start":"2026-02-02T18:07:56.517444Z","end":"2026-02-02T18:07:57.198207Z","steps":["trace[1373923175] 'range keys from in-memory index tree' (duration: 680.420593ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:57.198308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:56.517387Z","time spent":"680.91262ms","remote":"127.0.0.1:33268","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":"info","ts":"2026-02-02T18:07:57.198679Z","caller":"traceutil/trace.go:171","msg":"trace[941990168] linearizableReadLoop","detail":"{readStateIndex:961; appliedIndex:960; }","duration":"144.146569ms","start":"2026-02-02T18:07:57.054523Z","end":"2026-02-02T18:07:57.19867Z","steps":["trace[941990168] 'read index received' (duration: 136.546256ms)","trace[941990168] 'applied index is now lower than readState.Index' (duration: 7.599223ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T18:07:57.19992Z","caller":"traceutil/trace.go:171","msg":"trace[452533455] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"203.387582ms","start":"2026-02-02T18:07:56.996523Z","end":"2026-02-02T18:07:57.19991Z","steps":["trace[452533455] 'process raft request' (duration: 194.698454ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:57.200128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.607569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T18:07:57.200454Z","caller":"traceutil/trace.go:171","msg":"trace[226125382] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:930; }","duration":"145.980883ms","start":"2026-02-02T18:07:57.054464Z","end":"2026-02-02T18:07:57.200444Z","steps":["trace[226125382] 'agreement among raft nodes before linearized reading' (duration: 145.63583ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:57.201155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.659105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fa3a0114-4f8e-42e8-81ce-0527e7136ec5\" ","response":"range_response_count:1 size:3534"} {"level":"info","ts":"2026-02-02T18:07:57.201682Z","caller":"traceutil/trace.go:171","msg":"trace[1455466362] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fa3a0114-4f8e-42e8-81ce-0527e7136ec5; range_end:; response_count:1; response_revision:929; }","duration":"639.242743ms","start":"2026-02-02T18:07:56.562428Z","end":"2026-02-02T18:07:57.201671Z","steps":["trace[1455466362] 'range keys from in-memory index tree' (duration: 629.945257ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:57.201806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:56.562398Z","time spent":"639.394896ms","remote":"127.0.0.1:33174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3557,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fa3a0114-4f8e-42e8-81ce-0527e7136ec5\" "} {"level":"warn","ts":"2026-02-02T18:07:58.625215Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11712345440177595691,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-02T18:07:58.677618Z","caller":"traceutil/trace.go:171","msg":"trace[338454229] linearizableReadLoop","detail":"{readStateIndex:968; appliedIndex:967; }","duration":"552.910717ms","start":"2026-02-02T18:07:58.124685Z","end":"2026-02-02T18:07:58.677596Z","steps":["trace[338454229] 'read index received' (duration: 552.768055ms)","trace[338454229] 'applied index is now lower than readState.Index' (duration: 141.682µs)"],"step_count":2} {"level":"info","ts":"2026-02-02T18:07:58.677722Z","caller":"traceutil/trace.go:171","msg":"trace[1223207501] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"553.769858ms","start":"2026-02-02T18:07:58.123943Z","end":"2026-02-02T18:07:58.677713Z","steps":["trace[1223207501] 'process raft request' (duration: 553.534185ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:58.677823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:58.123937Z","time spent":"553.811229ms","remote":"127.0.0.1:33268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T18:07:58.677935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.122775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fa3a0114-4f8e-42e8-81ce-0527e7136ec5\" ","response":"range_response_count:1 size:3534"} {"level":"info","ts":"2026-02-02T18:07:58.677999Z","caller":"traceutil/trace.go:171","msg":"trace[195584641] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fa3a0114-4f8e-42e8-81ce-0527e7136ec5; range_end:; response_count:1; response_revision:937; }","duration":"471.215847ms","start":"2026-02-02T18:07:58.206765Z","end":"2026-02-02T18:07:58.67798Z","steps":["trace[195584641] 'agreement among raft nodes before linearized reading' (duration: 471.045794ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:58.67804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:58.206752Z","time spent":"471.279787ms","remote":"127.0.0.1:33174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3557,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fa3a0114-4f8e-42e8-81ce-0527e7136ec5\" "} {"level":"warn","ts":"2026-02-02T18:07:58.678297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.354603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fa3a0114-4f8e-42e8-81ce-0527e7136ec5\" ","response":"range_response_count:1 size:3534"} {"level":"info","ts":"2026-02-02T18:07:58.678327Z","caller":"traceutil/trace.go:171","msg":"trace[1115444380] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fa3a0114-4f8e-42e8-81ce-0527e7136ec5; range_end:; response_count:1; response_revision:937; }","duration":"374.414713ms","start":"2026-02-02T18:07:58.303904Z","end":"2026-02-02T18:07:58.678318Z","steps":["trace[1115444380] 'agreement among raft nodes before linearized reading' (duration: 374.329952ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:58.678326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.641287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-02T18:07:58.678354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:58.303889Z","time spent":"374.459424ms","remote":"127.0.0.1:33174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3557,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fa3a0114-4f8e-42e8-81ce-0527e7136ec5\" "} {"level":"info","ts":"2026-02-02T18:07:58.678375Z","caller":"traceutil/trace.go:171","msg":"trace[1836201965] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:937; }","duration":"553.720667ms","start":"2026-02-02T18:07:58.124644Z","end":"2026-02-02T18:07:58.678365Z","steps":["trace[1836201965] 'agreement among raft nodes before linearized reading' (duration: 553.595966ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:58.678405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:58.124629Z","time spent":"553.769159ms","remote":"127.0.0.1:33268","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-02T18:08:02.939446Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11712345440177595748,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T18:08:03.100291Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.028907494s","expected-duration":"1s"} {"level":"info","ts":"2026-02-02T18:08:03.100606Z","caller":"traceutil/trace.go:171","msg":"trace[1557412860] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"1.029281459s","start":"2026-02-02T18:08:02.071304Z","end":"2026-02-02T18:08:03.100585Z","steps":["trace[1557412860] 'process raft request' (duration: 1.029130137s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:08:03.100743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:08:02.071291Z","time spent":"1.0293733s","remote":"127.0.0.1:33268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T18:08:03.796042Z","caller":"traceutil/trace.go:171","msg":"trace[605401714] linearizableReadLoop","detail":"{readStateIndex:992; appliedIndex:991; }","duration":"1.357524197s","start":"2026-02-02T18:08:02.438494Z","end":"2026-02-02T18:08:03.796019Z","steps":["trace[605401714] 'read index received' (duration: 662.232868ms)","trace[605401714] 'applied index is now lower than readState.Index' (duration: 695.290429ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T18:08:03.796501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.187318612s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-02T18:08:03.796558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.358055885s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-02T18:08:03.797767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.761157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-02T18:08:03.798156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.022097293s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-02T18:08:03.798221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.099242895s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-02T18:08:03.798688Z","caller":"traceutil/trace.go:171","msg":"trace[248394084] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"1.723840789s","start":"2026-02-02T18:08:02.072438Z","end":"2026-02-02T18:08:03.796279Z","steps":["trace[248394084] 'process raft request' (duration: 1.719946328s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:08:03.798775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:08:02.072431Z","time spent":"1.726285751s","remote":"127.0.0.1:33268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T18:08:03.798979Z","caller":"traceutil/trace.go:171","msg":"trace[821730641] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:960; }","duration":"1.187396412s","start":"2026-02-02T18:08:02.609132Z","end":"2026-02-02T18:08:03.796528Z","steps":["trace[821730641] 'agreement among raft nodes before linearized reading' (duration: 1.187318631s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:08:03.799012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:08:02.60912Z","time spent":"1.189884026s","remote":"127.0.0.1:32992","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-02T18:08:03.7992Z","caller":"traceutil/trace.go:171","msg":"trace[1651521628] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:960; }","duration":"1.358083395s","start":"2026-02-02T18:08:02.438489Z","end":"2026-02-02T18:08:03.796572Z","steps":["trace[1651521628] 'agreement among raft nodes before linearized reading' (duration: 1.358041784s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:08:03.799248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:08:02.438444Z","time spent":"1.36079367s","remote":"127.0.0.1:59148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-02-02T18:08:03.799308Z","caller":"traceutil/trace.go:171","msg":"trace[727409839] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:960; }","duration":"570.836558ms","start":"2026-02-02T18:08:03.226951Z","end":"2026-02-02T18:08:03.797788Z","steps":["trace[727409839] 'agreement among raft nodes before linearized reading' (duration: 569.622841ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:08:03.799335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:08:03.22693Z","time spent":"572.397358ms","remote":"127.0.0.1:33268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-02T18:08:03.799382Z","caller":"traceutil/trace.go:171","msg":"trace[1321347038] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:960; }","duration":"1.022145544s","start":"2026-02-02T18:08:02.776031Z","end":"2026-02-02T18:08:03.798176Z","steps":["trace[1321347038] 'agreement among raft nodes before linearized reading' (duration: 1.022017082s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:08:03.799402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:08:02.77602Z","time spent":"1.023377149s","remote":"127.0.0.1:33268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-02T18:08:03.799442Z","caller":"traceutil/trace.go:171","msg":"trace[603102670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:960; }","duration":"1.099288216s","start":"2026-02-02T18:08:02.698948Z","end":"2026-02-02T18:08:03.798236Z","steps":["trace[603102670] 'agreement among raft nodes before linearized reading' (duration: 1.099230765s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:08:03.79946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:08:02.698935Z","time spent":"1.100519642s","remote":"127.0.0.1:33268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-02T18:08:05.9877Z","caller":"traceutil/trace.go:171","msg":"trace[1661251890] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"176.664291ms","start":"2026-02-02T18:08:05.811003Z","end":"2026-02-02T18:08:05.987667Z","steps":["trace[1661251890] 'process raft request' (duration: 176.384428ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T18:08:05.989341Z","caller":"traceutil/trace.go:171","msg":"trace[722619583] linearizableReadLoop","detail":"{readStateIndex:1000; appliedIndex:1000; }","duration":"165.588796ms","start":"2026-02-02T18:08:05.823423Z","end":"2026-02-02T18:08:05.989011Z","steps":["trace[722619583] 'read index received' (duration: 165.581606ms)","trace[722619583] 'applied index is now lower than readState.Index' (duration: 5.42µs)"],"step_count":2} {"level":"warn","ts":"2026-02-02T18:08:05.989578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.124874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-02T18:08:05.990697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.151956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-02T18:08:05.990745Z","caller":"traceutil/trace.go:171","msg":"trace[2127797434] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:968; }","duration":"160.236366ms","start":"2026-02-02T18:08:05.830496Z","end":"2026-02-02T18:08:05.990733Z","steps":["trace[2127797434] 'agreement among raft nodes before linearized reading' (duration: 160.114505ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:08:05.991085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.428178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-02T18:08:05.991119Z","caller":"traceutil/trace.go:171","msg":"trace[997487300] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:968; }","duration":"160.467289ms","start":"2026-02-02T18:08:05.830642Z","end":"2026-02-02T18:08:05.991109Z","steps":["trace[997487300] 'agreement among raft nodes before linearized reading' (duration: 160.353417ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T18:08:05.991396Z","caller":"traceutil/trace.go:171","msg":"trace[1072732350] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:968; }","duration":"167.190678ms","start":"2026-02-02T18:08:05.823393Z","end":"2026-02-02T18:08:05.990584Z","steps":["trace[1072732350] 'agreement among raft nodes before linearized reading' (duration: 165.999462ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T18:08:12.314154Z","caller":"traceutil/trace.go:171","msg":"trace[1040447081] linearizableReadLoop","detail":"{readStateIndex:1027; appliedIndex:1026; }","duration":"269.095549ms","start":"2026-02-02T18:08:12.045041Z","end":"2026-02-02T18:08:12.314136Z","steps":["trace[1040447081] 'read index received' (duration: 268.920357ms)","trace[1040447081] 'applied index is now lower than readState.Index' (duration: 174.682µs)"],"step_count":2} {"level":"info","ts":"2026-02-02T18:08:12.314221Z","caller":"traceutil/trace.go:171","msg":"trace[557632667] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"275.049525ms","start":"2026-02-02T18:08:12.039165Z","end":"2026-02-02T18:08:12.314215Z","steps":["trace[557632667] 'process raft request' (duration: 274.845473ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:08:12.314362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.308452ms","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-02T18:08:12.314376Z","caller":"traceutil/trace.go:171","msg":"trace[126917106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:993; }","duration":"269.362512ms","start":"2026-02-02T18:08:12.045009Z","end":"2026-02-02T18:08:12.314372Z","steps":["trace[126917106] 'agreement among raft nodes before linearized reading' (duration: 269.288941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:08:12.314447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.357042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-02T18:08:12.314457Z","caller":"traceutil/trace.go:171","msg":"trace[326092196] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:993; }","duration":"269.381873ms","start":"2026-02-02T18:08:12.045073Z","end":"2026-02-02T18:08:12.314454Z","steps":["trace[326092196] 'agreement among raft nodes before linearized reading' (duration: 269.342943ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:08:12.314555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.507183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-02T18:08:12.314566Z","caller":"traceutil/trace.go:171","msg":"trace[1399493337] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:993; }","duration":"206.549203ms","start":"2026-02-02T18:08:12.108014Z","end":"2026-02-02T18:08:12.314563Z","steps":["trace[1399493337] 'agreement among raft nodes before linearized reading' (duration: 206.471922ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:08:12.314692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.503559ms","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-02T18:08:12.314705Z","caller":"traceutil/trace.go:171","msg":"trace[586363575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:993; }","duration":"267.53654ms","start":"2026-02-02T18:08:12.047165Z","end":"2026-02-02T18:08:12.314701Z","steps":["trace[586363575] 'agreement among raft nodes before linearized reading' (duration: 267.496869ms)"],"step_count":1}