{"level":"warn","ts":"2026-04-02T16:37:21.311334Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-02T16:37:21.311448Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.35: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.35:2380","--initial-cluster=instance=https://199.204.45.35:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.35:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.35:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-02T16:37:21.311846Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-02T16:37:21.312417Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-04-02T16:37:21.312449Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-02T16:37:21.31333Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"]} {"level":"info","ts":"2026-04-02T16:37:21.313467Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.35:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-02T16:37:21.318573Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.756772ms"} {"level":"info","ts":"2026-04-02T16:37:21.325526Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fb85913747d92cac","cluster-id":"636a84d5a3ba9c31"} {"level":"info","ts":"2026-04-02T16:37:21.325604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=()"} {"level":"info","ts":"2026-04-02T16:37:21.325625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 0"} {"level":"info","ts":"2026-04-02T16:37:21.325636Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fb85913747d92cac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-02T16:37:21.325678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 1"} {"level":"info","ts":"2026-04-02T16:37:21.325723Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"warn","ts":"2026-04-02T16:37:21.338723Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-02T16:37:21.340031Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-02T16:37:21.341459Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-02T16:37:21.345345Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fb85913747d92cac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-02T16:37:21.345492Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-02T16:37:21.345597Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-02T16:37:21.345616Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-02T16:37:21.345477Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fb85913747d92cac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-02T16:37:21.35089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"info","ts":"2026-04-02T16:37:21.351317Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","added-peer-id":"fb85913747d92cac","added-peer-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-04-02T16:37:21.352964Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-02T16:37:21.353431Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fb85913747d92cac","initial-advertise-peer-urls":["https://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-02T16:37:21.353499Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-02T16:37:21.353636Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-04-02T16:37:21.353659Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-04-02T16:37:21.425979Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac is starting a new election at term 1"} {"level":"info","ts":"2026-04-02T16:37:21.42609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became pre-candidate at term 1"} {"level":"info","ts":"2026-04-02T16:37:21.426154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgPreVoteResp from fb85913747d92cac at term 1"} {"level":"info","ts":"2026-04-02T16:37:21.426187Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became candidate at term 2"} {"level":"info","ts":"2026-04-02T16:37:21.426205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgVoteResp from fb85913747d92cac at term 2"} {"level":"info","ts":"2026-04-02T16:37:21.426229Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became leader at term 2"} {"level":"info","ts":"2026-04-02T16:37:21.42625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fb85913747d92cac elected leader fb85913747d92cac at term 2"} {"level":"info","ts":"2026-04-02T16:37:21.427442Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T16:37:21.428073Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fb85913747d92cac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.35:2379]}","request-path":"/0/members/fb85913747d92cac/attributes","cluster-id":"636a84d5a3ba9c31","publish-timeout":"7s"} {"level":"info","ts":"2026-04-02T16:37:21.428279Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T16:37:21.428355Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T16:37:21.428525Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T16:37:21.428676Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T16:37:21.428718Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T16:37:21.432079Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.35:2379"} {"level":"info","ts":"2026-04-02T16:37:21.432236Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-02T16:37:21.432264Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-02T16:37:21.435363Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-02T16:37:26.376352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.905096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3491"} {"level":"info","ts":"2026-04-02T16:37:26.376501Z","caller":"traceutil/trace.go:171","msg":"trace[453826458] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:206; }","duration":"507.124764ms","start":"2026-04-02T16:37:25.869345Z","end":"2026-04-02T16:37:26.37647Z","steps":["trace[453826458] 'range keys from in-memory index tree' (duration: 506.73157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:37:26.376571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:37:25.869313Z","time spent":"507.247499ms","remote":"127.0.0.1:54562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3514,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-02T16:37:27.616071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.551536ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T16:37:27.616237Z","caller":"traceutil/trace.go:171","msg":"trace[954795413] linearizableReadLoop","detail":"{readStateIndex:214; appliedIndex:213; }","duration":"1.196047078s","start":"2026-04-02T16:37:26.420167Z","end":"2026-04-02T16:37:27.616214Z","steps":["trace[954795413] 'read index received' (duration: 367.624634ms)","trace[954795413] 'applied index is now lower than readState.Index' (duration: 828.420244ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:37:27.616291Z","caller":"traceutil/trace.go:171","msg":"trace[2075886374] transaction","detail":"{read_only:false; response_revision:208; number_of_response:1; }","duration":"1.226713053s","start":"2026-04-02T16:37:26.389526Z","end":"2026-04-02T16:37:27.616239Z","steps":["trace[2075886374] 'process raft request' (duration: 398.330541ms)","trace[2075886374] 'compare' (duration: 826.598655ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:37:27.616391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.196216082s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-apfr4y\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:37:27.616473Z","caller":"traceutil/trace.go:171","msg":"trace[428935534] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-apfr4y; range_end:; response_count:0; response_revision:208; }","duration":"1.196385358s","start":"2026-04-02T16:37:26.420066Z","end":"2026-04-02T16:37:27.616452Z","steps":["trace[428935534] 'agreement among raft nodes before linearized reading' (duration: 1.196235943s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:37:27.616519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:37:26.420039Z","time spent":"1.196473281s","remote":"127.0.0.1:54480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/kube-system/bootstrap-token-apfr4y\" "} {"level":"warn","ts":"2026-04-02T16:37:27.616544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:37:26.389488Z","time spent":"1.226950831s","remote":"127.0.0.1:54574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3959,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T16:37:28.11721Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219120796533731068,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T16:37:28.61743Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219120796533731068,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T16:37:29.082643Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.919790381s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-02T16:37:30.053125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.098132ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T16:37:30.053316Z","caller":"traceutil/trace.go:171","msg":"trace[1344055405] linearizableReadLoop","detail":"{readStateIndex:216; appliedIndex:214; }","duration":"2.437018574s","start":"2026-04-02T16:37:27.616276Z","end":"2026-04-02T16:37:30.053294Z","steps":["trace[1344055405] 'read index received' (duration: 1.466697596s)","trace[1344055405] 'applied index is now lower than readState.Index' (duration: 970.319828ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:37:30.053544Z","caller":"traceutil/trace.go:171","msg":"trace[1252873862] transaction","detail":"{read_only:false; response_revision:210; number_of_response:1; }","duration":"2.432982095s","start":"2026-04-02T16:37:27.620549Z","end":"2026-04-02T16:37:30.053531Z","steps":["trace[1252873862] 'process raft request' (duration: 2.432670595s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:37:30.053918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:37:27.62052Z","time spent":"2.433359537s","remote":"127.0.0.1:54480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":525,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T16:37:30.053576Z","caller":"traceutil/trace.go:171","msg":"trace[2016649335] transaction","detail":"{read_only:false; response_revision:209; number_of_response:1; }","duration":"2.890810863s","start":"2026-04-02T16:37:27.162724Z","end":"2026-04-02T16:37:30.053535Z","steps":["trace[2016649335] 'process raft request' (duration: 1.920239506s)","trace[2016649335] 'compare' (duration: 969.538955ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:37:30.054203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:37:27.162697Z","time spent":"2.891385891s","remote":"127.0.0.1:54574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T16:37:30.05417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.281745798s","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-04-02T16:37:30.053621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.628915997s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:37:30.054343Z","caller":"traceutil/trace.go:171","msg":"trace[322175875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:210; }","duration":"2.281977706s","start":"2026-04-02T16:37:27.772305Z","end":"2026-04-02T16:37:30.054282Z","steps":["trace[322175875] 'agreement among raft nodes before linearized reading' (duration: 2.281640856s)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:37:30.054395Z","caller":"traceutil/trace.go:171","msg":"trace[964282082] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:210; }","duration":"2.629677821s","start":"2026-04-02T16:37:27.424629Z","end":"2026-04-02T16:37:30.054307Z","steps":["trace[964282082] 'agreement among raft nodes before linearized reading' (duration: 2.628917567s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:37:30.05452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:37:27.772277Z","time spent":"2.28211479s","remote":"127.0.0.1:54634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-02T16:37:30.05367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.29201527s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:37:30.054684Z","caller":"traceutil/trace.go:171","msg":"trace[1765162794] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:210; }","duration":"3.29301012s","start":"2026-04-02T16:37:26.761609Z","end":"2026-04-02T16:37:30.05462Z","steps":["trace[1765162794] 'agreement among raft nodes before linearized reading' (duration: 3.29202132s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:37:30.054536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:37:27.424599Z","time spent":"2.629903188s","remote":"127.0.0.1:54634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-02T16:37:30.055366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:37:26.761593Z","time spent":"3.293741175s","remote":"127.0.0.1:54634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T16:37:30.053673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.39582456s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:37:30.055543Z","caller":"traceutil/trace.go:171","msg":"trace[1822325533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:210; }","duration":"3.397699269s","start":"2026-04-02T16:37:26.657786Z","end":"2026-04-02T16:37:30.055485Z","steps":["trace[1822325533] 'agreement among raft nodes before linearized reading' (duration: 3.395851501s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:37:30.055639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:37:26.657761Z","time spent":"3.397865065s","remote":"127.0.0.1:54634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-02T16:37:30.230749Z","caller":"traceutil/trace.go:171","msg":"trace[190747137] transaction","detail":"{read_only:false; response_revision:215; number_of_response:1; }","duration":"168.577797ms","start":"2026-04-02T16:37:30.062143Z","end":"2026-04-02T16:37:30.230721Z","steps":["trace[190747137] 'process raft request' (duration: 168.514795ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:37:30.231274Z","caller":"traceutil/trace.go:171","msg":"trace[922445222] transaction","detail":"{read_only:false; response_revision:211; number_of_response:1; }","duration":"171.954264ms","start":"2026-04-02T16:37:30.059307Z","end":"2026-04-02T16:37:30.231262Z","steps":["trace[922445222] 'process raft request' (duration: 165.480591ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:37:30.231437Z","caller":"traceutil/trace.go:171","msg":"trace[473381823] transaction","detail":"{read_only:false; response_revision:212; number_of_response:1; }","duration":"172.009356ms","start":"2026-04-02T16:37:30.059418Z","end":"2026-04-02T16:37:30.231427Z","steps":["trace[473381823] 'process raft request' (duration: 171.019794ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:37:30.231563Z","caller":"traceutil/trace.go:171","msg":"trace[284149459] transaction","detail":"{read_only:false; response_revision:213; number_of_response:1; }","duration":"171.871072ms","start":"2026-04-02T16:37:30.059684Z","end":"2026-04-02T16:37:30.231555Z","steps":["trace[284149459] 'process raft request' (duration: 170.850679ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:37:30.231649Z","caller":"traceutil/trace.go:171","msg":"trace[607324056] transaction","detail":"{read_only:false; response_revision:214; number_of_response:1; }","duration":"171.426038ms","start":"2026-04-02T16:37:30.060215Z","end":"2026-04-02T16:37:30.231641Z","steps":["trace[607324056] 'process raft request' (duration: 170.363094ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:37:58.785404Z","caller":"traceutil/trace.go:171","msg":"trace[1054504037] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"371.685445ms","start":"2026-04-02T16:37:58.413691Z","end":"2026-04-02T16:37:58.785377Z","steps":["trace[1054504037] 'process raft request' (duration: 371.631233ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:37:58.785449Z","caller":"traceutil/trace.go:171","msg":"trace[2043063644] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"372.671789ms","start":"2026-04-02T16:37:58.412735Z","end":"2026-04-02T16:37:58.785407Z","steps":["trace[2043063644] 'process raft request' (duration: 298.103037ms)","trace[2043063644] 'compare' (duration: 74.354027ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:37:58.785802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:37:58.413671Z","time spent":"371.834778ms","remote":"127.0.0.1:54634","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-04-02T16:37:58.786294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:37:58.41271Z","time spent":"372.861574ms","remote":"127.0.0.1:54634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:38:15.343153Z","caller":"traceutil/trace.go:171","msg":"trace[934803954] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"199.925488ms","start":"2026-04-02T16:38:15.143195Z","end":"2026-04-02T16:38:15.343121Z","steps":["trace[934803954] 'process raft request' (duration: 199.750754ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:15.352436Z","caller":"traceutil/trace.go:171","msg":"trace[414192807] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"191.157302ms","start":"2026-04-02T16:38:15.161254Z","end":"2026-04-02T16:38:15.352411Z","steps":["trace[414192807] 'process raft request' (duration: 190.925467ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:16.241397Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219120796533731936,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T16:38:16.629664Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.083713269s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-02T16:38:16.630106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.19855435s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-8lm2c\" ","response":"range_response_count:1 size:4679"} {"level":"info","ts":"2026-04-02T16:38:16.630146Z","caller":"traceutil/trace.go:171","msg":"trace[848658424] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-8lm2c; range_end:; response_count:1; response_revision:657; }","duration":"1.198624902s","start":"2026-04-02T16:38:15.431509Z","end":"2026-04-02T16:38:16.630134Z","steps":["trace[848658424] 'range keys from in-memory index tree' (duration: 1.198463166s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:16.630188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:15.431494Z","time spent":"1.198686803s","remote":"127.0.0.1:54574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4702,"request content":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-8lm2c\" "} {"level":"info","ts":"2026-04-02T16:38:16.630456Z","caller":"traceutil/trace.go:171","msg":"trace[1012412606] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"1.183767509s","start":"2026-04-02T16:38:15.446682Z","end":"2026-04-02T16:38:16.630449Z","steps":["trace[1012412606] 'process raft request' (duration: 1.183155166s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:16.630551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:15.446664Z","time spent":"1.18382509s","remote":"127.0.0.1:54438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5153,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:38:16.641005Z","caller":"traceutil/trace.go:171","msg":"trace[442125276] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"1.116383005s","start":"2026-04-02T16:38:15.524595Z","end":"2026-04-02T16:38:16.640978Z","steps":["trace[442125276] 'process raft request' (duration: 1.116068268s)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:16.641045Z","caller":"traceutil/trace.go:171","msg":"trace[1671231624] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"1.098096858s","start":"2026-04-02T16:38:15.542926Z","end":"2026-04-02T16:38:16.641023Z","steps":["trace[1671231624] 'process raft request' (duration: 1.097900084s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:16.6412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:15.542908Z","time spent":"1.098224391s","remote":"127.0.0.1:54438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T16:38:16.641333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:15.524529Z","time spent":"1.116545259s","remote":"127.0.0.1:54438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T16:38:16.641779Z","caller":"traceutil/trace.go:171","msg":"trace[176831811] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"1.074553753s","start":"2026-04-02T16:38:15.56721Z","end":"2026-04-02T16:38:16.641764Z","steps":["trace[176831811] 'process raft request' (duration: 1.074175174s)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:16.641917Z","caller":"traceutil/trace.go:171","msg":"trace[1362226332] linearizableReadLoop","detail":"{readStateIndex:694; appliedIndex:690; }","duration":"901.599396ms","start":"2026-04-02T16:38:15.740298Z","end":"2026-04-02T16:38:16.641897Z","steps":["trace[1362226332] 'read index received' (duration: 889.63626ms)","trace[1362226332] 'applied index is now lower than readState.Index' (duration: 11.960726ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:38:16.64187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:15.567174Z","time spent":"1.074645745s","remote":"127.0.0.1:54438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:38:16.642076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"901.7625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:38:16.642123Z","caller":"traceutil/trace.go:171","msg":"trace[347819968] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:661; }","duration":"901.877793ms","start":"2026-04-02T16:38:15.740232Z","end":"2026-04-02T16:38:16.64211Z","steps":["trace[347819968] 'agreement among raft nodes before linearized reading' (duration: 901.75704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:16.642173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:15.740208Z","time spent":"901.955784ms","remote":"127.0.0.1:54438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-04-02T16:38:16.647561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.710138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T16:38:16.647561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.086849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:38:16.647649Z","caller":"traceutil/trace.go:171","msg":"trace[1962547101] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:663; }","duration":"707.206441ms","start":"2026-04-02T16:38:15.940424Z","end":"2026-04-02T16:38:16.647631Z","steps":["trace[1962547101] 'agreement among raft nodes before linearized reading' (duration: 707.025797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:16.647776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:15.940409Z","time spent":"707.318094ms","remote":"127.0.0.1:54438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"info","ts":"2026-04-02T16:38:16.647643Z","caller":"traceutil/trace.go:171","msg":"trace[996140632] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:663; }","duration":"306.79385ms","start":"2026-04-02T16:38:16.340827Z","end":"2026-04-02T16:38:16.647621Z","steps":["trace[996140632] 'agreement among raft nodes before linearized reading' (duration: 306.642777ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:16.647995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.312036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:38:16.648029Z","caller":"traceutil/trace.go:171","msg":"trace[583784809] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:663; }","duration":"508.382947ms","start":"2026-04-02T16:38:16.139636Z","end":"2026-04-02T16:38:16.648019Z","steps":["trace[583784809] 'agreement among raft nodes before linearized reading' (duration: 508.333506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:16.648052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:16.139607Z","time spent":"508.439258ms","remote":"127.0.0.1:54438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-04-02T16:38:16.647584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.69577ms","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-04-02T16:38:16.648199Z","caller":"traceutil/trace.go:171","msg":"trace[1191727340] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:663; }","duration":"123.330953ms","start":"2026-04-02T16:38:16.524855Z","end":"2026-04-02T16:38:16.648186Z","steps":["trace[1191727340] 'agreement among raft nodes before linearized reading' (duration: 122.612098ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:16.647942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.687257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-04-02T16:38:16.648282Z","caller":"traceutil/trace.go:171","msg":"trace[938126873] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:663; }","duration":"108.042435ms","start":"2026-04-02T16:38:16.540231Z","end":"2026-04-02T16:38:16.648273Z","steps":["trace[938126873] 'agreement among raft nodes before linearized reading' (duration: 107.627546ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:16.648154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.726574ms","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-04-02T16:38:16.648372Z","caller":"traceutil/trace.go:171","msg":"trace[869855002] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:663; }","duration":"128.959298ms","start":"2026-04-02T16:38:16.519395Z","end":"2026-04-02T16:38:16.648354Z","steps":["trace[869855002] 'agreement among raft nodes before linearized reading' (duration: 128.698732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:16.648142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:16.340773Z","time spent":"307.203809ms","remote":"127.0.0.1:54438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-04-02T16:38:19.041633Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219120796533731991,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T16:38:19.53088Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.786256526s","expected-duration":"1s"} {"level":"info","ts":"2026-04-02T16:38:19.531151Z","caller":"traceutil/trace.go:171","msg":"trace[1364749536] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"1.786660474s","start":"2026-04-02T16:38:17.744471Z","end":"2026-04-02T16:38:19.531131Z","steps":["trace[1364749536] 'process raft request' (duration: 1.786556392s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:19.53125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:17.744442Z","time spent":"1.786762946s","remote":"127.0.0.1:54438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T16:38:19.540799Z","caller":"traceutil/trace.go:171","msg":"trace[1282054761] linearizableReadLoop","detail":"{readStateIndex:716; appliedIndex:712; }","duration":"1.00024749s","start":"2026-04-02T16:38:18.540533Z","end":"2026-04-02T16:38:19.54078Z","steps":["trace[1282054761] 'read index received' (duration: 990.555999ms)","trace[1282054761] 'applied index is now lower than readState.Index' (duration: 9.690761ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:38:19.540785Z","caller":"traceutil/trace.go:171","msg":"trace[1672345656] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"1.589976498s","start":"2026-04-02T16:38:17.950773Z","end":"2026-04-02T16:38:19.540749Z","steps":["trace[1672345656] 'process raft request' (duration: 1.589703563s)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:19.540897Z","caller":"traceutil/trace.go:171","msg":"trace[1129471388] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"1.174471534s","start":"2026-04-02T16:38:18.366418Z","end":"2026-04-02T16:38:19.54089Z","steps":["trace[1129471388] 'process raft request' (duration: 1.174312021s)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:19.54088Z","caller":"traceutil/trace.go:171","msg":"trace[264950226] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"1.375413954s","start":"2026-04-02T16:38:18.165426Z","end":"2026-04-02T16:38:19.54084Z","steps":["trace[264950226] 'process raft request' (duration: 1.37519158s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:19.540947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:17.950753Z","time spent":"1.590123702s","remote":"127.0.0.1:54438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T16:38:19.540971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:18.36639Z","time spent":"1.174528226s","remote":"127.0.0.1:54438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T16:38:19.541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:18.165409Z","time spent":"1.375529266s","remote":"127.0.0.1:54438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T16:38:19.541193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.0006593s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-04-02T16:38:19.541216Z","caller":"traceutil/trace.go:171","msg":"trace[162323435] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:683; }","duration":"1.000717771s","start":"2026-04-02T16:38:18.540491Z","end":"2026-04-02T16:38:19.541209Z","steps":["trace[162323435] 'agreement among raft nodes before linearized reading' (duration: 1.000609598s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:19.541233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:18.540476Z","time spent":"1.000754012s","remote":"127.0.0.1:54438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10400,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-04-02T16:38:19.541309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.162642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-02T16:38:19.54132Z","caller":"traceutil/trace.go:171","msg":"trace[1425093717] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:683; }","duration":"648.199132ms","start":"2026-04-02T16:38:18.893117Z","end":"2026-04-02T16:38:19.541316Z","steps":["trace[1425093717] 'agreement among raft nodes before linearized reading' (duration: 648.158242ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:19.541331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:18.893103Z","time spent":"648.225444ms","remote":"127.0.0.1:54634","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":"warn","ts":"2026-04-02T16:38:19.541353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.154665ms","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-04-02T16:38:19.541391Z","caller":"traceutil/trace.go:171","msg":"trace[1259006754] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:683; }","duration":"861.244747ms","start":"2026-04-02T16:38:18.680135Z","end":"2026-04-02T16:38:19.541379Z","steps":["trace[1259006754] 'agreement among raft nodes before linearized reading' (duration: 861.100914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:19.54142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:18.680095Z","time spent":"861.319109ms","remote":"127.0.0.1:54634","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-04-02T16:38:19.54143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.200334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:38:19.541448Z","caller":"traceutil/trace.go:171","msg":"trace[230192327] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway; range_end:; response_count:0; response_revision:683; }","duration":"175.249015ms","start":"2026-04-02T16:38:19.366192Z","end":"2026-04-02T16:38:19.541441Z","steps":["trace[230192327] 'agreement among raft nodes before linearized reading' (duration: 175.220625ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:19.541526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.006199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:38:19.541538Z","caller":"traceutil/trace.go:171","msg":"trace[1308517276] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:683; }","duration":"447.04595ms","start":"2026-04-02T16:38:19.094488Z","end":"2026-04-02T16:38:19.541534Z","steps":["trace[1308517276] 'agreement among raft nodes before linearized reading' (duration: 447.02478ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:19.541549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:19.094446Z","time spent":"447.100801ms","remote":"127.0.0.1:54400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T16:38:19.541716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.60912ms","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-04-02T16:38:19.54174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.671924ms","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-04-02T16:38:19.541756Z","caller":"traceutil/trace.go:171","msg":"trace[520301514] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:683; }","duration":"860.705356ms","start":"2026-04-02T16:38:18.681045Z","end":"2026-04-02T16:38:19.54175Z","steps":["trace[520301514] 'agreement among raft nodes before linearized reading' (duration: 860.660105ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:19.541748Z","caller":"traceutil/trace.go:171","msg":"trace[473578089] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:683; }","duration":"658.669501ms","start":"2026-04-02T16:38:18.883069Z","end":"2026-04-02T16:38:19.541738Z","steps":["trace[473578089] 'agreement among raft nodes before linearized reading' (duration: 658.58023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:19.541767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:18.681034Z","time spent":"860.730516ms","remote":"127.0.0.1:54634","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-04-02T16:38:19.541782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:18.883057Z","time spent":"658.713612ms","remote":"127.0.0.1:54634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-02T16:38:19.541855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.514885ms","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-04-02T16:38:19.541873Z","caller":"traceutil/trace.go:171","msg":"trace[1943692915] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:683; }","duration":"751.562035ms","start":"2026-04-02T16:38:18.790304Z","end":"2026-04-02T16:38:19.541866Z","steps":["trace[1943692915] 'agreement among raft nodes before linearized reading' (duration: 751.521264ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:19.541885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:18.790285Z","time spent":"751.596897ms","remote":"127.0.0.1:54634","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-04-02T16:38:24.430107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.929505ms","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":"warn","ts":"2026-04-02T16:38:24.430296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.407763ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T16:38:24.430409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.114215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:38:24.430506Z","caller":"traceutil/trace.go:171","msg":"trace[1119407313] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:734; }","duration":"266.696469ms","start":"2026-04-02T16:38:24.16376Z","end":"2026-04-02T16:38:24.430457Z","steps":["trace[1119407313] 'range keys from in-memory index tree' (duration: 266.386622ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:24.430604Z","caller":"traceutil/trace.go:171","msg":"trace[1782250736] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:734; }","duration":"335.33838ms","start":"2026-04-02T16:38:24.095208Z","end":"2026-04-02T16:38:24.430546Z","steps":["trace[1782250736] 'range keys from in-memory index tree' (duration: 335.052314ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:24.430718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:24.095197Z","time spent":"335.502824ms","remote":"127.0.0.1:54400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T16:38:24.431239Z","caller":"traceutil/trace.go:171","msg":"trace[1194998884] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:734; }","duration":"347.12681ms","start":"2026-04-02T16:38:24.083097Z","end":"2026-04-02T16:38:24.430223Z","steps":["trace[1194998884] 'count revisions from in-memory index tree' (duration: 346.846384ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:24.431368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:24.083077Z","time spent":"348.220612ms","remote":"127.0.0.1:54784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"info","ts":"2026-04-02T16:38:24.600232Z","caller":"traceutil/trace.go:171","msg":"trace[339605319] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"156.706433ms","start":"2026-04-02T16:38:24.443506Z","end":"2026-04-02T16:38:24.600213Z","steps":["trace[339605319] 'process raft request' (duration: 156.587431ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:25.555768Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219120796533732119,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T16:38:25.707871Z","caller":"traceutil/trace.go:171","msg":"trace[1205456685] linearizableReadLoop","detail":"{readStateIndex:772; appliedIndex:771; }","duration":"652.668993ms","start":"2026-04-02T16:38:25.055184Z","end":"2026-04-02T16:38:25.707854Z","steps":["trace[1205456685] 'read index received' (duration: 630.037237ms)","trace[1205456685] 'applied index is now lower than readState.Index' (duration: 22.631075ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:38:25.707964Z","caller":"traceutil/trace.go:171","msg":"trace[1855303575] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"704.453342ms","start":"2026-04-02T16:38:25.003504Z","end":"2026-04-02T16:38:25.707957Z","steps":["trace[1855303575] 'process raft request' (duration: 681.790125ms)","trace[1855303575] 'compare' (duration: 22.33708ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:38:25.708165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:25.003479Z","time spent":"704.504773ms","remote":"127.0.0.1:54418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:38:25.708385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.655088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T16:38:25.708519Z","caller":"traceutil/trace.go:171","msg":"trace[166063364] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:737; }","duration":"492.855083ms","start":"2026-04-02T16:38:25.215642Z","end":"2026-04-02T16:38:25.708497Z","steps":["trace[166063364] 'agreement among raft nodes before linearized reading' (duration: 492.529096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:25.708661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.439588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T16:38:25.708736Z","caller":"traceutil/trace.go:171","msg":"trace[69977719] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:737; }","duration":"653.55218ms","start":"2026-04-02T16:38:25.055159Z","end":"2026-04-02T16:38:25.708711Z","steps":["trace[69977719] 'agreement among raft nodes before linearized reading' (duration: 653.434478ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:25.708661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:25.215623Z","time spent":"493.004466ms","remote":"127.0.0.1:54716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":14,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-04-02T16:38:25.708801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:25.055147Z","time spent":"653.640483ms","remote":"127.0.0.1:54900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-02T16:38:25.709264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.230863ms","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-04-02T16:38:25.709304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.474033ms","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-04-02T16:38:25.709385Z","caller":"traceutil/trace.go:171","msg":"trace[1435568050] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:737; }","duration":"140.584976ms","start":"2026-04-02T16:38:25.568787Z","end":"2026-04-02T16:38:25.709372Z","steps":["trace[1435568050] 'agreement among raft nodes before linearized reading' (duration: 140.435683ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:25.709356Z","caller":"traceutil/trace.go:171","msg":"trace[813107804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:737; }","duration":"136.339676ms","start":"2026-04-02T16:38:25.573004Z","end":"2026-04-02T16:38:25.709344Z","steps":["trace[813107804] 'agreement among raft nodes before linearized reading' (duration: 136.127592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:25.708428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.288554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-02T16:38:25.70973Z","caller":"traceutil/trace.go:171","msg":"trace[22453125] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:737; }","duration":"136.638442ms","start":"2026-04-02T16:38:25.57308Z","end":"2026-04-02T16:38:25.709718Z","steps":["trace[22453125] 'agreement among raft nodes before linearized reading' (duration: 135.10187ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:25.70994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.371551ms","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-04-02T16:38:25.709975Z","caller":"traceutil/trace.go:171","msg":"trace[1593615610] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:737; }","duration":"139.425422ms","start":"2026-04-02T16:38:25.570541Z","end":"2026-04-02T16:38:25.709966Z","steps":["trace[1593615610] 'agreement among raft nodes before linearized reading' (duration: 139.33068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:25.713021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.571778ms","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-04-02T16:38:25.713093Z","caller":"traceutil/trace.go:171","msg":"trace[647469628] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:737; }","duration":"133.793284ms","start":"2026-04-02T16:38:25.579282Z","end":"2026-04-02T16:38:25.713075Z","steps":["trace[647469628] 'agreement among raft nodes before linearized reading' (duration: 129.571374ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:25.865767Z","caller":"traceutil/trace.go:171","msg":"trace[1709481763] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"149.487963ms","start":"2026-04-02T16:38:25.716255Z","end":"2026-04-02T16:38:25.865743Z","steps":["trace[1709481763] 'process raft request' (duration: 149.408991ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:25.865784Z","caller":"traceutil/trace.go:171","msg":"trace[797631662] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"149.558634ms","start":"2026-04-02T16:38:25.716185Z","end":"2026-04-02T16:38:25.865744Z","steps":["trace[797631662] 'process raft request' (duration: 140.650877ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:25.866135Z","caller":"traceutil/trace.go:171","msg":"trace[1093177069] linearizableReadLoop","detail":"{readStateIndex:774; appliedIndex:772; }","duration":"149.88033ms","start":"2026-04-02T16:38:25.716231Z","end":"2026-04-02T16:38:25.866111Z","steps":["trace[1093177069] 'read index received' (duration: 3.119155ms)","trace[1093177069] 'applied index is now lower than readState.Index' (duration: 146.760185ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:38:25.86616Z","caller":"traceutil/trace.go:171","msg":"trace[252590719] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"149.479452ms","start":"2026-04-02T16:38:25.716665Z","end":"2026-04-02T16:38:25.866144Z","steps":["trace[252590719] 'process raft request' (duration: 149.333979ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:25.86616Z","caller":"traceutil/trace.go:171","msg":"trace[506643573] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"149.680757ms","start":"2026-04-02T16:38:25.716459Z","end":"2026-04-02T16:38:25.86614Z","steps":["trace[506643573] 'process raft request' (duration: 149.245408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:25.866372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.129456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-02T16:38:25.866409Z","caller":"traceutil/trace.go:171","msg":"trace[1712229808] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:742; }","duration":"150.31869ms","start":"2026-04-02T16:38:25.716078Z","end":"2026-04-02T16:38:25.866397Z","steps":["trace[1712229808] 'agreement among raft nodes before linearized reading' (duration: 150.167847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:28.370815Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219120796533732138,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T16:38:28.871002Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219120796533732138,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T16:38:28.990439Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.263269125s","expected-duration":"1s"} {"level":"info","ts":"2026-04-02T16:38:28.990959Z","caller":"traceutil/trace.go:171","msg":"trace[2004609199] linearizableReadLoop","detail":"{readStateIndex:778; appliedIndex:777; }","duration":"1.121026847s","start":"2026-04-02T16:38:27.869909Z","end":"2026-04-02T16:38:28.990935Z","steps":["trace[2004609199] 'read index received' (duration: 1.120720031s)","trace[2004609199] 'applied index is now lower than readState.Index' (duration: 305.816µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:38:28.991059Z","caller":"traceutil/trace.go:171","msg":"trace[1177717793] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"1.263918798s","start":"2026-04-02T16:38:27.727105Z","end":"2026-04-02T16:38:28.991024Z","steps":["trace[1177717793] 'process raft request' (duration: 1.263676983s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:28.991212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.239714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T16:38:28.991232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.12069112s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-02T16:38:28.991259Z","caller":"traceutil/trace.go:171","msg":"trace[2129372946] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:743; }","duration":"896.348297ms","start":"2026-04-02T16:38:28.094898Z","end":"2026-04-02T16:38:28.991246Z","steps":["trace[2129372946] 'agreement among raft nodes before linearized reading' (duration: 896.253665ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:28.991262Z","caller":"traceutil/trace.go:171","msg":"trace[1262604703] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:743; }","duration":"1.120752802s","start":"2026-04-02T16:38:27.8705Z","end":"2026-04-02T16:38:28.991253Z","steps":["trace[1262604703] 'agreement among raft nodes before linearized reading' (duration: 1.120630149s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:28.991279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.121349374s","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":"warn","ts":"2026-04-02T16:38:28.991291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.119995346s","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-04-02T16:38:28.991294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:27.870454Z","time spent":"1.120833334s","remote":"127.0.0.1:54634","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-04-02T16:38:28.99133Z","caller":"traceutil/trace.go:171","msg":"trace[576607873] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:743; }","duration":"1.121462876s","start":"2026-04-02T16:38:27.869855Z","end":"2026-04-02T16:38:28.991318Z","steps":["trace[576607873] 'agreement among raft nodes before linearized reading' (duration: 1.121270322s)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:28.991335Z","caller":"traceutil/trace.go:171","msg":"trace[1656767615] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:743; }","duration":"1.120069348s","start":"2026-04-02T16:38:27.871253Z","end":"2026-04-02T16:38:28.991322Z","steps":["trace[1656767615] 'agreement among raft nodes before linearized reading' (duration: 1.119921545s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:28.991366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:27.869828Z","time spent":"1.121529428s","remote":"127.0.0.1:54634","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-04-02T16:38:28.991366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:27.871243Z","time spent":"1.120114489s","remote":"127.0.0.1:54634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-02T16:38:28.991293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:28.094875Z","time spent":"896.412478ms","remote":"127.0.0.1:54400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T16:38:28.9914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.636789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T16:38:28.99149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.120751842s","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-04-02T16:38:28.99154Z","caller":"traceutil/trace.go:171","msg":"trace[179412251] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:743; }","duration":"583.795833ms","start":"2026-04-02T16:38:28.407694Z","end":"2026-04-02T16:38:28.991489Z","steps":["trace[179412251] 'agreement among raft nodes before linearized reading' (duration: 583.596639ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:28.991571Z","caller":"traceutil/trace.go:171","msg":"trace[107161293] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:743; }","duration":"1.120899245s","start":"2026-04-02T16:38:27.870654Z","end":"2026-04-02T16:38:28.991553Z","steps":["trace[107161293] 'agreement among raft nodes before linearized reading' (duration: 1.1206553s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:28.991622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:27.87062Z","time spent":"1.120993736s","remote":"127.0.0.1:54634","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-04-02T16:38:28.991624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:28.407675Z","time spent":"583.903215ms","remote":"127.0.0.1:54520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-04-02T16:38:28.991728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:27.727094Z","time spent":"1.264024021s","remote":"127.0.0.1:54634","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-04-02T16:38:29.124185Z","caller":"traceutil/trace.go:171","msg":"trace[1193639907] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"128.638977ms","start":"2026-04-02T16:38:28.995517Z","end":"2026-04-02T16:38:29.124156Z","steps":["trace[1193639907] 'process raft request' (duration: 75.201297ms)","trace[1193639907] 'compare' (duration: 53.324127ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:38:29.134102Z","caller":"traceutil/trace.go:171","msg":"trace[1455407163] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"137.479788ms","start":"2026-04-02T16:38:28.996591Z","end":"2026-04-02T16:38:29.134071Z","steps":["trace[1455407163] 'process raft request' (duration: 137.432497ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:29.134184Z","caller":"traceutil/trace.go:171","msg":"trace[996614035] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"137.851976ms","start":"2026-04-02T16:38:28.99631Z","end":"2026-04-02T16:38:29.134162Z","steps":["trace[996614035] 'process raft request' (duration: 137.666932ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:29.134132Z","caller":"traceutil/trace.go:171","msg":"trace[484475444] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"138.02115ms","start":"2026-04-02T16:38:28.996082Z","end":"2026-04-02T16:38:29.134103Z","steps":["trace[484475444] 'process raft request' (duration: 137.767705ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:31.586059Z","caller":"traceutil/trace.go:171","msg":"trace[1839748380] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"123.31026ms","start":"2026-04-02T16:38:31.462721Z","end":"2026-04-02T16:38:31.586032Z","steps":["trace[1839748380] 'process raft request' (duration: 123.155266ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:31.640957Z","caller":"traceutil/trace.go:171","msg":"trace[1315885553] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"167.304065ms","start":"2026-04-02T16:38:31.473619Z","end":"2026-04-02T16:38:31.640924Z","steps":["trace[1315885553] 'process raft request' (duration: 166.398656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:46.563591Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219120796533732455,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T16:38:47.063788Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219120796533732455,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T16:38:47.178237Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.613865421s","expected-duration":"1s"} {"level":"info","ts":"2026-04-02T16:38:47.178638Z","caller":"traceutil/trace.go:171","msg":"trace[967093139] linearizableReadLoop","detail":"{readStateIndex:905; appliedIndex:904; }","duration":"1.115612375s","start":"2026-04-02T16:38:46.063005Z","end":"2026-04-02T16:38:47.178618Z","steps":["trace[967093139] 'read index received' (duration: 1.115383291s)","trace[967093139] 'applied index is now lower than readState.Index' (duration: 228.354µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:38:47.178688Z","caller":"traceutil/trace.go:171","msg":"trace[406327559] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"1.614463752s","start":"2026-04-02T16:38:45.564204Z","end":"2026-04-02T16:38:47.178668Z","steps":["trace[406327559] 'process raft request' (duration: 1.614254988s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:47.178878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:45.564175Z","time spent":"1.614559274s","remote":"127.0.0.1:54574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4090,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:38:47.178879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.115775769s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:38:47.178969Z","caller":"traceutil/trace.go:171","msg":"trace[1621518790] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:864; }","duration":"1.115960541s","start":"2026-04-02T16:38:46.06296Z","end":"2026-04-02T16:38:47.178921Z","steps":["trace[1621518790] 'agreement among raft nodes before linearized reading' (duration: 1.115743437s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:47.178999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.445907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-02T16:38:47.179053Z","caller":"traceutil/trace.go:171","msg":"trace[1247572777] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:864; }","duration":"113.538249ms","start":"2026-04-02T16:38:47.0655Z","end":"2026-04-02T16:38:47.179039Z","steps":["trace[1247572777] 'agreement among raft nodes before linearized reading' (duration: 113.359175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:47.179072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:46.062939Z","time spent":"1.116076084s","remote":"127.0.0.1:54630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-04-02T16:38:47.178897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.085194719s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:38:47.179219Z","caller":"traceutil/trace.go:171","msg":"trace[952768595] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:864; }","duration":"1.085562506s","start":"2026-04-02T16:38:46.093632Z","end":"2026-04-02T16:38:47.179194Z","steps":["trace[952768595] 'agreement among raft nodes before linearized reading' (duration: 1.08518962s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:47.179351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:46.093614Z","time spent":"1.085718569s","remote":"127.0.0.1:54400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T16:38:47.535835Z","caller":"traceutil/trace.go:171","msg":"trace[728739337] linearizableReadLoop","detail":"{readStateIndex:906; appliedIndex:905; }","duration":"347.873604ms","start":"2026-04-02T16:38:47.187935Z","end":"2026-04-02T16:38:47.535808Z","steps":["trace[728739337] 'read index received' (duration: 305.188049ms)","trace[728739337] 'applied index is now lower than readState.Index' (duration: 42.683975ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:38:47.536084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.144159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:38:47.536151Z","caller":"traceutil/trace.go:171","msg":"trace[1483414815] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:865; }","duration":"348.261301ms","start":"2026-04-02T16:38:47.187874Z","end":"2026-04-02T16:38:47.536135Z","steps":["trace[1483414815] 'agreement among raft nodes before linearized reading' (duration: 348.078417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:47.536187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:47.187857Z","time spent":"348.322782ms","remote":"127.0.0.1:54400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T16:38:47.535991Z","caller":"traceutil/trace.go:171","msg":"trace[363286091] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"348.820082ms","start":"2026-04-02T16:38:47.187115Z","end":"2026-04-02T16:38:47.535935Z","steps":["trace[363286091] 'process raft request' (duration: 306.158358ms)","trace[363286091] 'compare' (duration: 42.42752ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:38:47.536393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:47.1871Z","time spent":"349.208709ms","remote":"127.0.0.1:54634","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-04-02T16:38:47.536542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.270767ms","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-04-02T16:38:47.536572Z","caller":"traceutil/trace.go:171","msg":"trace[634391205] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:865; }","duration":"334.324229ms","start":"2026-04-02T16:38:47.20224Z","end":"2026-04-02T16:38:47.536564Z","steps":["trace[634391205] 'agreement among raft nodes before linearized reading' (duration: 334.188736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:47.536602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:47.202228Z","time spent":"334.368459ms","remote":"127.0.0.1:54634","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":"warn","ts":"2026-04-02T16:38:47.536716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.662631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-02T16:38:47.536737Z","caller":"traceutil/trace.go:171","msg":"trace[1734393000] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:865; }","duration":"311.706342ms","start":"2026-04-02T16:38:47.225024Z","end":"2026-04-02T16:38:47.53673Z","steps":["trace[1734393000] 'agreement among raft nodes before linearized reading' (duration: 311.643711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:47.536754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:47.225012Z","time spent":"311.737172ms","remote":"127.0.0.1:54634","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-04-02T16:38:47.53684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.382058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-02T16:38:47.536863Z","caller":"traceutil/trace.go:171","msg":"trace[176290578] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:865; }","duration":"310.423898ms","start":"2026-04-02T16:38:47.226429Z","end":"2026-04-02T16:38:47.536852Z","steps":["trace[176290578] 'agreement among raft nodes before linearized reading' (duration: 310.373507ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:47.53689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:47.226414Z","time spent":"310.467599ms","remote":"127.0.0.1:54634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-02T16:38:47.537095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.873147ms","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-04-02T16:38:47.537177Z","caller":"traceutil/trace.go:171","msg":"trace[474446768] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:865; }","duration":"310.998269ms","start":"2026-04-02T16:38:47.22616Z","end":"2026-04-02T16:38:47.537158Z","steps":["trace[474446768] 'agreement among raft nodes before linearized reading' (duration: 310.347877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:47.538033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:38:47.226144Z","time spent":"311.867125ms","remote":"127.0.0.1:54634","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-04-02T16:38:49.458394Z","caller":"traceutil/trace.go:171","msg":"trace[1318292970] linearizableReadLoop","detail":"{readStateIndex:913; appliedIndex:912; }","duration":"191.499785ms","start":"2026-04-02T16:38:49.266873Z","end":"2026-04-02T16:38:49.458373Z","steps":["trace[1318292970] 'read index received' (duration: 191.317302ms)","trace[1318292970] 'applied index is now lower than readState.Index' (duration: 181.583µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:38:49.458746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.839562ms","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-04-02T16:38:49.45875Z","caller":"traceutil/trace.go:171","msg":"trace[1617232280] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"245.657838ms","start":"2026-04-02T16:38:49.213067Z","end":"2026-04-02T16:38:49.458724Z","steps":["trace[1617232280] 'process raft request' (duration: 245.185439ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:49.458803Z","caller":"traceutil/trace.go:171","msg":"trace[824225382] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:872; }","duration":"191.951664ms","start":"2026-04-02T16:38:49.266842Z","end":"2026-04-02T16:38:49.458794Z","steps":["trace[824225382] 'agreement among raft nodes before linearized reading' (duration: 191.775711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:49.83857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.21164ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T16:38:49.839037Z","caller":"traceutil/trace.go:171","msg":"trace[672573717] linearizableReadLoop","detail":"{readStateIndex:914; appliedIndex:913; }","duration":"283.758769ms","start":"2026-04-02T16:38:49.555254Z","end":"2026-04-02T16:38:49.839013Z","steps":["trace[672573717] 'read index received' (duration: 105.983998ms)","trace[672573717] 'applied index is now lower than readState.Index' (duration: 177.772311ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:38:49.839097Z","caller":"traceutil/trace.go:171","msg":"trace[1372362943] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"293.910409ms","start":"2026-04-02T16:38:49.545155Z","end":"2026-04-02T16:38:49.839065Z","steps":["trace[1372362943] 'process raft request' (duration: 116.066617ms)","trace[1372362943] 'compare' (duration: 176.625708ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:38:49.839376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.082824ms","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-04-02T16:38:49.839459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.904314ms","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-04-02T16:38:49.839487Z","caller":"traceutil/trace.go:171","msg":"trace[1077333480] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:873; }","duration":"284.308769ms","start":"2026-04-02T16:38:49.555154Z","end":"2026-04-02T16:38:49.839462Z","steps":["trace[1077333480] 'agreement among raft nodes before linearized reading' (duration: 283.990503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:49.839464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.213161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-02T16:38:49.839528Z","caller":"traceutil/trace.go:171","msg":"trace[64379263] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:873; }","duration":"282.014407ms","start":"2026-04-02T16:38:49.557493Z","end":"2026-04-02T16:38:49.839507Z","steps":["trace[64379263] 'agreement among raft nodes before linearized reading' (duration: 281.784983ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:38:49.839606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.494958ms","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-04-02T16:38:49.839551Z","caller":"traceutil/trace.go:171","msg":"trace[1400999575] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:873; }","duration":"281.330263ms","start":"2026-04-02T16:38:49.558209Z","end":"2026-04-02T16:38:49.839539Z","steps":["trace[1400999575] 'agreement among raft nodes before linearized reading' (duration: 281.17342ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:49.839764Z","caller":"traceutil/trace.go:171","msg":"trace[1282774518] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:873; }","duration":"280.6185ms","start":"2026-04-02T16:38:49.559081Z","end":"2026-04-02T16:38:49.8397Z","steps":["trace[1282774518] 'agreement among raft nodes before linearized reading' (duration: 280.282424ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:50.009074Z","caller":"traceutil/trace.go:171","msg":"trace[629449411] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"165.544462ms","start":"2026-04-02T16:38:49.843503Z","end":"2026-04-02T16:38:50.009048Z","steps":["trace[629449411] 'process raft request' (duration: 164.039923ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:50.009546Z","caller":"traceutil/trace.go:171","msg":"trace[1414443785] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"165.891287ms","start":"2026-04-02T16:38:49.843636Z","end":"2026-04-02T16:38:50.009528Z","steps":["trace[1414443785] 'process raft request' (duration: 165.258005ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:38:50.009771Z","caller":"traceutil/trace.go:171","msg":"trace[518267404] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"165.796816ms","start":"2026-04-02T16:38:49.843958Z","end":"2026-04-02T16:38:50.009754Z","steps":["trace[518267404] 'process raft request' (duration: 165.50506ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:39:40.425014Z","caller":"traceutil/trace.go:171","msg":"trace[1259166569] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"114.104709ms","start":"2026-04-02T16:39:40.310871Z","end":"2026-04-02T16:39:40.424976Z","steps":["trace[1259166569] 'process raft request' (duration: 100.580414ms)","trace[1259166569] 'compare' (duration: 13.25609ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:39:40.425054Z","caller":"traceutil/trace.go:171","msg":"trace[420516142] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"112.266631ms","start":"2026-04-02T16:39:40.312758Z","end":"2026-04-02T16:39:40.425024Z","steps":["trace[420516142] 'process raft request' (duration: 112.177789ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:39:40.429261Z","caller":"traceutil/trace.go:171","msg":"trace[1641297704] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"113.991697ms","start":"2026-04-02T16:39:40.31176Z","end":"2026-04-02T16:39:40.425752Z","steps":["trace[1641297704] 'process raft request' (duration: 113.110393ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:39:42.590236Z","caller":"traceutil/trace.go:171","msg":"trace[557367361] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"117.80623ms","start":"2026-04-02T16:39:42.472402Z","end":"2026-04-02T16:39:42.590209Z","steps":["trace[557367361] 'process raft request' (duration: 117.759499ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:39:42.590411Z","caller":"traceutil/trace.go:171","msg":"trace[794516808] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"119.06896ms","start":"2026-04-02T16:39:42.471304Z","end":"2026-04-02T16:39:42.590373Z","steps":["trace[794516808] 'process raft request' (duration: 110.396832ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:39:44.29026Z","caller":"traceutil/trace.go:171","msg":"trace[300596143] linearizableReadLoop","detail":"{readStateIndex:1235; appliedIndex:1234; }","duration":"120.662715ms","start":"2026-04-02T16:39:44.169582Z","end":"2026-04-02T16:39:44.290245Z","steps":["trace[300596143] 'read index received' (duration: 120.533573ms)","trace[300596143] 'applied index is now lower than readState.Index' (duration: 128.542µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:39:44.290303Z","caller":"traceutil/trace.go:171","msg":"trace[1973904944] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"128.813383ms","start":"2026-04-02T16:39:44.161474Z","end":"2026-04-02T16:39:44.290287Z","steps":["trace[1973904944] 'process raft request' (duration: 128.679091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:39:44.290342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.746977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:39:44.290365Z","caller":"traceutil/trace.go:171","msg":"trace[2137871691] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1182; }","duration":"120.818148ms","start":"2026-04-02T16:39:44.169539Z","end":"2026-04-02T16:39:44.290357Z","steps":["trace[2137871691] 'agreement among raft nodes before linearized reading' (duration: 120.764657ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:32.73677Z","caller":"traceutil/trace.go:171","msg":"trace[667933218] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"210.922043ms","start":"2026-04-02T16:40:32.525813Z","end":"2026-04-02T16:40:32.736735Z","steps":["trace[667933218] 'process raft request' (duration: 210.676139ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:40.927486Z","caller":"traceutil/trace.go:171","msg":"trace[1201362892] linearizableReadLoop","detail":"{readStateIndex:1472; appliedIndex:1471; }","duration":"148.964722ms","start":"2026-04-02T16:40:40.778506Z","end":"2026-04-02T16:40:40.927471Z","steps":["trace[1201362892] 'read index received' (duration: 148.915371ms)","trace[1201362892] 'applied index is now lower than readState.Index' (duration: 48.351µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:40:40.92775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.227476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-02T16:40:40.927829Z","caller":"traceutil/trace.go:171","msg":"trace[125096363] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1406; }","duration":"149.392288ms","start":"2026-04-02T16:40:40.778426Z","end":"2026-04-02T16:40:40.927819Z","steps":["trace[125096363] 'agreement among raft nodes before linearized reading' (duration: 149.160145ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:40.928378Z","caller":"traceutil/trace.go:171","msg":"trace[390438752] transaction","detail":"{read_only:false; response_revision:1406; number_of_response:1; }","duration":"151.482358ms","start":"2026-04-02T16:40:40.7761Z","end":"2026-04-02T16:40:40.927582Z","steps":["trace[390438752] 'process raft request' (duration: 151.264935ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:41:14.826847Z","caller":"traceutil/trace.go:171","msg":"trace[1157684418] linearizableReadLoop","detail":"{readStateIndex:1594; appliedIndex:1593; }","duration":"348.494317ms","start":"2026-04-02T16:41:14.478326Z","end":"2026-04-02T16:41:14.82682Z","steps":["trace[1157684418] 'read index received' (duration: 348.190102ms)","trace[1157684418] 'applied index is now lower than readState.Index' (duration: 303.015µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:41:14.826948Z","caller":"traceutil/trace.go:171","msg":"trace[508731073] transaction","detail":"{read_only:false; response_revision:1522; number_of_response:1; }","duration":"348.70389ms","start":"2026-04-02T16:41:14.478214Z","end":"2026-04-02T16:41:14.826918Z","steps":["trace[508731073] 'process raft request' (duration: 348.423436ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:41:14.827111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:41:14.478193Z","time spent":"348.834361ms","remote":"127.0.0.1:54634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:41:14.827362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.010194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-cb286\" ","response":"range_response_count:1 size:18521"} {"level":"info","ts":"2026-04-02T16:41:14.827418Z","caller":"traceutil/trace.go:171","msg":"trace[362858221] range","detail":"{range_begin:/registry/pods/kube-system/cilium-cb286; range_end:; response_count:1; response_revision:1522; }","duration":"349.090555ms","start":"2026-04-02T16:41:14.478311Z","end":"2026-04-02T16:41:14.827401Z","steps":["trace[362858221] 'agreement among raft nodes before linearized reading' (duration: 348.616688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:41:14.827455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:41:14.478303Z","time spent":"349.141605ms","remote":"127.0.0.1:54574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18544,"request content":"key:\"/registry/pods/kube-system/cilium-cb286\" "} {"level":"warn","ts":"2026-04-02T16:41:15.307656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.969526ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T16:41:15.308102Z","caller":"traceutil/trace.go:171","msg":"trace[451156235] transaction","detail":"{read_only:false; response_revision:1524; number_of_response:1; }","duration":"171.529184ms","start":"2026-04-02T16:41:15.136563Z","end":"2026-04-02T16:41:15.308092Z","steps":["trace[451156235] 'process raft request' (duration: 171.419032ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:41:15.308201Z","caller":"traceutil/trace.go:171","msg":"trace[2145344781] transaction","detail":"{read_only:false; response_revision:1523; number_of_response:1; }","duration":"174.447365ms","start":"2026-04-02T16:41:15.13372Z","end":"2026-04-02T16:41:15.308167Z","steps":["trace[2145344781] 'process raft request' (duration: 71.900571ms)","trace[2145344781] 'compare' (duration: 101.868074ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:41:15.308276Z","caller":"traceutil/trace.go:171","msg":"trace[5094156] transaction","detail":"{read_only:false; response_revision:1525; number_of_response:1; }","duration":"155.841014ms","start":"2026-04-02T16:41:15.152413Z","end":"2026-04-02T16:41:15.308254Z","steps":["trace[5094156] 'process raft request' (duration: 155.618341ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:41:15.308066Z","caller":"traceutil/trace.go:171","msg":"trace[436829839] linearizableReadLoop","detail":"{readStateIndex:1597; appliedIndex:1595; }","duration":"167.527658ms","start":"2026-04-02T16:41:15.140525Z","end":"2026-04-02T16:41:15.308052Z","steps":["trace[436829839] 'read index received' (duration: 65.106696ms)","trace[436829839] 'applied index is now lower than readState.Index' (duration: 102.420402ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:41:15.30852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.166558ms","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-04-02T16:41:15.308573Z","caller":"traceutil/trace.go:171","msg":"trace[86602937] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1525; }","duration":"134.249139ms","start":"2026-04-02T16:41:15.174315Z","end":"2026-04-02T16:41:15.308564Z","steps":["trace[86602937] 'agreement among raft nodes before linearized reading' (duration: 134.122637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:41:15.308719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.589012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-02T16:41:15.308798Z","caller":"traceutil/trace.go:171","msg":"trace[1937769691] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1525; }","duration":"131.704483ms","start":"2026-04-02T16:41:15.177076Z","end":"2026-04-02T16:41:15.308781Z","steps":["trace[1937769691] 'agreement among raft nodes before linearized reading' (duration: 131.51094ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:41:15.308802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.640013ms","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-04-02T16:41:15.308841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.510491ms","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-04-02T16:41:15.308869Z","caller":"traceutil/trace.go:171","msg":"trace[1096092654] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1525; }","duration":"131.559301ms","start":"2026-04-02T16:41:15.177301Z","end":"2026-04-02T16:41:15.30886Z","steps":["trace[1096092654] 'agreement among raft nodes before linearized reading' (duration: 131.148325ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:41:15.308871Z","caller":"traceutil/trace.go:171","msg":"trace[1144732839] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1525; }","duration":"131.739734ms","start":"2026-04-02T16:41:15.177112Z","end":"2026-04-02T16:41:15.308852Z","steps":["trace[1144732839] 'agreement among raft nodes before linearized reading' (duration: 131.44259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:41:15.30919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.657793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-cb286\" ","response":"range_response_count:1 size:18521"} {"level":"info","ts":"2026-04-02T16:41:15.30924Z","caller":"traceutil/trace.go:171","msg":"trace[2106168048] range","detail":"{range_begin:/registry/pods/kube-system/cilium-cb286; range_end:; response_count:1; response_revision:1525; }","duration":"168.723724ms","start":"2026-04-02T16:41:15.140509Z","end":"2026-04-02T16:41:15.309232Z","steps":["trace[2106168048] 'agreement among raft nodes before linearized reading' (duration: 167.770051ms)"],"step_count":1}