{"level":"warn","ts":"2026-03-05T07:06:19.567456Z","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-05T07:06:19.567732Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.210: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.210:2380","--initial-cluster=instance=https://199.204.45.210:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.210:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.210: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-05T07:06:19.567858Z","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-05T07:06:19.567898Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-03-05T07:06:19.567964Z","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-05T07:06:19.568883Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"]} {"level":"info","ts":"2026-03-05T07:06:19.569035Z","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.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.210: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-05T07:06:19.573744Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.36436ms"} {"level":"info","ts":"2026-03-05T07:06:19.58099Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1cac722af8b2b8a6","cluster-id":"ba0a336ca61ee41d"} {"level":"info","ts":"2026-03-05T07:06:19.581122Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=()"} {"level":"info","ts":"2026-03-05T07:06:19.581206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 0"} {"level":"info","ts":"2026-03-05T07:06:19.581235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1cac722af8b2b8a6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-05T07:06:19.581253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 1"} {"level":"info","ts":"2026-03-05T07:06:19.581306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"warn","ts":"2026-03-05T07:06:19.587237Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-05T07:06:19.588812Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-05T07:06:19.589968Z","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-05T07:06:19.592637Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1cac722af8b2b8a6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-05T07:06:19.592943Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1cac722af8b2b8a6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-05T07:06:19.593214Z","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-05T07:06:19.593307Z","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-05T07:06:19.593324Z","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-05T07:06:19.593978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"info","ts":"2026-03-05T07:06:19.594193Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","added-peer-id":"1cac722af8b2b8a6","added-peer-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-03-05T07:06:19.600596Z","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-05T07:06:19.600763Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-03-05T07:06:19.600792Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-03-05T07:06:19.602039Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1cac722af8b2b8a6","initial-advertise-peer-urls":["https://199.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-05T07:06:19.602086Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-05T07:06:20.182441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 is starting a new election at term 1"} {"level":"info","ts":"2026-03-05T07:06:20.1825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-05T07:06:20.182549Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgPreVoteResp from 1cac722af8b2b8a6 at term 1"} {"level":"info","ts":"2026-03-05T07:06:20.182569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became candidate at term 2"} {"level":"info","ts":"2026-03-05T07:06:20.182581Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgVoteResp from 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-03-05T07:06:20.182596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became leader at term 2"} {"level":"info","ts":"2026-03-05T07:06:20.18261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1cac722af8b2b8a6 elected leader 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-03-05T07:06:20.183562Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-05T07:06:20.183664Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1cac722af8b2b8a6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.210:2379]}","request-path":"/0/members/1cac722af8b2b8a6/attributes","cluster-id":"ba0a336ca61ee41d","publish-timeout":"7s"} {"level":"info","ts":"2026-03-05T07:06:20.183709Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-05T07:06:20.183709Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-05T07:06:20.183887Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-05T07:06:20.183964Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-05T07:06:20.186762Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-05T07:06:20.187697Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","cluster-version":"3.5"} {"level":"info","ts":"2026-03-05T07:06:20.187775Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-05T07:06:20.187798Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-05T07:06:20.193098Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.210:2379"} {"level":"info","ts":"2026-03-05T07:06:21.616935Z","caller":"traceutil/trace.go:171","msg":"trace[308921609] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"115.223226ms","start":"2026-03-05T07:06:21.501691Z","end":"2026-03-05T07:06:21.616914Z","steps":["trace[308921609] 'process raft request' (duration: 115.005651ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:06:21.655322Z","caller":"traceutil/trace.go:171","msg":"trace[1256853615] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"153.281368ms","start":"2026-03-05T07:06:21.501761Z","end":"2026-03-05T07:06:21.655042Z","steps":["trace[1256853615] 'process raft request' (duration: 152.856877ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:06:21.656648Z","caller":"traceutil/trace.go:171","msg":"trace[339734818] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"142.425714ms","start":"2026-03-05T07:06:21.514127Z","end":"2026-03-05T07:06:21.656552Z","steps":["trace[339734818] 'read index received' (duration: 102.667231ms)","trace[339734818] 'applied index is now lower than readState.Index' (duration: 39.757503ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T07:06:21.658596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.450036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-05T07:06:21.658688Z","caller":"traceutil/trace.go:171","msg":"trace[47574224] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"144.557648ms","start":"2026-03-05T07:06:21.514101Z","end":"2026-03-05T07:06:21.658659Z","steps":["trace[47574224] 'agreement among raft nodes before linearized reading' (duration: 143.712117ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:06:21.658814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.97407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-05T07:06:21.65888Z","caller":"traceutil/trace.go:171","msg":"trace[163778361] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"109.058761ms","start":"2026-03-05T07:06:21.549793Z","end":"2026-03-05T07:06:21.658852Z","steps":["trace[163778361] 'agreement among raft nodes before linearized reading' (duration: 108.369064ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:06:21.659954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.956684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-05T07:06:21.660006Z","caller":"traceutil/trace.go:171","msg":"trace[148249199] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"110.137538ms","start":"2026-03-05T07:06:21.549842Z","end":"2026-03-05T07:06:21.65998Z","steps":["trace[148249199] 'agreement among raft nodes before linearized reading' (duration: 109.690267ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:06:54.297074Z","caller":"traceutil/trace.go:171","msg":"trace[1000231269] linearizableReadLoop","detail":"{readStateIndex:494; appliedIndex:493; }","duration":"416.570897ms","start":"2026-03-05T07:06:53.880475Z","end":"2026-03-05T07:06:54.297045Z","steps":["trace[1000231269] 'read index received' (duration: 416.368842ms)","trace[1000231269] 'applied index is now lower than readState.Index' (duration: 200.715µs)"],"step_count":2} {"level":"info","ts":"2026-03-05T07:06:54.297561Z","caller":"traceutil/trace.go:171","msg":"trace[674054099] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"470.227374ms","start":"2026-03-05T07:06:53.827318Z","end":"2026-03-05T07:06:54.297545Z","steps":["trace[674054099] 'process raft request' (duration: 469.571696ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:06:54.298148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:06:53.827302Z","time spent":"470.305297ms","remote":"127.0.0.1:36966","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-05T07:06:54.298458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.978465ms","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-05T07:06:54.298498Z","caller":"traceutil/trace.go:171","msg":"trace[2045029532] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:479; }","duration":"418.033186ms","start":"2026-03-05T07:06:53.880451Z","end":"2026-03-05T07:06:54.298484Z","steps":["trace[2045029532] 'agreement among raft nodes before linearized reading' (duration: 417.911443ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:06:54.29854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:06:53.880437Z","time spent":"418.095408ms","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-05T07:06:54.298733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.22142ms","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-05T07:06:54.298779Z","caller":"traceutil/trace.go:171","msg":"trace[161548927] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:479; }","duration":"369.282681ms","start":"2026-03-05T07:06:53.929483Z","end":"2026-03-05T07:06:54.298765Z","steps":["trace[161548927] 'agreement among raft nodes before linearized reading' (duration: 369.209829ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:06:54.298809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:06:53.929472Z","time spent":"369.330263ms","remote":"127.0.0.1:36966","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-05T07:06:54.299065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.056235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8qfd4\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-05T07:06:54.299101Z","caller":"traceutil/trace.go:171","msg":"trace[575845330] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8qfd4; range_end:; response_count:1; response_revision:479; }","duration":"239.119477ms","start":"2026-03-05T07:06:54.059973Z","end":"2026-03-05T07:06:54.299092Z","steps":["trace[575845330] 'agreement among raft nodes before linearized reading' (duration: 239.007674ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:06:54.458534Z","caller":"traceutil/trace.go:171","msg":"trace[1048468274] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"147.789215ms","start":"2026-03-05T07:06:54.310713Z","end":"2026-03-05T07:06:54.458502Z","steps":["trace[1048468274] 'process raft request' (duration: 147.62105ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:06:54.458619Z","caller":"traceutil/trace.go:171","msg":"trace[1226480521] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"153.978463ms","start":"2026-03-05T07:06:54.30458Z","end":"2026-03-05T07:06:54.458559Z","steps":["trace[1226480521] 'process raft request' (duration: 153.604752ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:06:54.808424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.125341ms","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-03-05T07:06:54.808513Z","caller":"traceutil/trace.go:171","msg":"trace[522028077] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:482; }","duration":"230.299915ms","start":"2026-03-05T07:06:54.578193Z","end":"2026-03-05T07:06:54.808493Z","steps":["trace[522028077] 'range keys from in-memory index tree' (duration: 229.989767ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:06:54.922108Z","caller":"traceutil/trace.go:171","msg":"trace[722377578] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"109.33828ms","start":"2026-03-05T07:06:54.812746Z","end":"2026-03-05T07:06:54.922084Z","steps":["trace[722377578] 'process raft request' (duration: 109.184035ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:06:56.374656Z","caller":"traceutil/trace.go:171","msg":"trace[1185988462] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"179.196248ms","start":"2026-03-05T07:06:56.195424Z","end":"2026-03-05T07:06:56.374621Z","steps":["trace[1185988462] 'process raft request' (duration: 178.867499ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:07:15.09071Z","caller":"traceutil/trace.go:171","msg":"trace[1855599603] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"239.214259ms","start":"2026-03-05T07:07:14.85148Z","end":"2026-03-05T07:07:15.090694Z","steps":["trace[1855599603] 'process raft request' (duration: 239.123826ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:07:15.403385Z","caller":"traceutil/trace.go:171","msg":"trace[1901784443] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"525.467085ms","start":"2026-03-05T07:07:14.877884Z","end":"2026-03-05T07:07:15.403351Z","steps":["trace[1901784443] 'process raft request' (duration: 525.273479ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:07:15.403577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:07:14.877863Z","time spent":"525.622279ms","remote":"127.0.0.1:36886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3856,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T07:07:15.403636Z","caller":"traceutil/trace.go:171","msg":"trace[1748065909] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"525.454494ms","start":"2026-03-05T07:07:14.878169Z","end":"2026-03-05T07:07:15.403624Z","steps":["trace[1748065909] 'process raft request' (duration: 525.168337ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:07:15.403394Z","caller":"traceutil/trace.go:171","msg":"trace[675123806] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"525.24927ms","start":"2026-03-05T07:07:14.878124Z","end":"2026-03-05T07:07:15.403373Z","steps":["trace[675123806] 'process raft request' (duration: 525.168237ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:07:15.403743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:07:14.878116Z","time spent":"525.598768ms","remote":"127.0.0.1:36886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3820,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T07:07:15.403755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:07:14.878142Z","time spent":"525.541478ms","remote":"127.0.0.1:36886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3856,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T07:07:15.403819Z","caller":"traceutil/trace.go:171","msg":"trace[158377423] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:639; }","duration":"405.340961ms","start":"2026-03-05T07:07:14.998446Z","end":"2026-03-05T07:07:15.403787Z","steps":["trace[158377423] 'read index received' (duration: 92.159433ms)","trace[158377423] 'applied index is now lower than readState.Index' (duration: 313.180428ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T07:07:15.40391Z","caller":"traceutil/trace.go:171","msg":"trace[766122377] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"524.415656ms","start":"2026-03-05T07:07:14.879484Z","end":"2026-03-05T07:07:15.4039Z","steps":["trace[766122377] 'process raft request' (duration: 524.091698ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:07:15.403997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:07:14.879468Z","time spent":"524.50054ms","remote":"127.0.0.1:36872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4972,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T07:07:15.404057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.598999ms","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-03-05T07:07:15.404101Z","caller":"traceutil/trace.go:171","msg":"trace[748850660] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:624; }","duration":"405.689191ms","start":"2026-03-05T07:07:14.998402Z","end":"2026-03-05T07:07:15.404092Z","steps":["trace[748850660] 'agreement among raft nodes before linearized reading' (duration: 405.509895ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:07:15.404137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:07:14.998383Z","time spent":"405.746833ms","remote":"127.0.0.1:36966","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-03-05T07:07:15.593718Z","caller":"traceutil/trace.go:171","msg":"trace[419625073] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"184.719008ms","start":"2026-03-05T07:07:15.408977Z","end":"2026-03-05T07:07:15.593696Z","steps":["trace[419625073] 'process raft request' (duration: 158.183177ms)","trace[419625073] 'compare' (duration: 26.441728ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T07:07:15.608074Z","caller":"traceutil/trace.go:171","msg":"trace[644304895] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"199.075238ms","start":"2026-03-05T07:07:15.408987Z","end":"2026-03-05T07:07:15.608063Z","steps":["trace[644304895] 'process raft request' (duration: 198.945094ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:07:15.608236Z","caller":"traceutil/trace.go:171","msg":"trace[1277785769] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:644; }","duration":"198.819951ms","start":"2026-03-05T07:07:15.409386Z","end":"2026-03-05T07:07:15.608206Z","steps":["trace[1277785769] 'read index received' (duration: 158.002933ms)","trace[1277785769] 'applied index is now lower than readState.Index' (duration: 40.815448ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T07:07:15.608364Z","caller":"traceutil/trace.go:171","msg":"trace[1413130336] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"197.824644ms","start":"2026-03-05T07:07:15.410512Z","end":"2026-03-05T07:07:15.608336Z","steps":["trace[1413130336] 'process raft request' (duration: 197.486255ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:07:15.60841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.008097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-qzztj\" ","response":"range_response_count:1 size:3835"} {"level":"info","ts":"2026-03-05T07:07:15.608447Z","caller":"traceutil/trace.go:171","msg":"trace[598842576] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-qzztj; range_end:; response_count:1; response_revision:627; }","duration":"199.073558ms","start":"2026-03-05T07:07:15.409364Z","end":"2026-03-05T07:07:15.608437Z","steps":["trace[598842576] 'agreement among raft nodes before linearized reading' (duration: 198.944884ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:07:24.935286Z","caller":"traceutil/trace.go:171","msg":"trace[1999659041] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"429.541619ms","start":"2026-03-05T07:07:24.505725Z","end":"2026-03-05T07:07:24.935267Z","steps":["trace[1999659041] 'process raft request' (duration: 429.431896ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:07:24.935421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:07:24.505712Z","time spent":"429.637272ms","remote":"127.0.0.1:36966","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-05T07:07:24.971927Z","caller":"traceutil/trace.go:171","msg":"trace[808961282] linearizableReadLoop","detail":"{readStateIndex:721; appliedIndex:719; }","duration":"396.148752ms","start":"2026-03-05T07:07:24.575757Z","end":"2026-03-05T07:07:24.971906Z","steps":["trace[808961282] 'read index received' (duration: 359.468136ms)","trace[808961282] 'applied index is now lower than readState.Index' (duration: 36.679676ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T07:07:24.972017Z","caller":"traceutil/trace.go:171","msg":"trace[1119829388] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"463.199572ms","start":"2026-03-05T07:07:24.50881Z","end":"2026-03-05T07:07:24.972009Z","steps":["trace[1119829388] 'process raft request' (duration: 462.974957ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:07:24.972101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:07:24.508789Z","time spent":"463.248804ms","remote":"127.0.0.1:36966","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-05T07:07:24.972217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.000573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-05T07:07:24.972233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.466701ms","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-03-05T07:07:24.972262Z","caller":"traceutil/trace.go:171","msg":"trace[1702950999] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:699; }","duration":"394.095856ms","start":"2026-03-05T07:07:24.578155Z","end":"2026-03-05T07:07:24.972251Z","steps":["trace[1702950999] 'agreement among raft nodes before linearized reading' (duration: 393.940741ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:07:24.97228Z","caller":"traceutil/trace.go:171","msg":"trace[1735933038] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:699; }","duration":"396.552963ms","start":"2026-03-05T07:07:24.575714Z","end":"2026-03-05T07:07:24.972267Z","steps":["trace[1735933038] 'agreement among raft nodes before linearized reading' (duration: 396.41822ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:07:24.972288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:07:24.578134Z","time spent":"394.149398ms","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-05T07:07:24.972315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:07:24.5757Z","time spent":"396.606845ms","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-05T07:07:25.146059Z","caller":"traceutil/trace.go:171","msg":"trace[1066946537] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"170.659316ms","start":"2026-03-05T07:07:24.975351Z","end":"2026-03-05T07:07:25.146011Z","steps":["trace[1066946537] 'process raft request' (duration: 158.137115ms)","trace[1066946537] 'compare' (duration: 12.382477ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T07:07:25.149244Z","caller":"traceutil/trace.go:171","msg":"trace[1635540017] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"173.481912ms","start":"2026-03-05T07:07:24.975748Z","end":"2026-03-05T07:07:25.14923Z","steps":["trace[1635540017] 'process raft request' (duration: 173.40794ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:07:25.800931Z","caller":"traceutil/trace.go:171","msg":"trace[163245892] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"106.542654ms","start":"2026-03-05T07:07:25.694367Z","end":"2026-03-05T07:07:25.80091Z","steps":["trace[163245892] 'compare' (duration: 97.385355ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:07:26.205443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:07:25.861872Z","time spent":"343.566914ms","remote":"127.0.0.1:36736","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-05T07:07:51.394977Z","caller":"traceutil/trace.go:171","msg":"trace[813102990] linearizableReadLoop","detail":"{readStateIndex:861; appliedIndex:860; }","duration":"135.92717ms","start":"2026-03-05T07:07:51.259029Z","end":"2026-03-05T07:07:51.394956Z","steps":["trace[813102990] 'read index received' (duration: 135.745636ms)","trace[813102990] 'applied index is now lower than readState.Index' (duration: 180.514µs)"],"step_count":2} {"level":"info","ts":"2026-03-05T07:07:51.395238Z","caller":"traceutil/trace.go:171","msg":"trace[1815819359] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"242.625877ms","start":"2026-03-05T07:07:51.152599Z","end":"2026-03-05T07:07:51.395225Z","steps":["trace[1815819359] 'process raft request' (duration: 242.240676ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:07:51.395489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.446834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-05T07:07:51.395521Z","caller":"traceutil/trace.go:171","msg":"trace[2127803660] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:831; }","duration":"136.540806ms","start":"2026-03-05T07:07:51.25897Z","end":"2026-03-05T07:07:51.395511Z","steps":["trace[2127803660] 'agreement among raft nodes before linearized reading' (duration: 136.430993ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:07:51.395703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.889778ms","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-05T07:07:51.395778Z","caller":"traceutil/trace.go:171","msg":"trace[1895920388] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:831; }","duration":"104.996031ms","start":"2026-03-05T07:07:51.290764Z","end":"2026-03-05T07:07:51.39576Z","steps":["trace[1895920388] 'agreement among raft nodes before linearized reading' (duration: 104.791326ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:08:07.819503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.444472ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T07:08:07.819639Z","caller":"traceutil/trace.go:171","msg":"trace[2092427199] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:895; }","duration":"450.596736ms","start":"2026-03-05T07:08:07.36902Z","end":"2026-03-05T07:08:07.819617Z","steps":["trace[2092427199] 'range keys from in-memory index tree' (duration: 450.424162ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:08:07.819642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.710868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-05T07:08:07.819651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.595176ms","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-05T07:08:07.819703Z","caller":"traceutil/trace.go:171","msg":"trace[945236569] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:895; }","duration":"436.806351ms","start":"2026-03-05T07:08:07.382881Z","end":"2026-03-05T07:08:07.819688Z","steps":["trace[945236569] 'range keys from in-memory index tree' (duration: 436.626196ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:08:07.819711Z","caller":"traceutil/trace.go:171","msg":"trace[1013662852] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:895; }","duration":"558.695409ms","start":"2026-03-05T07:08:07.261Z","end":"2026-03-05T07:08:07.819696Z","steps":["trace[1013662852] 'range keys from in-memory index tree' (duration: 558.363501ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:08:07.819733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:08:07.382856Z","time spent":"436.869902ms","remote":"127.0.0.1:36656","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-05T07:08:07.819746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:08:07.260981Z","time spent":"558.756961ms","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-05T07:08:07.819853Z","caller":"traceutil/trace.go:171","msg":"trace[1053648930] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"343.305804ms","start":"2026-03-05T07:08:07.476533Z","end":"2026-03-05T07:08:07.819839Z","steps":["trace[1053648930] 'process raft request' (duration: 256.952687ms)","trace[1053648930] 'compare' (duration: 86.196903ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T07:08:07.819836Z","caller":"traceutil/trace.go:171","msg":"trace[1268611476] linearizableReadLoop","detail":"{readStateIndex:929; appliedIndex:928; }","duration":"336.293628ms","start":"2026-03-05T07:08:07.483513Z","end":"2026-03-05T07:08:07.819807Z","steps":["trace[1268611476] 'read index received' (duration: 249.878839ms)","trace[1268611476] 'applied index is now lower than readState.Index' (duration: 86.413879ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T07:08:07.819947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:08:07.476517Z","time spent":"343.372375ms","remote":"127.0.0.1:36966","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-05T07:08:07.820069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.532905ms","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-05T07:08:07.820103Z","caller":"traceutil/trace.go:171","msg":"trace[1152642850] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:896; }","duration":"336.609907ms","start":"2026-03-05T07:08:07.483482Z","end":"2026-03-05T07:08:07.820092Z","steps":["trace[1152642850] 'agreement among raft nodes before linearized reading' (duration: 336.460652ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:08:07.820114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.491638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-03-05T07:08:07.820138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:08:07.483467Z","time spent":"336.662988ms","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-05T07:08:07.820151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.429344ms","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-05T07:08:07.820154Z","caller":"traceutil/trace.go:171","msg":"trace[16888196] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:896; }","duration":"312.5535ms","start":"2026-03-05T07:08:07.507589Z","end":"2026-03-05T07:08:07.820142Z","steps":["trace[16888196] 'agreement among raft nodes before linearized reading' (duration: 312.436926ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:08:07.820184Z","caller":"traceutil/trace.go:171","msg":"trace[1593998754] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:896; }","duration":"270.481765ms","start":"2026-03-05T07:08:07.549691Z","end":"2026-03-05T07:08:07.820172Z","steps":["trace[1593998754] 'agreement among raft nodes before linearized reading' (duration: 270.360302ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:08:07.820189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:08:07.507579Z","time spent":"312.602931ms","remote":"127.0.0.1:36966","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-03-05T07:08:08.210047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.399432ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-05T07:08:08.210168Z","caller":"traceutil/trace.go:171","msg":"trace[621182432] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"386.311293ms","start":"2026-03-05T07:08:07.823838Z","end":"2026-03-05T07:08:08.210149Z","steps":["trace[621182432] 'process raft request' (duration: 146.569022ms)","trace[621182432] 'compare' (duration: 239.139665ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T07:08:08.210238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:08:07.823825Z","time spent":"386.381765ms","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T07:08:08.408667Z","caller":"traceutil/trace.go:171","msg":"trace[2140493012] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"584.700378ms","start":"2026-03-05T07:08:07.823943Z","end":"2026-03-05T07:08:08.408643Z","steps":["trace[2140493012] 'process raft request' (duration: 569.792143ms)","trace[2140493012] 'compare' (duration: 14.741541ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T07:08:08.409033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:08:07.823922Z","time spent":"585.030867ms","remote":"127.0.0.1:36966","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-03-05T07:08:08.409152Z","caller":"traceutil/trace.go:171","msg":"trace[843116356] linearizableReadLoop","detail":"{readStateIndex:933; appliedIndex:930; }","duration":"377.753666ms","start":"2026-03-05T07:08:08.031371Z","end":"2026-03-05T07:08:08.409125Z","steps":["trace[843116356] 'read index received' (duration: 362.465751ms)","trace[843116356] 'applied index is now lower than readState.Index' (duration: 15.286745ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T07:08:08.409201Z","caller":"traceutil/trace.go:171","msg":"trace[1639619579] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"585.137209ms","start":"2026-03-05T07:08:07.824039Z","end":"2026-03-05T07:08:08.409177Z","steps":["trace[1639619579] 'process raft request' (duration: 584.561554ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:08:08.409285Z","caller":"traceutil/trace.go:171","msg":"trace[1134776443] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"584.217804ms","start":"2026-03-05T07:08:07.825032Z","end":"2026-03-05T07:08:08.409249Z","steps":["trace[1134776443] 'process raft request' (duration: 583.963648ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:08:08.409352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:08:07.824034Z","time spent":"585.245632ms","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T07:08:08.409343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.952121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-05T07:08:08.409406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:08:07.825025Z","time spent":"584.291047ms","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T07:08:08.409458Z","caller":"traceutil/trace.go:171","msg":"trace[1177571010] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:900; }","duration":"378.113935ms","start":"2026-03-05T07:08:08.031329Z","end":"2026-03-05T07:08:08.409443Z","steps":["trace[1177571010] 'agreement among raft nodes before linearized reading' (duration: 377.958241ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:08:08.40951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:08:08.031309Z","time spent":"378.186447ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-03-05T07:08:08.509366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.361404ms","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-05T07:08:08.509447Z","caller":"traceutil/trace.go:171","msg":"trace[1649637940] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:900; }","duration":"460.476728ms","start":"2026-03-05T07:08:08.048954Z","end":"2026-03-05T07:08:08.509431Z","steps":["trace[1649637940] 'agreement among raft nodes before linearized reading' (duration: 460.261173ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:08:08.509488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:08:08.048944Z","time spent":"460.535939ms","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-05T07:08:08.509625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.340697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-05T07:08:08.509718Z","caller":"traceutil/trace.go:171","msg":"trace[1607867848] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:900; }","duration":"271.472182ms","start":"2026-03-05T07:08:08.238226Z","end":"2026-03-05T07:08:08.509698Z","steps":["trace[1607867848] 'agreement among raft nodes before linearized reading' (duration: 271.326977ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:08:08.509746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.529341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T07:08:08.50978Z","caller":"traceutil/trace.go:171","msg":"trace[1059850292] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:900; }","duration":"374.585382ms","start":"2026-03-05T07:08:08.135181Z","end":"2026-03-05T07:08:08.509767Z","steps":["trace[1059850292] 'agreement among raft nodes before linearized reading' (duration: 374.531961ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:08:08.509808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:08:08.135169Z","time spent":"374.631203ms","remote":"127.0.0.1:55590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-03-05T07:08:22.513179Z","caller":"traceutil/trace.go:171","msg":"trace[900259489] linearizableReadLoop","detail":"{readStateIndex:1020; appliedIndex:1019; }","duration":"130.062505ms","start":"2026-03-05T07:08:22.383095Z","end":"2026-03-05T07:08:22.513157Z","steps":["trace[900259489] 'read index received' (duration: 129.84183ms)","trace[900259489] 'applied index is now lower than readState.Index' (duration: 219.535µs)"],"step_count":2} {"level":"warn","ts":"2026-03-05T07:08:22.51335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.24243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T07:08:22.513298Z","caller":"traceutil/trace.go:171","msg":"trace[1919662836] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"237.187153ms","start":"2026-03-05T07:08:22.276081Z","end":"2026-03-05T07:08:22.513268Z","steps":["trace[1919662836] 'process raft request' (duration: 236.928757ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:08:22.513391Z","caller":"traceutil/trace.go:171","msg":"trace[437799161] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:984; }","duration":"130.332902ms","start":"2026-03-05T07:08:22.383048Z","end":"2026-03-05T07:08:22.513381Z","steps":["trace[437799161] 'agreement among raft nodes before linearized reading' (duration: 130.25645ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:08:22.818718Z","caller":"traceutil/trace.go:171","msg":"trace[1839010150] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"300.449488ms","start":"2026-03-05T07:08:22.51824Z","end":"2026-03-05T07:08:22.818689Z","steps":["trace[1839010150] 'process raft request' (duration: 265.805461ms)","trace[1839010150] 'compare' (duration: 34.306579ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T07:08:22.818968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:08:22.518222Z","time spent":"300.652815ms","remote":"127.0.0.1:36966","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-03-05T07:08:22.821648Z","caller":"traceutil/trace.go:171","msg":"trace[1487116650] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"303.062217ms","start":"2026-03-05T07:08:22.518568Z","end":"2026-03-05T07:08:22.821631Z","steps":["trace[1487116650] 'process raft request' (duration: 302.951264ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:08:22.821774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:08:22.51855Z","time spent":"303.162171ms","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T07:08:22.8219Z","caller":"traceutil/trace.go:171","msg":"trace[1689525851] linearizableReadLoop","detail":"{readStateIndex:1023; appliedIndex:1020; }","duration":"221.628021ms","start":"2026-03-05T07:08:22.600226Z","end":"2026-03-05T07:08:22.821854Z","steps":["trace[1689525851] 'read index received' (duration: 183.83441ms)","trace[1689525851] 'applied index is now lower than readState.Index' (duration: 37.73965ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T07:08:22.821933Z","caller":"traceutil/trace.go:171","msg":"trace[1779755832] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"303.001856ms","start":"2026-03-05T07:08:22.518922Z","end":"2026-03-05T07:08:22.821924Z","steps":["trace[1779755832] 'process raft request' (duration: 302.677257ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:08:22.822117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:08:22.518913Z","time spent":"303.14172ms","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T07:08:22.822156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.928179ms","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-03-05T07:08:22.822899Z","caller":"traceutil/trace.go:171","msg":"trace[1988728127] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:987; }","duration":"222.705429ms","start":"2026-03-05T07:08:22.600177Z","end":"2026-03-05T07:08:22.822882Z","steps":["trace[1988728127] 'agreement among raft nodes before linearized reading' (duration: 221.781535ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:11:11.950247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.1582ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-05T07:11:11.950374Z","caller":"traceutil/trace.go:171","msg":"trace[569275388] transaction","detail":"{read_only:false; response_revision:1684; number_of_response:1; }","duration":"159.594867ms","start":"2026-03-05T07:11:11.790757Z","end":"2026-03-05T07:11:11.950352Z","steps":["trace[569275388] 'process raft request' (duration: 51.234742ms)","trace[569275388] 'compare' (duration: 107.884163ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T07:11:11.95037Z","caller":"traceutil/trace.go:171","msg":"trace[1175628125] linearizableReadLoop","detail":"{readStateIndex:1755; appliedIndex:1753; }","duration":"159.096764ms","start":"2026-03-05T07:11:11.791253Z","end":"2026-03-05T07:11:11.95035Z","steps":["trace[1175628125] 'read index received' (duration: 15.676214ms)","trace[1175628125] 'applied index is now lower than readState.Index' (duration: 143.41893ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T07:11:11.950579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.322651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-03-05T07:11:11.950636Z","caller":"traceutil/trace.go:171","msg":"trace[856781334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1684; }","duration":"159.407022ms","start":"2026-03-05T07:11:11.791214Z","end":"2026-03-05T07:11:11.950621Z","steps":["trace[856781334] 'agreement among raft nodes before linearized reading' (duration: 159.254799ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:11:11.950711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.285219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-05T07:11:11.950754Z","caller":"traceutil/trace.go:171","msg":"trace[2096353504] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1684; }","duration":"159.349141ms","start":"2026-03-05T07:11:11.791392Z","end":"2026-03-05T07:11:11.950741Z","steps":["trace[2096353504] 'agreement among raft nodes before linearized reading' (duration: 159.246119ms)"],"step_count":1}