{"level":"warn","ts":"2026-02-24T10:43:21.619604Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-24T10:43:21.619731Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.113: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.113:2380","--initial-cluster=instance=https://199.204.45.113:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.113:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.113:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-24T10:43:21.619848Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-24T10:43:21.619861Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-02-24T10:43:21.619904Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-24T10:43:21.620623Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"]} {"level":"info","ts":"2026-02-24T10:43:21.620765Z","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.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.113:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-24T10:43:21.625033Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.994638ms"} {"level":"info","ts":"2026-02-24T10:43:21.63647Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a31e123290fcf0df","cluster-id":"37661d6ee45ed9e8"} {"level":"info","ts":"2026-02-24T10:43:21.636575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=()"} {"level":"info","ts":"2026-02-24T10:43:21.636638Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 0"} {"level":"info","ts":"2026-02-24T10:43:21.636657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a31e123290fcf0df [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-24T10:43:21.636673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 1"} {"level":"info","ts":"2026-02-24T10:43:21.636751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"warn","ts":"2026-02-24T10:43:21.645348Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-24T10:43:21.647183Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-24T10:43:21.648454Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-24T10:43:21.650732Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a31e123290fcf0df","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-24T10:43:21.651141Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-24T10:43:21.651332Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-24T10:43:21.651354Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-24T10:43:21.653758Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a31e123290fcf0df","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-24T10:43:21.654036Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"info","ts":"2026-02-24T10:43:21.654259Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","added-peer-id":"a31e123290fcf0df","added-peer-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-02-24T10:43:21.656616Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-24T10:43:21.656785Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-02-24T10:43:21.656832Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-02-24T10:43:21.656909Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a31e123290fcf0df","initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-24T10:43:21.656955Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-24T10:43:22.537491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df is starting a new election at term 1"} {"level":"info","ts":"2026-02-24T10:43:22.537557Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became pre-candidate at term 1"} {"level":"info","ts":"2026-02-24T10:43:22.537583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgPreVoteResp from a31e123290fcf0df at term 1"} {"level":"info","ts":"2026-02-24T10:43:22.537596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became candidate at term 2"} {"level":"info","ts":"2026-02-24T10:43:22.537609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgVoteResp from a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-02-24T10:43:22.537619Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became leader at term 2"} {"level":"info","ts":"2026-02-24T10:43:22.537628Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a31e123290fcf0df elected leader a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-02-24T10:43:22.538674Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T10:43:22.539322Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a31e123290fcf0df","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.113:2379]}","request-path":"/0/members/a31e123290fcf0df/attributes","cluster-id":"37661d6ee45ed9e8","publish-timeout":"7s"} {"level":"info","ts":"2026-02-24T10:43:22.539444Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T10:43:22.539501Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T10:43:22.541568Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T10:43:22.541676Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T10:43:22.541707Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T10:43:22.54181Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.113:2379"} {"level":"info","ts":"2026-02-24T10:43:22.542663Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-24T10:43:22.54491Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-24T10:43:22.544974Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-24T10:43:40.143901Z","caller":"traceutil/trace.go:171","msg":"trace[58527159] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"176.367242ms","start":"2026-02-24T10:43:39.967487Z","end":"2026-02-24T10:43:40.143854Z","steps":["trace[58527159] 'process raft request' (duration: 169.956707ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:43:52.333212Z","caller":"traceutil/trace.go:171","msg":"trace[656853824] linearizableReadLoop","detail":"{readStateIndex:523; appliedIndex:522; }","duration":"117.914027ms","start":"2026-02-24T10:43:52.21525Z","end":"2026-02-24T10:43:52.333164Z","steps":["trace[656853824] 'read index received' (duration: 117.743166ms)","trace[656853824] 'applied index is now lower than readState.Index' (duration: 169.711µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:43:52.333225Z","caller":"traceutil/trace.go:171","msg":"trace[765274565] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"255.520579ms","start":"2026-02-24T10:43:52.077676Z","end":"2026-02-24T10:43:52.333197Z","steps":["trace[765274565] 'process raft request' (duration: 255.343257ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:43:52.333402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.138699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-24T10:43:52.333534Z","caller":"traceutil/trace.go:171","msg":"trace[2135050975] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:505; }","duration":"118.355062ms","start":"2026-02-24T10:43:52.215165Z","end":"2026-02-24T10:43:52.33352Z","steps":["trace[2135050975] 'agreement among raft nodes before linearized reading' (duration: 118.12796ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:43:52.783308Z","caller":"traceutil/trace.go:171","msg":"trace[2040226669] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"440.557841ms","start":"2026-02-24T10:43:52.342712Z","end":"2026-02-24T10:43:52.783269Z","steps":["trace[2040226669] 'process raft request' (duration: 437.148719ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:43:52.783443Z","caller":"traceutil/trace.go:171","msg":"trace[268843496] linearizableReadLoop","detail":"{readStateIndex:524; appliedIndex:523; }","duration":"124.112095ms","start":"2026-02-24T10:43:52.659075Z","end":"2026-02-24T10:43:52.783187Z","steps":["trace[268843496] 'read index received' (duration: 120.861655ms)","trace[268843496] 'applied index is now lower than readState.Index' (duration: 3.2493ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:43:52.783561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.451248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-24T10:43:52.783654Z","caller":"traceutil/trace.go:171","msg":"trace[1847723491] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:506; }","duration":"124.597909ms","start":"2026-02-24T10:43:52.659025Z","end":"2026-02-24T10:43:52.783623Z","steps":["trace[1847723491] 'agreement among raft nodes before linearized reading' (duration: 124.340197ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:43:52.783943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:43:52.342682Z","time spent":"440.711252ms","remote":"127.0.0.1:38136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:43:56.090622Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356763627961690244,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T10:43:56.167089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:43:55.496531Z","time spent":"670.553422ms","remote":"127.0.0.1:37936","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-24T10:43:56.167151Z","caller":"traceutil/trace.go:171","msg":"trace[75207685] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:533; }","duration":"577.593691ms","start":"2026-02-24T10:43:55.58951Z","end":"2026-02-24T10:43:56.167104Z","steps":["trace[75207685] 'read index received' (duration: 577.56861ms)","trace[75207685] 'applied index is now lower than readState.Index' (duration: 19.601µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:43:56.167301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.617324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T10:43:56.167317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.841043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:43:56.167406Z","caller":"traceutil/trace.go:171","msg":"trace[1982634622] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:514; }","duration":"577.976944ms","start":"2026-02-24T10:43:55.589406Z","end":"2026-02-24T10:43:56.167382Z","steps":["trace[1982634622] 'agreement among raft nodes before linearized reading' (duration: 577.819553ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:43:56.167413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.20161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:43:56.167452Z","caller":"traceutil/trace.go:171","msg":"trace[654069407] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:514; }","duration":"136.283822ms","start":"2026-02-24T10:43:56.031158Z","end":"2026-02-24T10:43:56.167442Z","steps":["trace[654069407] 'agreement among raft nodes before linearized reading' (duration: 136.221181ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:43:56.167344Z","caller":"traceutil/trace.go:171","msg":"trace[1317526996] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:514; }","duration":"383.719266ms","start":"2026-02-24T10:43:55.783613Z","end":"2026-02-24T10:43:56.167332Z","steps":["trace[1317526996] 'agreement among raft nodes before linearized reading' (duration: 383.633515ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:43:56.167488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:43:55.783571Z","time spent":"383.911507ms","remote":"127.0.0.1:37888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T10:43:56.167453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:43:55.589392Z","time spent":"578.051475ms","remote":"127.0.0.1:37992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"info","ts":"2026-02-24T10:43:56.567274Z","caller":"traceutil/trace.go:171","msg":"trace[114746967] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:533; }","duration":"304.103789ms","start":"2026-02-24T10:43:56.263141Z","end":"2026-02-24T10:43:56.567245Z","steps":["trace[114746967] 'read index received' (duration: 277.331535ms)","trace[114746967] 'applied index is now lower than readState.Index' (duration: 26.771214ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:43:56.56729Z","caller":"traceutil/trace.go:171","msg":"trace[2093635791] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"397.699831ms","start":"2026-02-24T10:43:56.169562Z","end":"2026-02-24T10:43:56.567262Z","steps":["trace[2093635791] 'process raft request' (duration: 370.978918ms)","trace[2093635791] 'compare' (duration: 26.553752ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:43:56.56746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.296021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:43:56.567503Z","caller":"traceutil/trace.go:171","msg":"trace[1032224784] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:515; }","duration":"304.393411ms","start":"2026-02-24T10:43:56.2631Z","end":"2026-02-24T10:43:56.567493Z","steps":["trace[1032224784] 'agreement among raft nodes before linearized reading' (duration: 304.22042ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:43:56.567495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:43:56.169533Z","time spent":"397.877513ms","remote":"127.0.0.1:37936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:43:56.567531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:43:56.263083Z","time spent":"304.442532ms","remote":"127.0.0.1:37992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"warn","ts":"2026-02-24T10:43:56.567829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.749691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T10:43:56.567983Z","caller":"traceutil/trace.go:171","msg":"trace[636464557] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:515; }","duration":"218.951123ms","start":"2026-02-24T10:43:56.349002Z","end":"2026-02-24T10:43:56.567953Z","steps":["trace[636464557] 'agreement among raft nodes before linearized reading' (duration: 218.464779ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:43:57.040891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.218247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-24T10:43:57.040884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.747478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12727"} {"level":"info","ts":"2026-02-24T10:43:57.040974Z","caller":"traceutil/trace.go:171","msg":"trace[1735747015] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:540; }","duration":"127.368809ms","start":"2026-02-24T10:43:56.913586Z","end":"2026-02-24T10:43:57.040955Z","steps":["trace[1735747015] 'range keys from in-memory index tree' (duration: 127.035225ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:43:57.040995Z","caller":"traceutil/trace.go:171","msg":"trace[821903946] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:540; }","duration":"110.911669ms","start":"2026-02-24T10:43:56.930062Z","end":"2026-02-24T10:43:57.040974Z","steps":["trace[821903946] 'range keys from in-memory index tree' (duration: 110.640226ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:44:00.370038Z","caller":"traceutil/trace.go:171","msg":"trace[671665062] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"165.322557ms","start":"2026-02-24T10:44:00.204686Z","end":"2026-02-24T10:44:00.370009Z","steps":["trace[671665062] 'process raft request' (duration: 165.155875ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:00.84077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.605156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T10:44:00.840843Z","caller":"traceutil/trace.go:171","msg":"trace[936369044] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:550; }","duration":"252.740637ms","start":"2026-02-24T10:44:00.588088Z","end":"2026-02-24T10:44:00.840829Z","steps":["trace[936369044] 'range keys from in-memory index tree' (duration: 252.444455ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:01.193806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.989458ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-24T10:44:01.194622Z","caller":"traceutil/trace.go:171","msg":"trace[815896081] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:569; }","duration":"377.15446ms","start":"2026-02-24T10:44:00.817385Z","end":"2026-02-24T10:44:01.194539Z","steps":["trace[815896081] 'read index received' (duration: 7.773769ms)","trace[815896081] 'applied index is now lower than readState.Index' (duration: 369.375951ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:44:01.195094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.689085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-24T10:44:01.195228Z","caller":"traceutil/trace.go:171","msg":"trace[838025633] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:550; }","duration":"377.808237ms","start":"2026-02-24T10:44:00.817336Z","end":"2026-02-24T10:44:01.195144Z","steps":["trace[838025633] 'agreement among raft nodes before linearized reading' (duration: 377.557524ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:01.195332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:44:00.817309Z","time spent":"377.967098ms","remote":"127.0.0.1:38136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T10:44:01.196774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.35699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-24T10:44:01.196871Z","caller":"traceutil/trace.go:171","msg":"trace[572158336] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:551; }","duration":"304.479922ms","start":"2026-02-24T10:44:00.892342Z","end":"2026-02-24T10:44:01.196822Z","steps":["trace[572158336] 'agreement among raft nodes before linearized reading' (duration: 303.18943ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:01.197011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.32201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:44:01.197267Z","caller":"traceutil/trace.go:171","msg":"trace[1690817855] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:551; }","duration":"165.556552ms","start":"2026-02-24T10:44:01.031632Z","end":"2026-02-24T10:44:01.197189Z","steps":["trace[1690817855] 'agreement among raft nodes before linearized reading' (duration: 165.127948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:01.197266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:44:00.892317Z","time spent":"304.597942ms","remote":"127.0.0.1:38136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-24T10:44:01.199929Z","caller":"traceutil/trace.go:171","msg":"trace[655101079] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"353.012725ms","start":"2026-02-24T10:44:00.844954Z","end":"2026-02-24T10:44:01.197966Z","steps":["trace[655101079] 'process raft request' (duration: 349.943127ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:01.200096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:44:00.844936Z","time spent":"355.077873ms","remote":"127.0.0.1:38136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:44:01.202963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.519363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-24T10:44:01.203055Z","caller":"traceutil/trace.go:171","msg":"trace[480734435] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:551; }","duration":"145.681445ms","start":"2026-02-24T10:44:01.057346Z","end":"2026-02-24T10:44:01.203027Z","steps":["trace[480734435] 'agreement among raft nodes before linearized reading' (duration: 138.331339ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:01.970283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.386002ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:44:01.970492Z","caller":"traceutil/trace.go:171","msg":"trace[412768864] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"763.754729ms","start":"2026-02-24T10:44:01.206719Z","end":"2026-02-24T10:44:01.970474Z","steps":["trace[412768864] 'process raft request' (duration: 763.674228ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:01.970608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:44:01.206695Z","time spent":"763.873271ms","remote":"127.0.0.1:38136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:44:01.970754Z","caller":"traceutil/trace.go:171","msg":"trace[91799761] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"764.628487ms","start":"2026-02-24T10:44:01.206115Z","end":"2026-02-24T10:44:01.970743Z","steps":["trace[91799761] 'process raft request' (duration: 96.69248ms)","trace[91799761] 'compare' (duration: 667.258841ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:44:01.970807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:44:01.206092Z","time spent":"764.689528ms","remote":"127.0.0.1:38136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:44:07.53723Z","caller":"traceutil/trace.go:171","msg":"trace[1177760692] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"141.137592ms","start":"2026-02-24T10:44:07.396068Z","end":"2026-02-24T10:44:07.537205Z","steps":["trace[1177760692] 'process raft request' (duration: 140.98651ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:44:42.291581Z","caller":"traceutil/trace.go:171","msg":"trace[1251721033] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"107.020123ms","start":"2026-02-24T10:44:42.184534Z","end":"2026-02-24T10:44:42.291555Z","steps":["trace[1251721033] 'process raft request' (duration: 106.868728ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:45:11.877557Z","caller":"traceutil/trace.go:171","msg":"trace[696105307] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"183.595088ms","start":"2026-02-24T10:45:11.693935Z","end":"2026-02-24T10:45:11.87753Z","steps":["trace[696105307] 'process raft request' (duration: 183.424924ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:45:14.347238Z","caller":"traceutil/trace.go:171","msg":"trace[967647111] linearizableReadLoop","detail":"{readStateIndex:947; appliedIndex:946; }","duration":"464.483994ms","start":"2026-02-24T10:45:13.882712Z","end":"2026-02-24T10:45:14.347196Z","steps":["trace[967647111] 'read index received' (duration: 464.33518ms)","trace[967647111] 'applied index is now lower than readState.Index' (duration: 147.574µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:45:14.34728Z","caller":"traceutil/trace.go:171","msg":"trace[1930851908] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"710.878414ms","start":"2026-02-24T10:45:13.636368Z","end":"2026-02-24T10:45:14.347246Z","steps":["trace[1930851908] 'process raft request' (duration: 710.670459ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:14.347435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:45:13.636294Z","time spent":"711.058879ms","remote":"127.0.0.1:38136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:45:14.34755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.831552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-24T10:45:14.347633Z","caller":"traceutil/trace.go:171","msg":"trace[2074673008] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:911; }","duration":"464.949574ms","start":"2026-02-24T10:45:13.882654Z","end":"2026-02-24T10:45:14.347604Z","steps":["trace[2074673008] 'agreement among raft nodes before linearized reading' (duration: 464.688598ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:14.34767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:45:13.882626Z","time spent":"465.037677ms","remote":"127.0.0.1:38136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-24T10:45:27.579115Z","caller":"traceutil/trace.go:171","msg":"trace[496870638] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"103.840643ms","start":"2026-02-24T10:45:27.475251Z","end":"2026-02-24T10:45:27.579091Z","steps":["trace[496870638] 'process raft request' (duration: 103.68817ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:45:35.6835Z","caller":"traceutil/trace.go:171","msg":"trace[1927350121] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"101.436606ms","start":"2026-02-24T10:45:35.582035Z","end":"2026-02-24T10:45:35.683471Z","steps":["trace[1927350121] 'process raft request' (duration: 44.169285ms)","trace[1927350121] 'compare' (duration: 57.080677ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:45:36.674082Z","caller":"traceutil/trace.go:171","msg":"trace[1309879277] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"152.97096ms","start":"2026-02-24T10:45:36.52107Z","end":"2026-02-24T10:45:36.67404Z","steps":["trace[1309879277] 'process raft request' (duration: 92.889561ms)","trace[1309879277] 'compare' (duration: 59.857785ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:45:37.43177Z","caller":"traceutil/trace.go:171","msg":"trace[417092381] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"753.128853ms","start":"2026-02-24T10:45:36.67862Z","end":"2026-02-24T10:45:37.431749Z","steps":["trace[417092381] 'process raft request' (duration: 729.197045ms)","trace[417092381] 'compare' (duration: 23.558031ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:45:37.432499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:45:36.678598Z","time spent":"753.595093ms","remote":"127.0.0.1:38136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:45:37.438016Z","caller":"traceutil/trace.go:171","msg":"trace[1521021967] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"758.758626ms","start":"2026-02-24T10:45:36.679224Z","end":"2026-02-24T10:45:37.437983Z","steps":["trace[1521021967] 'process raft request' (duration: 758.525772ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:45:37.438134Z","caller":"traceutil/trace.go:171","msg":"trace[1526132264] linearizableReadLoop","detail":"{readStateIndex:1058; appliedIndex:1055; }","duration":"403.77222ms","start":"2026-02-24T10:45:37.034343Z","end":"2026-02-24T10:45:37.438115Z","steps":["trace[1526132264] 'read index received' (duration: 373.462123ms)","trace[1526132264] 'applied index is now lower than readState.Index' (duration: 30.309217ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:45:37.43836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.929583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:45:37.438396Z","caller":"traceutil/trace.go:171","msg":"trace[1821647414] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1016; }","duration":"404.099546ms","start":"2026-02-24T10:45:37.034286Z","end":"2026-02-24T10:45:37.438385Z","steps":["trace[1821647414] 'agreement among raft nodes before linearized reading' (duration: 403.952343ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:37.43838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:45:36.6792Z","time spent":"758.8995ms","remote":"127.0.0.1:38136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:45:37.43838Z","caller":"traceutil/trace.go:171","msg":"trace[1526237530] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"520.235982ms","start":"2026-02-24T10:45:36.918126Z","end":"2026-02-24T10:45:37.438362Z","steps":["trace[1526237530] 'process raft request' (duration: 519.795303ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:37.438499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:45:37.034263Z","time spent":"404.224239ms","remote":"127.0.0.1:37888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T10:45:37.438616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:45:36.918093Z","time spent":"520.432866ms","remote":"127.0.0.1:38136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:45:43.959708Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356763627961691496,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T10:45:44.460907Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356763627961691496,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T10:45:44.74836Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.292160903s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T10:45:44.748765Z","caller":"traceutil/trace.go:171","msg":"trace[109714781] linearizableReadLoop","detail":"{readStateIndex:1079; appliedIndex:1078; }","duration":"1.289134724s","start":"2026-02-24T10:45:43.459606Z","end":"2026-02-24T10:45:44.748741Z","steps":["trace[109714781] 'read index received' (duration: 1.288971731s)","trace[109714781] 'applied index is now lower than readState.Index' (duration: 161.783µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:45:44.748808Z","caller":"traceutil/trace.go:171","msg":"trace[324565854] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"1.292808265s","start":"2026-02-24T10:45:43.455971Z","end":"2026-02-24T10:45:44.74878Z","steps":["trace[324565854] 'process raft request' (duration: 1.292624052s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:44.748933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:45:43.455876Z","time spent":"1.292986749s","remote":"127.0.0.1:38136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:45:44.749003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.799095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-24T10:45:44.749045Z","caller":"traceutil/trace.go:171","msg":"trace[1867569321] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1035; }","duration":"937.891237ms","start":"2026-02-24T10:45:43.811143Z","end":"2026-02-24T10:45:44.749034Z","steps":["trace[1867569321] 'agreement among raft nodes before linearized reading' (duration: 937.746524ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:44.749088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.773589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T10:45:44.749085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:45:43.811121Z","time spent":"937.955298ms","remote":"127.0.0.1:38136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-24T10:45:44.749093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.082009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T10:45:44.749114Z","caller":"traceutil/trace.go:171","msg":"trace[1587684215] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1035; }","duration":"717.837759ms","start":"2026-02-24T10:45:44.031269Z","end":"2026-02-24T10:45:44.749106Z","steps":["trace[1587684215] 'agreement among raft nodes before linearized reading' (duration: 717.790168ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:44.749073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.57929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:45:44.749158Z","caller":"traceutil/trace.go:171","msg":"trace[1765430464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1035; }","duration":"191.184212ms","start":"2026-02-24T10:45:44.557944Z","end":"2026-02-24T10:45:44.749128Z","steps":["trace[1765430464] 'agreement among raft nodes before linearized reading' (duration: 190.994028ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:45:44.749201Z","caller":"traceutil/trace.go:171","msg":"trace[180840354] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1035; }","duration":"143.725492ms","start":"2026-02-24T10:45:44.605432Z","end":"2026-02-24T10:45:44.749158Z","steps":["trace[180840354] 'agreement among raft nodes before linearized reading' (duration: 143.488588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:44.749136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:45:44.031251Z","time spent":"717.882441ms","remote":"127.0.0.1:37888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T10:45:44.749379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.289769726s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-24T10:45:44.74969Z","caller":"traceutil/trace.go:171","msg":"trace[1201080350] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1035; }","duration":"1.289852118s","start":"2026-02-24T10:45:43.459556Z","end":"2026-02-24T10:45:44.749408Z","steps":["trace[1201080350] 'agreement among raft nodes before linearized reading' (duration: 1.289302808s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:44.749827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:45:43.459534Z","time spent":"1.290279817s","remote":"127.0.0.1:38136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-24T10:45:45.72971Z","caller":"traceutil/trace.go:171","msg":"trace[1052045722] linearizableReadLoop","detail":"{readStateIndex:1084; appliedIndex:1083; }","duration":"226.767018ms","start":"2026-02-24T10:45:45.502912Z","end":"2026-02-24T10:45:45.729679Z","steps":["trace[1052045722] 'read index received' (duration: 226.556584ms)","trace[1052045722] 'applied index is now lower than readState.Index' (duration: 209.024µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:45:45.729875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.947081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 ","response":"range_response_count:5 size:1780"} {"level":"info","ts":"2026-02-24T10:45:45.729937Z","caller":"traceutil/trace.go:171","msg":"trace[1504457063] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:5; response_revision:1039; }","duration":"227.107774ms","start":"2026-02-24T10:45:45.50281Z","end":"2026-02-24T10:45:45.729918Z","steps":["trace[1504457063] 'agreement among raft nodes before linearized reading' (duration: 226.963702ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:45.730318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.629159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cilium-operator\" ","response":"range_response_count:1 size:2379"} {"level":"info","ts":"2026-02-24T10:45:45.73038Z","caller":"traceutil/trace.go:171","msg":"trace[1859372466] range","detail":"{range_begin:/registry/clusterroles/cilium-operator; range_end:; response_count:1; response_revision:1039; }","duration":"160.73259ms","start":"2026-02-24T10:45:45.569634Z","end":"2026-02-24T10:45:45.730366Z","steps":["trace[1859372466] 'agreement among raft nodes before linearized reading' (duration: 160.589057ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:45.730642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.327049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cilium\" ","response":"range_response_count:1 size:1654"} {"level":"info","ts":"2026-02-24T10:45:45.730715Z","caller":"traceutil/trace.go:171","msg":"trace[1546481944] range","detail":"{range_begin:/registry/clusterroles/cilium; range_end:; response_count:1; response_revision:1039; }","duration":"164.429741ms","start":"2026-02-24T10:45:45.566268Z","end":"2026-02-24T10:45:45.730698Z","steps":["trace[1546481944] 'agreement among raft nodes before linearized reading' (duration: 163.679157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:46.714155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.213769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:1 size:3475"} {"level":"warn","ts":"2026-02-24T10:45:46.714184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.926952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cluster-admin\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-02-24T10:45:46.714225Z","caller":"traceutil/trace.go:171","msg":"trace[2027987767] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:1; response_revision:1040; }","duration":"837.28248ms","start":"2026-02-24T10:45:45.87692Z","end":"2026-02-24T10:45:46.714203Z","steps":["trace[2027987767] 'range keys from in-memory index tree' (duration: 837.152088ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:45:46.714254Z","caller":"traceutil/trace.go:171","msg":"trace[1228815236] range","detail":"{range_begin:/registry/clusterroles/cluster-admin; range_end:; response_count:1; response_revision:1040; }","duration":"839.045904ms","start":"2026-02-24T10:45:45.875191Z","end":"2026-02-24T10:45:46.714237Z","steps":["trace[1228815236] 'range keys from in-memory index tree' (duration: 838.821149ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:46.71427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:45:45.876913Z","time spent":"837.346961ms","remote":"127.0.0.1:38234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":3498,"request content":"key:\"/registry/clusterroles/edit\" "} {"level":"warn","ts":"2026-02-24T10:45:46.714292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:45:45.875174Z","time spent":"839.111445ms","remote":"127.0.0.1:38234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":576,"request content":"key:\"/registry/clusterroles/cluster-admin\" "} {"level":"warn","ts":"2026-02-24T10:45:46.714039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.185799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:45:46.715148Z","caller":"traceutil/trace.go:171","msg":"trace[721043290] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1040; }","duration":"684.310351ms","start":"2026-02-24T10:45:46.030779Z","end":"2026-02-24T10:45:46.715089Z","steps":["trace[721043290] 'range keys from in-memory index tree' (duration: 683.089108ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:46.715839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:45:46.030763Z","time spent":"685.044795ms","remote":"127.0.0.1:37892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T10:45:52.9724Z","caller":"traceutil/trace.go:171","msg":"trace[1847376023] linearizableReadLoop","detail":"{readStateIndex:1106; appliedIndex:1105; }","duration":"105.937587ms","start":"2026-02-24T10:45:52.866441Z","end":"2026-02-24T10:45:52.972378Z","steps":["trace[1847376023] 'read index received' (duration: 21.659576ms)","trace[1847376023] 'applied index is now lower than readState.Index' (duration: 84.277081ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:45:52.972527Z","caller":"traceutil/trace.go:171","msg":"trace[1614540531] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"106.276723ms","start":"2026-02-24T10:45:52.866221Z","end":"2026-02-24T10:45:52.972498Z","steps":["trace[1614540531] 'process raft request' (duration: 21.84862ms)","trace[1614540531] 'compare' (duration: 84.227459ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:45:52.972635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.165091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node-proxier\" ","response":"range_response_count:1 size:714"} {"level":"info","ts":"2026-02-24T10:45:52.972672Z","caller":"traceutil/trace.go:171","msg":"trace[136680360] range","detail":"{range_begin:/registry/clusterroles/system:node-proxier; range_end:; response_count:1; response_revision:1060; }","duration":"106.238102ms","start":"2026-02-24T10:45:52.866423Z","end":"2026-02-24T10:45:52.972662Z","steps":["trace[136680360] 'agreement among raft nodes before linearized reading' (duration: 106.074819ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:52.9746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.856046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:ephemeral-volume-controller\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-02-24T10:45:52.974581Z","caller":"traceutil/trace.go:171","msg":"trace[230746580] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"107.939913ms","start":"2026-02-24T10:45:52.866585Z","end":"2026-02-24T10:45:52.974525Z","steps":["trace[230746580] 'process raft request' (duration: 107.660558ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:45:52.974601Z","caller":"traceutil/trace.go:171","msg":"trace[1004829661] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"107.953844ms","start":"2026-02-24T10:45:52.866627Z","end":"2026-02-24T10:45:52.974581Z","steps":["trace[1004829661] 'process raft request' (duration: 107.73349ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:45:52.974681Z","caller":"traceutil/trace.go:171","msg":"trace[866923102] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:ephemeral-volume-controller; range_end:; response_count:1; response_revision:1062; }","duration":"105.994918ms","start":"2026-02-24T10:45:52.868665Z","end":"2026-02-24T10:45:52.97466Z","steps":["trace[866923102] 'agreement among raft nodes before linearized reading' (duration: 105.785034ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:45:55.125732Z","caller":"traceutil/trace.go:171","msg":"trace[933795812] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"145.205033ms","start":"2026-02-24T10:45:54.9805Z","end":"2026-02-24T10:45:55.125705Z","steps":["trace[933795812] 'process raft request' (duration: 115.279215ms)","trace[933795812] 'compare' (duration: 29.752055ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:45:55.125888Z","caller":"traceutil/trace.go:171","msg":"trace[1287417886] linearizableReadLoop","detail":"{readStateIndex:1113; appliedIndex:1111; }","duration":"101.677638ms","start":"2026-02-24T10:45:55.024185Z","end":"2026-02-24T10:45:55.125862Z","steps":["trace[1287417886] 'read index received' (duration: 71.542916ms)","trace[1287417886] 'applied index is now lower than readState.Index' (duration: 30.133562ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:45:55.125977Z","caller":"traceutil/trace.go:171","msg":"trace[1591056923] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"144.520151ms","start":"2026-02-24T10:45:54.981392Z","end":"2026-02-24T10:45:55.125913Z","steps":["trace[1591056923] 'process raft request' (duration: 144.271476ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:55.126093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.857592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:coredns\" ","response":"range_response_count:1 size:415"} {"level":"info","ts":"2026-02-24T10:45:55.126143Z","caller":"traceutil/trace.go:171","msg":"trace[1561612083] range","detail":"{range_begin:/registry/clusterrolebindings/system:coredns; range_end:; response_count:1; response_revision:1067; }","duration":"101.975784ms","start":"2026-02-24T10:45:55.024155Z","end":"2026-02-24T10:45:55.12613Z","steps":["trace[1561612083] 'agreement among raft nodes before linearized reading' (duration: 101.831531ms)"],"step_count":1}