{"level":"warn","ts":"2026-03-04T06:16:26.926611Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-04T06:16:26.926726Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.70: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.70:2380","--initial-cluster=instance=https://199.204.45.70:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.70:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.70: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-03-04T06:16:26.926812Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-04T06:16:26.926827Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-03-04T06:16:26.926848Z","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-03-04T06:16:26.927552Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"]} {"level":"info","ts":"2026-03-04T06:16:26.927633Z","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.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.70: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-03-04T06:16:26.934145Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.206846ms"} {"level":"info","ts":"2026-03-04T06:16:26.942284Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f8844a9a4263041d","cluster-id":"4749b7c221ae0648"} {"level":"info","ts":"2026-03-04T06:16:26.942364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=()"} {"level":"info","ts":"2026-03-04T06:16:26.94239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 0"} {"level":"info","ts":"2026-03-04T06:16:26.942403Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f8844a9a4263041d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-04T06:16:26.942416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 1"} {"level":"info","ts":"2026-03-04T06:16:26.942452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"warn","ts":"2026-03-04T06:16:26.948693Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-04T06:16:26.953178Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-04T06:16:26.954445Z","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-03-04T06:16:26.956622Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f8844a9a4263041d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-04T06:16:26.956724Z","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-03-04T06:16:26.956799Z","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-03-04T06:16:26.956811Z","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-03-04T06:16:26.956974Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f8844a9a4263041d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-04T06:16:26.957766Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"info","ts":"2026-03-04T06:16:26.957907Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","added-peer-id":"f8844a9a4263041d","added-peer-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-03-04T06:16:26.959462Z","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-03-04T06:16:26.959564Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-03-04T06:16:26.959588Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-03-04T06:16:26.959712Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f8844a9a4263041d","initial-advertise-peer-urls":["https://199.204.45.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-04T06:16:26.959759Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-04T06:16:27.242664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d is starting a new election at term 1"} {"level":"info","ts":"2026-03-04T06:16:27.24274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became pre-candidate at term 1"} {"level":"info","ts":"2026-03-04T06:16:27.242773Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgPreVoteResp from f8844a9a4263041d at term 1"} {"level":"info","ts":"2026-03-04T06:16:27.242796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became candidate at term 2"} {"level":"info","ts":"2026-03-04T06:16:27.242808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgVoteResp from f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-03-04T06:16:27.24283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became leader at term 2"} {"level":"info","ts":"2026-03-04T06:16:27.242858Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f8844a9a4263041d elected leader f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-03-04T06:16:27.243815Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-04T06:16:27.244567Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f8844a9a4263041d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.70:2379]}","request-path":"/0/members/f8844a9a4263041d/attributes","cluster-id":"4749b7c221ae0648","publish-timeout":"7s"} {"level":"info","ts":"2026-03-04T06:16:27.244572Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-04T06:16:27.24466Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-04T06:16:27.244847Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-04T06:16:27.244911Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-04T06:16:27.245063Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","cluster-version":"3.5"} {"level":"info","ts":"2026-03-04T06:16:27.245193Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-04T06:16:27.245233Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-04T06:16:27.251999Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.70:2379"} {"level":"info","ts":"2026-03-04T06:16:27.252275Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-04T06:16:55.814647Z","caller":"traceutil/trace.go:171","msg":"trace[1853464677] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"169.439038ms","start":"2026-03-04T06:16:55.645185Z","end":"2026-03-04T06:16:55.814624Z","steps":["trace[1853464677] 'process raft request' (duration: 169.051409ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T06:16:56.021306Z","caller":"traceutil/trace.go:171","msg":"trace[1473293875] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"202.537297ms","start":"2026-03-04T06:16:55.818748Z","end":"2026-03-04T06:16:56.021285Z","steps":["trace[1473293875] 'process raft request' (duration: 202.410924ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T06:16:58.638347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.544192ms","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-03-04T06:16:58.638514Z","caller":"traceutil/trace.go:171","msg":"trace[1564882968] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:468; }","duration":"215.790539ms","start":"2026-03-04T06:16:58.422693Z","end":"2026-03-04T06:16:58.638484Z","steps":["trace[1564882968] 'range keys from in-memory index tree' (duration: 215.266837ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T06:17:02.282911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.162027ms","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-03-04T06:17:02.282999Z","caller":"traceutil/trace.go:171","msg":"trace[394036976] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:480; }","duration":"182.338831ms","start":"2026-03-04T06:17:02.10064Z","end":"2026-03-04T06:17:02.282979Z","steps":["trace[394036976] 'range keys from in-memory index tree' (duration: 181.969852ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T06:17:02.283133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.818693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-tlc42\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-03-04T06:17:02.283269Z","caller":"traceutil/trace.go:171","msg":"trace[2123584651] range","detail":"{range_begin:/registry/pods/kube-system/cilium-tlc42; range_end:; response_count:1; response_revision:480; }","duration":"135.044879ms","start":"2026-03-04T06:17:02.148198Z","end":"2026-03-04T06:17:02.283243Z","steps":["trace[2123584651] 'range keys from in-memory index tree' (duration: 134.549826ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T06:17:02.54753Z","caller":"traceutil/trace.go:171","msg":"trace[882236915] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"260.208683ms","start":"2026-03-04T06:17:02.287289Z","end":"2026-03-04T06:17:02.547498Z","steps":["trace[882236915] 'process raft request' (duration: 260.027419ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T06:17:02.800388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.559623ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T06:17:02.800667Z","caller":"traceutil/trace.go:171","msg":"trace[1809658467] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"507.228596ms","start":"2026-03-04T06:17:02.293405Z","end":"2026-03-04T06:17:02.800633Z","steps":["trace[1809658467] 'process raft request' (duration: 375.589358ms)","trace[1809658467] 'compare' (duration: 130.366638ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T06:17:02.800757Z","caller":"traceutil/trace.go:171","msg":"trace[1885313188] linearizableReadLoop","detail":"{readStateIndex:504; appliedIndex:501; }","duration":"130.930591ms","start":"2026-03-04T06:17:02.669728Z","end":"2026-03-04T06:17:02.800658Z","steps":["trace[1885313188] 'read index received' (duration: 53.231µs)","trace[1885313188] 'applied index is now lower than readState.Index' (duration: 130.8759ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T06:17:02.800995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T06:17:02.293377Z","time spent":"507.3708ms","remote":"127.0.0.1:37068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T06:17:02.800845Z","caller":"traceutil/trace.go:171","msg":"trace[2087588822] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"310.778364ms","start":"2026-03-04T06:17:02.490035Z","end":"2026-03-04T06:17:02.800814Z","steps":["trace[2087588822] 'process raft request' (duration: 310.479967ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T06:17:02.801267Z","caller":"traceutil/trace.go:171","msg":"trace[1112730043] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"303.475401ms","start":"2026-03-04T06:17:02.497758Z","end":"2026-03-04T06:17:02.801234Z","steps":["trace[1112730043] 'process raft request' (duration: 302.839247ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T06:17:02.801288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T06:17:02.490011Z","time spent":"311.231634ms","remote":"127.0.0.1:37056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4744,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T06:17:02.801377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T06:17:02.497731Z","time spent":"303.596265ms","remote":"127.0.0.1:37124","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-03-04T06:17:02.801828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.082539ms","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-03-04T06:17:02.802136Z","caller":"traceutil/trace.go:171","msg":"trace[1322818723] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:484; }","duration":"132.452047ms","start":"2026-03-04T06:17:02.669661Z","end":"2026-03-04T06:17:02.802113Z","steps":["trace[1322818723] 'agreement among raft nodes before linearized reading' (duration: 131.378002ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T06:17:03.604884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.572113ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T06:17:03.605029Z","caller":"traceutil/trace.go:171","msg":"trace[1664074744] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"796.507125ms","start":"2026-03-04T06:17:02.808495Z","end":"2026-03-04T06:17:03.605002Z","steps":["trace[1664074744] 'process raft request' (duration: 529.333877ms)","trace[1664074744] 'compare' (duration: 266.42126ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T06:17:03.605134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T06:17:02.808466Z","time spent":"796.605286ms","remote":"127.0.0.1:37124","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-03-04T06:17:03.918282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.143493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T06:17:03.918382Z","caller":"traceutil/trace.go:171","msg":"trace[742245011] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:486; }","duration":"184.297967ms","start":"2026-03-04T06:17:03.734066Z","end":"2026-03-04T06:17:03.918364Z","steps":["trace[742245011] 'range keys from in-memory index tree' (duration: 184.01458ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T06:17:05.483268Z","caller":"traceutil/trace.go:171","msg":"trace[1102476691] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"121.874578ms","start":"2026-03-04T06:17:05.361376Z","end":"2026-03-04T06:17:05.483251Z","steps":["trace[1102476691] 'process raft request' (duration: 121.771676ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T06:17:22.826374Z","caller":"traceutil/trace.go:171","msg":"trace[1797132035] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"183.102079ms","start":"2026-03-04T06:17:22.643238Z","end":"2026-03-04T06:17:22.82634Z","steps":["trace[1797132035] 'process raft request' (duration: 182.937345ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T06:17:23.983577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.743348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-03-04T06:17:23.983683Z","caller":"traceutil/trace.go:171","msg":"trace[188868884] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:673; }","duration":"708.923154ms","start":"2026-03-04T06:17:23.274738Z","end":"2026-03-04T06:17:23.983661Z","steps":["trace[188868884] 'range keys from in-memory index tree' (duration: 708.528674ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T06:17:23.983742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T06:17:23.274716Z","time spent":"709.015975ms","remote":"127.0.0.1:37124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-04T06:17:23.983713Z","caller":"traceutil/trace.go:171","msg":"trace[1123768090] linearizableReadLoop","detail":"{readStateIndex:700; appliedIndex:699; }","duration":"293.606459ms","start":"2026-03-04T06:17:23.690065Z","end":"2026-03-04T06:17:23.983672Z","steps":["trace[1123768090] 'read index received' (duration: 285.302403ms)","trace[1123768090] 'applied index is now lower than readState.Index' (duration: 8.302876ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T06:17:23.983826Z","caller":"traceutil/trace.go:171","msg":"trace[686507479] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"676.133151ms","start":"2026-03-04T06:17:23.307646Z","end":"2026-03-04T06:17:23.983779Z","steps":["trace[686507479] 'process raft request' (duration: 667.778615ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T06:17:23.983929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.286596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T06:17:23.983972Z","caller":"traceutil/trace.go:171","msg":"trace[336818383] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:674; }","duration":"249.372649ms","start":"2026-03-04T06:17:23.734587Z","end":"2026-03-04T06:17:23.98396Z","steps":["trace[336818383] 'agreement among raft nodes before linearized reading' (duration: 249.248706ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T06:17:23.983991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T06:17:23.307611Z","time spent":"676.289975ms","remote":"127.0.0.1:37124","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-03-04T06:17:23.984063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.975288ms","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-03-04T06:17:23.984166Z","caller":"traceutil/trace.go:171","msg":"trace[753025506] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:674; }","duration":"294.145412ms","start":"2026-03-04T06:17:23.689994Z","end":"2026-03-04T06:17:23.984139Z","steps":["trace[753025506] 'agreement among raft nodes before linearized reading' (duration: 293.793784ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T06:17:50.627272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.567713ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T06:17:50.627514Z","caller":"traceutil/trace.go:171","msg":"trace[950094825] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"298.845702ms","start":"2026-03-04T06:17:50.328633Z","end":"2026-03-04T06:17:50.627479Z","steps":["trace[950094825] 'process raft request' (duration: 143.949751ms)","trace[950094825] 'compare' (duration: 154.45073ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T06:17:50.627597Z","caller":"traceutil/trace.go:171","msg":"trace[469146474] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"193.148794ms","start":"2026-03-04T06:17:50.434434Z","end":"2026-03-04T06:17:50.627583Z","steps":["trace[469146474] 'process raft request' (duration: 193.104053ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T06:17:50.627733Z","caller":"traceutil/trace.go:171","msg":"trace[836747783] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"241.024095ms","start":"2026-03-04T06:17:50.386695Z","end":"2026-03-04T06:17:50.627719Z","steps":["trace[836747783] 'process raft request' (duration: 240.692338ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T06:17:51.347855Z","caller":"traceutil/trace.go:171","msg":"trace[368155611] linearizableReadLoop","detail":"{readStateIndex:790; appliedIndex:789; }","duration":"209.607723ms","start":"2026-03-04T06:17:51.138215Z","end":"2026-03-04T06:17:51.347823Z","steps":["trace[368155611] 'read index received' (duration: 209.342167ms)","trace[368155611] 'applied index is now lower than readState.Index' (duration: 264.586µs)"],"step_count":2} {"level":"info","ts":"2026-03-04T06:17:51.347938Z","caller":"traceutil/trace.go:171","msg":"trace[269290354] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"299.759364ms","start":"2026-03-04T06:17:51.048146Z","end":"2026-03-04T06:17:51.347905Z","steps":["trace[269290354] 'process raft request' (duration: 299.442837ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T06:17:51.348132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.8958ms","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-03-04T06:17:51.348169Z","caller":"traceutil/trace.go:171","msg":"trace[1520939488] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:757; }","duration":"210.016833ms","start":"2026-03-04T06:17:51.138142Z","end":"2026-03-04T06:17:51.348159Z","steps":["trace[1520939488] 'agreement among raft nodes before linearized reading' (duration: 209.796898ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T06:18:01.814473Z","caller":"traceutil/trace.go:171","msg":"trace[287931170] linearizableReadLoop","detail":"{readStateIndex:874; appliedIndex:873; }","duration":"366.846099ms","start":"2026-03-04T06:18:01.447602Z","end":"2026-03-04T06:18:01.814448Z","steps":["trace[287931170] 'read index received' (duration: 366.672495ms)","trace[287931170] 'applied index is now lower than readState.Index' (duration: 172.754µs)"],"step_count":2} {"level":"warn","ts":"2026-03-04T06:18:01.814768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.145876ms","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-03-04T06:18:01.814801Z","caller":"traceutil/trace.go:171","msg":"trace[1313737880] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:836; }","duration":"367.244598ms","start":"2026-03-04T06:18:01.447548Z","end":"2026-03-04T06:18:01.814793Z","steps":["trace[1313737880] 'agreement among raft nodes before linearized reading' (duration: 367.045933ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T06:18:01.814824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T06:18:01.447525Z","time spent":"367.293119ms","remote":"127.0.0.1:37124","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-03-04T06:18:01.814726Z","caller":"traceutil/trace.go:171","msg":"trace[347914233] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"409.321512ms","start":"2026-03-04T06:18:01.405273Z","end":"2026-03-04T06:18:01.814594Z","steps":["trace[347914233] 'process raft request' (duration: 409.012695ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T06:18:01.815034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T06:18:01.405249Z","time spent":"409.68061ms","remote":"127.0.0.1:37124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T06:18:02.792451Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296565462376269157,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-04T06:18:02.986293Z","caller":"traceutil/trace.go:171","msg":"trace[809184835] linearizableReadLoop","detail":"{readStateIndex:876; appliedIndex:875; }","duration":"694.858511ms","start":"2026-03-04T06:18:02.291402Z","end":"2026-03-04T06:18:02.98626Z","steps":["trace[809184835] 'read index received' (duration: 694.552644ms)","trace[809184835] 'applied index is now lower than readState.Index' (duration: 304.717µs)"],"step_count":2} {"level":"info","ts":"2026-03-04T06:18:02.986434Z","caller":"traceutil/trace.go:171","msg":"trace[1750411466] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"762.013998ms","start":"2026-03-04T06:18:02.22438Z","end":"2026-03-04T06:18:02.986394Z","steps":["trace[1750411466] 'process raft request' (duration: 761.67791ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T06:18:02.986513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.181943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T06:18:02.986553Z","caller":"traceutil/trace.go:171","msg":"trace[1242012463] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:838; }","duration":"319.293016ms","start":"2026-03-04T06:18:02.667248Z","end":"2026-03-04T06:18:02.986541Z","steps":["trace[1242012463] 'agreement among raft nodes before linearized reading' (duration: 319.198634ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T06:18:02.986591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T06:18:02.667227Z","time spent":"319.355467ms","remote":"127.0.0.1:36842","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-03-04T06:18:02.986564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.544192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-04T06:18:02.986622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T06:18:02.224347Z","time spent":"762.172532ms","remote":"127.0.0.1:37124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T06:18:02.986657Z","caller":"traceutil/trace.go:171","msg":"trace[1198154534] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:838; }","duration":"253.686355ms","start":"2026-03-04T06:18:02.732953Z","end":"2026-03-04T06:18:02.98664Z","steps":["trace[1198154534] 'agreement among raft nodes before linearized reading' (duration: 253.534532ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T06:18:02.986742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.083745ms","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-03-04T06:18:02.986811Z","caller":"traceutil/trace.go:171","msg":"trace[182413863] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:838; }","duration":"309.165087ms","start":"2026-03-04T06:18:02.677627Z","end":"2026-03-04T06:18:02.986793Z","steps":["trace[182413863] 'agreement among raft nodes before linearized reading' (duration: 308.975802ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T06:18:02.986887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T06:18:02.677557Z","time spent":"309.320051ms","remote":"127.0.0.1:37124","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-03-04T06:18:02.987101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.706072ms","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-03-04T06:18:02.987132Z","caller":"traceutil/trace.go:171","msg":"trace[1438457618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:838; }","duration":"695.734973ms","start":"2026-03-04T06:18:02.291388Z","end":"2026-03-04T06:18:02.987123Z","steps":["trace[1438457618] 'agreement among raft nodes before linearized reading' (duration: 695.046186ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T06:18:02.987147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T06:18:02.291332Z","time spent":"695.810434ms","remote":"127.0.0.1:37124","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-03-04T06:18:03.108009Z","caller":"traceutil/trace.go:171","msg":"trace[1484731398] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"117.015015ms","start":"2026-03-04T06:18:02.99096Z","end":"2026-03-04T06:18:03.107975Z","steps":["trace[1484731398] 'process raft request' (duration: 81.305882ms)","trace[1484731398] 'compare' (duration: 35.57338ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T06:18:03.638594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.569685ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T06:18:03.638716Z","caller":"traceutil/trace.go:171","msg":"trace[933998937] linearizableReadLoop","detail":"{readStateIndex:878; appliedIndex:876; }","duration":"633.259646ms","start":"2026-03-04T06:18:03.005437Z","end":"2026-03-04T06:18:03.638697Z","steps":["trace[933998937] 'read index received' (duration: 66.942872ms)","trace[933998937] 'applied index is now lower than readState.Index' (duration: 566.315214ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T06:18:03.63878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.343478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T06:18:03.638806Z","caller":"traceutil/trace.go:171","msg":"trace[456881376] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:840; }","duration":"633.41238ms","start":"2026-03-04T06:18:03.005383Z","end":"2026-03-04T06:18:03.638796Z","steps":["trace[456881376] 'agreement among raft nodes before linearized reading' (duration: 633.361308ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T06:18:03.638839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T06:18:03.00537Z","time spent":"633.45956ms","remote":"127.0.0.1:37168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"info","ts":"2026-03-04T06:18:03.639092Z","caller":"traceutil/trace.go:171","msg":"trace[279889910] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"647.698997ms","start":"2026-03-04T06:18:02.991375Z","end":"2026-03-04T06:18:03.639074Z","steps":["trace[279889910] 'process raft request' (duration: 439.243001ms)","trace[279889910] 'compare' (duration: 207.457853ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T06:18:03.639149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T06:18:02.991352Z","time spent":"647.766099ms","remote":"127.0.0.1:37124","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-03-04T06:18:18.654723Z","caller":"traceutil/trace.go:171","msg":"trace[485336080] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"145.875497ms","start":"2026-03-04T06:18:18.508823Z","end":"2026-03-04T06:18:18.654699Z","steps":["trace[485336080] 'process raft request' (duration: 145.303443ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T06:18:20.018362Z","caller":"traceutil/trace.go:171","msg":"trace[1732515101] linearizableReadLoop","detail":"{readStateIndex:953; appliedIndex:952; }","duration":"104.605333ms","start":"2026-03-04T06:18:19.913729Z","end":"2026-03-04T06:18:20.018334Z","steps":["trace[1732515101] 'read index received' (duration: 104.457689ms)","trace[1732515101] 'applied index is now lower than readState.Index' (duration: 146.694µs)"],"step_count":2} {"level":"info","ts":"2026-03-04T06:18:20.018399Z","caller":"traceutil/trace.go:171","msg":"trace[62589652] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"112.398046ms","start":"2026-03-04T06:18:19.90596Z","end":"2026-03-04T06:18:20.018358Z","steps":["trace[62589652] 'process raft request' (duration: 112.228622ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T06:18:20.019262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.484323ms","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-03-04T06:18:20.019357Z","caller":"traceutil/trace.go:171","msg":"trace[1199928565] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:912; }","duration":"105.661247ms","start":"2026-03-04T06:18:19.913672Z","end":"2026-03-04T06:18:20.019333Z","steps":["trace[1199928565] 'agreement among raft nodes before linearized reading' (duration: 104.818017ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T06:18:21.000889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.420278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-04T06:18:21.00126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.81322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.70\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-04T06:18:21.001011Z","caller":"traceutil/trace.go:171","msg":"trace[1979305466] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:913; }","duration":"206.634643ms","start":"2026-03-04T06:18:20.794356Z","end":"2026-03-04T06:18:21.00099Z","steps":["trace[1979305466] 'count revisions from in-memory index tree' (duration: 206.292885ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T06:18:21.00137Z","caller":"traceutil/trace.go:171","msg":"trace[954641582] range","detail":"{range_begin:/registry/masterleases/199.204.45.70; range_end:; response_count:1; response_revision:913; }","duration":"702.999884ms","start":"2026-03-04T06:18:20.298341Z","end":"2026-03-04T06:18:21.001341Z","steps":["trace[954641582] 'range keys from in-memory index tree' (duration: 702.580744ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T06:18:21.001422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T06:18:20.298315Z","time spent":"703.097056ms","remote":"127.0.0.1:36872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.70\" "} {"level":"warn","ts":"2026-03-04T06:18:21.3294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T06:18:21.002755Z","time spent":"326.64029ms","remote":"127.0.0.1:36872","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-04T06:18:21.329466Z","caller":"traceutil/trace.go:171","msg":"trace[2082696109] linearizableReadLoop","detail":"{readStateIndex:955; appliedIndex:955; }","duration":"210.987886ms","start":"2026-03-04T06:18:21.118427Z","end":"2026-03-04T06:18:21.329415Z","steps":["trace[2082696109] 'read index received' (duration: 210.976996ms)","trace[2082696109] 'applied index is now lower than readState.Index' (duration: 8.73µs)"],"step_count":2} {"level":"warn","ts":"2026-03-04T06:18:21.329795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.340214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-03-04T06:18:21.329886Z","caller":"traceutil/trace.go:171","msg":"trace[1689200644] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:913; }","duration":"211.481378ms","start":"2026-03-04T06:18:21.118356Z","end":"2026-03-04T06:18:21.329838Z","steps":["trace[1689200644] 'agreement among raft nodes before linearized reading' (duration: 211.15801ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T06:18:21.527886Z","caller":"traceutil/trace.go:171","msg":"trace[1505269277] linearizableReadLoop","detail":"{readStateIndex:956; appliedIndex:955; }","duration":"198.307417ms","start":"2026-03-04T06:18:21.329553Z","end":"2026-03-04T06:18:21.52786Z","steps":["trace[1505269277] 'read index received' (duration: 198.268086ms)","trace[1505269277] 'applied index is now lower than readState.Index' (duration: 38.431µs)"],"step_count":2} {"level":"warn","ts":"2026-03-04T06:18:21.528207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.441539ms","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-03-04T06:18:21.528208Z","caller":"traceutil/trace.go:171","msg":"trace[805208686] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"309.187437ms","start":"2026-03-04T06:18:21.219002Z","end":"2026-03-04T06:18:21.528189Z","steps":["trace[805208686] 'process raft request' (duration: 308.47ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T06:18:21.52826Z","caller":"traceutil/trace.go:171","msg":"trace[1904292090] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:914; }","duration":"297.589273ms","start":"2026-03-04T06:18:21.230653Z","end":"2026-03-04T06:18:21.528242Z","steps":["trace[1904292090] 'agreement among raft nodes before linearized reading' (duration: 297.322416ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T06:18:21.528342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T06:18:21.218969Z","time spent":"309.291249ms","remote":"127.0.0.1:37124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T06:18:21.537027Z","caller":"traceutil/trace.go:171","msg":"trace[1744502015] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"201.86457ms","start":"2026-03-04T06:18:21.335151Z","end":"2026-03-04T06:18:21.537015Z","steps":["trace[1744502015] 'process raft request' (duration: 201.806439ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T06:18:21.537168Z","caller":"traceutil/trace.go:171","msg":"trace[1854365422] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"206.363197ms","start":"2026-03-04T06:18:21.330794Z","end":"2026-03-04T06:18:21.537157Z","steps":["trace[1854365422] 'process raft request' (duration: 206.08524ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T06:18:21.708732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.192579ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T06:18:21.708837Z","caller":"traceutil/trace.go:171","msg":"trace[1183525280] linearizableReadLoop","detail":"{readStateIndex:959; appliedIndex:958; }","duration":"169.71716ms","start":"2026-03-04T06:18:21.539102Z","end":"2026-03-04T06:18:21.70882Z","steps":["trace[1183525280] 'read index received' (duration: 55.248785ms)","trace[1183525280] 'applied index is now lower than readState.Index' (duration: 114.466995ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T06:18:21.708939Z","caller":"traceutil/trace.go:171","msg":"trace[1371806990] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"175.064888ms","start":"2026-03-04T06:18:21.533845Z","end":"2026-03-04T06:18:21.708909Z","steps":["trace[1371806990] 'process raft request' (duration: 60.4842ms)","trace[1371806990] 'compare' (duration: 113.740798ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T06:18:21.710723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.606745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-03-04T06:18:21.710777Z","caller":"traceutil/trace.go:171","msg":"trace[200664880] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:917; }","duration":"171.674247ms","start":"2026-03-04T06:18:21.539082Z","end":"2026-03-04T06:18:21.710756Z","steps":["trace[200664880] 'agreement among raft nodes before linearized reading' (duration: 169.785473ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T06:18:22.397292Z","caller":"traceutil/trace.go:171","msg":"trace[2020466100] linearizableReadLoop","detail":"{readStateIndex:963; appliedIndex:962; }","duration":"196.568285ms","start":"2026-03-04T06:18:22.200692Z","end":"2026-03-04T06:18:22.39726Z","steps":["trace[2020466100] 'read index received' (duration: 187.978732ms)","trace[2020466100] 'applied index is now lower than readState.Index' (duration: 8.588363ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T06:18:22.397495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.771641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T06:18:22.397541Z","caller":"traceutil/trace.go:171","msg":"trace[1950966005] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:921; }","duration":"196.869153ms","start":"2026-03-04T06:18:22.200652Z","end":"2026-03-04T06:18:22.397521Z","steps":["trace[1950966005] 'agreement among raft nodes before linearized reading' (duration: 196.716719ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T06:18:22.398042Z","caller":"traceutil/trace.go:171","msg":"trace[1843124583] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"295.009072ms","start":"2026-03-04T06:18:22.103012Z","end":"2026-03-04T06:18:22.398021Z","steps":["trace[1843124583] 'process raft request' (duration: 285.703252ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T06:18:22.933334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.151163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T06:18:22.933437Z","caller":"traceutil/trace.go:171","msg":"trace[1919778003] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:921; }","duration":"201.318597ms","start":"2026-03-04T06:18:22.732101Z","end":"2026-03-04T06:18:22.93342Z","steps":["trace[1919778003] 'range keys from in-memory index tree' (duration: 201.056551ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T06:18:22.934056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.676124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T06:18:22.93415Z","caller":"traceutil/trace.go:171","msg":"trace[945241492] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:921; }","duration":"182.024822ms","start":"2026-03-04T06:18:22.752103Z","end":"2026-03-04T06:18:22.934128Z","steps":["trace[945241492] 'count revisions from in-memory index tree' (duration: 181.54833ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T06:18:23.812367Z","caller":"traceutil/trace.go:171","msg":"trace[949403647] linearizableReadLoop","detail":"{readStateIndex:965; appliedIndex:964; }","duration":"271.334962ms","start":"2026-03-04T06:18:23.540999Z","end":"2026-03-04T06:18:23.812334Z","steps":["trace[949403647] 'read index received' (duration: 253.274505ms)","trace[949403647] 'applied index is now lower than readState.Index' (duration: 18.059347ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T06:18:23.812429Z","caller":"traceutil/trace.go:171","msg":"trace[216229965] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"278.213444ms","start":"2026-03-04T06:18:23.534166Z","end":"2026-03-04T06:18:23.812379Z","steps":["trace[216229965] 'process raft request' (duration: 260.205259ms)","trace[216229965] 'compare' (duration: 17.486693ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T06:18:23.812729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.698001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-03-04T06:18:23.81279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.269801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T06:18:23.812819Z","caller":"traceutil/trace.go:171","msg":"trace[1583451945] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:923; }","duration":"271.830564ms","start":"2026-03-04T06:18:23.540967Z","end":"2026-03-04T06:18:23.812797Z","steps":["trace[1583451945] 'agreement among raft nodes before linearized reading' (duration: 271.491916ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T06:18:23.81284Z","caller":"traceutil/trace.go:171","msg":"trace[51316691] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:923; }","duration":"271.525977ms","start":"2026-03-04T06:18:23.541297Z","end":"2026-03-04T06:18:23.812823Z","steps":["trace[51316691] 'agreement among raft nodes before linearized reading' (duration: 271.22815ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T06:18:23.812909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.262599ms","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-03-04T06:18:23.812962Z","caller":"traceutil/trace.go:171","msg":"trace[330426059] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:923; }","duration":"100.374982ms","start":"2026-03-04T06:18:23.712573Z","end":"2026-03-04T06:18:23.812948Z","steps":["trace[330426059] 'agreement among raft nodes before linearized reading' (duration: 100.149626ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T06:18:24.017997Z","caller":"traceutil/trace.go:171","msg":"trace[981047801] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"200.458697ms","start":"2026-03-04T06:18:23.817518Z","end":"2026-03-04T06:18:24.017977Z","steps":["trace[981047801] 'process raft request' (duration: 200.384945ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T06:18:24.018084Z","caller":"traceutil/trace.go:171","msg":"trace[254140265] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"200.60402ms","start":"2026-03-04T06:18:23.817428Z","end":"2026-03-04T06:18:24.018032Z","steps":["trace[254140265] 'process raft request' (duration: 200.389965ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T06:18:24.017952Z","caller":"traceutil/trace.go:171","msg":"trace[1602138003] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"201.189145ms","start":"2026-03-04T06:18:23.816718Z","end":"2026-03-04T06:18:24.017908Z","steps":["trace[1602138003] 'process raft request' (duration: 121.320897ms)","trace[1602138003] 'compare' (duration: 79.614272ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T06:18:24.018367Z","caller":"traceutil/trace.go:171","msg":"trace[1712530481] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"200.768895ms","start":"2026-03-04T06:18:23.817568Z","end":"2026-03-04T06:18:24.018337Z","steps":["trace[1712530481] 'process raft request' (duration: 200.368185ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T06:18:24.018417Z","caller":"traceutil/trace.go:171","msg":"trace[268155221] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"200.658492ms","start":"2026-03-04T06:18:23.817736Z","end":"2026-03-04T06:18:24.018395Z","steps":["trace[268155221] 'process raft request' (duration: 200.491628ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T06:18:24.45619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.132153ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T06:18:24.456513Z","caller":"traceutil/trace.go:171","msg":"trace[2120686998] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"338.49559ms","start":"2026-03-04T06:18:24.117994Z","end":"2026-03-04T06:18:24.45649Z","steps":["trace[2120686998] 'process raft request' (duration: 172.957547ms)","trace[2120686998] 'compare' (duration: 164.98974ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T06:18:24.456591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T06:18:24.117968Z","time spent":"338.590372ms","remote":"127.0.0.1:37124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T06:18:35.760166Z","caller":"traceutil/trace.go:171","msg":"trace[1924787800] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"147.753882ms","start":"2026-03-04T06:18:35.612368Z","end":"2026-03-04T06:18:35.760122Z","steps":["trace[1924787800] 'process raft request' (duration: 147.378453ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T06:18:36.248732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.811858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-03-04T06:18:36.248845Z","caller":"traceutil/trace.go:171","msg":"trace[484301195] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1000; }","duration":"173.979501ms","start":"2026-03-04T06:18:36.074839Z","end":"2026-03-04T06:18:36.248819Z","steps":["trace[484301195] 'range keys from in-memory index tree' (duration: 173.556271ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T06:18:36.248855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.707395ms","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-03-04T06:18:36.248956Z","caller":"traceutil/trace.go:171","msg":"trace[768822512] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1000; }","duration":"173.881879ms","start":"2026-03-04T06:18:36.075053Z","end":"2026-03-04T06:18:36.248935Z","steps":["trace[768822512] 'range keys from in-memory index tree' (duration: 173.49153ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T06:18:36.249546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.857603ms","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-03-04T06:18:36.249652Z","caller":"traceutil/trace.go:171","msg":"trace[533561829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1000; }","duration":"175.070857ms","start":"2026-03-04T06:18:36.074558Z","end":"2026-03-04T06:18:36.249629Z","steps":["trace[533561829] 'range keys from in-memory index tree' (duration: 173.976351ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T06:18:36.363651Z","caller":"traceutil/trace.go:171","msg":"trace[1907108233] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"109.318483ms","start":"2026-03-04T06:18:36.254306Z","end":"2026-03-04T06:18:36.363625Z","steps":["trace[1907108233] 'process raft request' (duration: 109.121019ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T06:18:36.426967Z","caller":"traceutil/trace.go:171","msg":"trace[2020651844] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"171.845161ms","start":"2026-03-04T06:18:36.255098Z","end":"2026-03-04T06:18:36.426943Z","steps":["trace[2020651844] 'process raft request' (duration: 171.782149ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T06:18:36.427044Z","caller":"traceutil/trace.go:171","msg":"trace[625716380] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"172.66005ms","start":"2026-03-04T06:18:36.254349Z","end":"2026-03-04T06:18:36.427009Z","steps":["trace[625716380] 'process raft request' (duration: 172.425995ms)"],"step_count":1}