{"level":"warn","ts":"2026-05-30T09:42:41.36467Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-30T09:42:41.364853Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.35:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.35:2380","--initial-cluster=instance=https://199.204.45.35:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.35:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.35:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-30T09:42:41.364986Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-30T09:42:41.36507Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-05-30T09:42:41.365124Z","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-05-30T09:42:41.365925Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"]} {"level":"info","ts":"2026-05-30T09:42:41.366059Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.35:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-30T09:42:41.375344Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.165ms"} {"level":"info","ts":"2026-05-30T09:42:41.393111Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fb85913747d92cac","cluster-id":"636a84d5a3ba9c31"} {"level":"info","ts":"2026-05-30T09:42:41.393313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=()"} {"level":"info","ts":"2026-05-30T09:42:41.393363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 0"} {"level":"info","ts":"2026-05-30T09:42:41.393382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fb85913747d92cac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-30T09:42:41.393431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 1"} {"level":"info","ts":"2026-05-30T09:42:41.393573Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"warn","ts":"2026-05-30T09:42:41.405307Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-30T09:42:41.408691Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-30T09:42:41.41009Z","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-05-30T09:42:41.413018Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fb85913747d92cac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-30T09:42:41.413284Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fb85913747d92cac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-30T09:42:41.416049Z","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-05-30T09:42:41.416253Z","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-05-30T09:42:41.416281Z","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-05-30T09:42:41.417615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"info","ts":"2026-05-30T09:42:41.41804Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","added-peer-id":"fb85913747d92cac","added-peer-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-05-30T09:42:41.421216Z","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-05-30T09:42:41.421468Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-05-30T09:42:41.421672Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-05-30T09:42:41.421841Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fb85913747d92cac","initial-advertise-peer-urls":["https://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-30T09:42:41.421916Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-30T09:42:42.194863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac is starting a new election at term 1"} {"level":"info","ts":"2026-05-30T09:42:42.194969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became pre-candidate at term 1"} {"level":"info","ts":"2026-05-30T09:42:42.195001Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgPreVoteResp from fb85913747d92cac at term 1"} {"level":"info","ts":"2026-05-30T09:42:42.195023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became candidate at term 2"} {"level":"info","ts":"2026-05-30T09:42:42.195034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgVoteResp from fb85913747d92cac at term 2"} {"level":"info","ts":"2026-05-30T09:42:42.195051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became leader at term 2"} {"level":"info","ts":"2026-05-30T09:42:42.195066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fb85913747d92cac elected leader fb85913747d92cac at term 2"} {"level":"info","ts":"2026-05-30T09:42:42.201249Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T09:42:42.202644Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-30T09:42:42.202674Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fb85913747d92cac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.35:2379]}","request-path":"/0/members/fb85913747d92cac/attributes","cluster-id":"636a84d5a3ba9c31","publish-timeout":"7s"} {"level":"info","ts":"2026-05-30T09:42:42.202736Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-30T09:42:42.20316Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-30T09:42:42.203195Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-30T09:42:42.204235Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T09:42:42.204368Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T09:42:42.204422Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T09:42:42.206392Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.35:2379"} {"level":"info","ts":"2026-05-30T09:42:42.206985Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-30T09:42:46.935931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.43936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-30T09:42:46.936067Z","caller":"traceutil/trace.go:171","msg":"trace[2070016224] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:291; }","duration":"109.664413ms","start":"2026-05-30T09:42:46.826375Z","end":"2026-05-30T09:42:46.93604Z","steps":["trace[2070016224] 'range keys from in-memory index tree' (duration: 109.282919ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:42:49.093473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000141536s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-30T09:42:49.093622Z","caller":"traceutil/trace.go:171","msg":"trace[814872792] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000355669s","start":"2026-05-30T09:42:47.093235Z","end":"2026-05-30T09:42:49.093591Z","steps":["trace[814872792] 'agreement among raft nodes before linearized reading' (duration: 2.000171617s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:42:49.093718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:42:47.093205Z","time spent":"2.00049654s","remote":"127.0.0.1:57138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-30T09:42:49.739412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.667735297s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:42:49.739625Z","caller":"traceutil/trace.go:171","msg":"trace[432269241] linearizableReadLoop","detail":"{readStateIndex:306; appliedIndex:305; }","duration":"2.64627592s","start":"2026-05-30T09:42:47.093292Z","end":"2026-05-30T09:42:49.739568Z","steps":["trace[432269241] 'read index received' (duration: 63.85µs)","trace[432269241] 'applied index is now lower than readState.Index' (duration: 2.64620982s)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:42:49.73975Z","caller":"traceutil/trace.go:171","msg":"trace[1885329968] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"2.712880042s","start":"2026-05-30T09:42:47.026801Z","end":"2026-05-30T09:42:49.739681Z","steps":["trace[1885329968] 'process raft request' (duration: 43.515641ms)","trace[1885329968] 'compare' (duration: 2.666346677s)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:42:49.740014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:42:47.026785Z","time spent":"2.713069053s","remote":"127.0.0.1:57360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":176,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-30T09:42:50.239903Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219122073031668631,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-30T09:42:50.740743Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219122073031668631,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-30T09:42:51.099555Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.543112457s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-30T09:42:51.293497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.609997ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-05-30T09:42:51.293649Z","caller":"traceutil/trace.go:171","msg":"trace[1328059821] transaction","detail":"{read_only:false; number_of_response:0; response_revision:297; }","duration":"3.73737515s","start":"2026-05-30T09:42:47.556245Z","end":"2026-05-30T09:42:51.29362Z","steps":["trace[1328059821] 'process raft request' (duration: 3.543568271s)","trace[1328059821] 'compare' (duration: 193.525067ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:42:51.293805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:42:47.556228Z","time spent":"3.737518552s","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T09:42:51.31439Z","caller":"traceutil/trace.go:171","msg":"trace[1924167655] linearizableReadLoop","detail":"{readStateIndex:308; appliedIndex:306; }","duration":"1.57466618s","start":"2026-05-30T09:42:49.739659Z","end":"2026-05-30T09:42:51.314326Z","steps":["trace[1924167655] 'read index received' (duration: 1.360171733s)","trace[1924167655] 'applied index is now lower than readState.Index' (duration: 214.493367ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:42:51.314534Z","caller":"traceutil/trace.go:171","msg":"trace[155942698] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"2.217732558s","start":"2026-05-30T09:42:49.096791Z","end":"2026-05-30T09:42:51.314524Z","steps":["trace[155942698] 'process raft request' (duration: 2.217343675s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:42:51.314661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:42:49.096754Z","time spent":"2.217825629s","remote":"127.0.0.1:57230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-30T09:42:51.31497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.868486342s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-30T09:42:51.314987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.570496146s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-30T09:42:51.315092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.390026711s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:42:51.315126Z","caller":"traceutil/trace.go:171","msg":"trace[1825857039] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:298; }","duration":"3.390095661s","start":"2026-05-30T09:42:47.925021Z","end":"2026-05-30T09:42:51.315117Z","steps":["trace[1825857039] 'agreement among raft nodes before linearized reading' (duration: 3.390043961s)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:42:51.315137Z","caller":"traceutil/trace.go:171","msg":"trace[358715882] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:0; response_revision:298; }","duration":"1.570705087s","start":"2026-05-30T09:42:49.744407Z","end":"2026-05-30T09:42:51.315112Z","steps":["trace[358715882] 'agreement among raft nodes before linearized reading' (duration: 1.570457975s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:42:51.31516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:42:47.924991Z","time spent":"3.390162271s","remote":"127.0.0.1:57408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-30T09:42:51.315189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:42:49.744388Z","time spent":"1.570791367s","remote":"127.0.0.1:57360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"info","ts":"2026-05-30T09:42:51.315101Z","caller":"traceutil/trace.go:171","msg":"trace[418775654] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:298; }","duration":"3.868673724s","start":"2026-05-30T09:42:47.446413Z","end":"2026-05-30T09:42:51.315087Z","steps":["trace[418775654] 'agreement among raft nodes before linearized reading' (duration: 3.868448972s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:42:51.315278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.678170119s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-30T09:42:51.315305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:42:47.446385Z","time spent":"3.868908445s","remote":"127.0.0.1:57408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-30T09:42:51.315331Z","caller":"traceutil/trace.go:171","msg":"trace[812128611] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:298; }","duration":"2.678404031s","start":"2026-05-30T09:42:48.636913Z","end":"2026-05-30T09:42:51.315317Z","steps":["trace[812128611] 'agreement among raft nodes before linearized reading' (duration: 2.678131399s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:42:51.315511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:42:48.636888Z","time spent":"2.678610023s","remote":"127.0.0.1:57408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-30T09:42:51.314987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.760391907s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"warn","ts":"2026-05-30T09:42:51.315613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.58686519s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:42:51.315802Z","caller":"traceutil/trace.go:171","msg":"trace[351098467] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:298; }","duration":"3.587093742s","start":"2026-05-30T09:42:47.728698Z","end":"2026-05-30T09:42:51.315792Z","steps":["trace[351098467] 'agreement among raft nodes before linearized reading' (duration: 3.5868897s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:42:51.316496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:42:47.728672Z","time spent":"3.587811658s","remote":"127.0.0.1:57408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-30T09:42:51.316331Z","caller":"traceutil/trace.go:171","msg":"trace[252631549] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:298; }","duration":"3.761151372s","start":"2026-05-30T09:42:47.554521Z","end":"2026-05-30T09:42:51.315673Z","steps":["trace[252631549] 'agreement among raft nodes before linearized reading' (duration: 3.760296186s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:42:51.316815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:42:47.554507Z","time spent":"3.762286662s","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":3489,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"info","ts":"2026-05-30T09:42:51.432272Z","caller":"traceutil/trace.go:171","msg":"trace[1118847789] transaction","detail":"{read_only:false; response_revision:299; number_of_response:1; }","duration":"112.137533ms","start":"2026-05-30T09:42:51.320107Z","end":"2026-05-30T09:42:51.432244Z","steps":["trace[1118847789] 'process raft request' (duration: 101.680617ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:42:51.435111Z","caller":"traceutil/trace.go:171","msg":"trace[1207306000] transaction","detail":"{read_only:false; response_revision:300; number_of_response:1; }","duration":"114.457882ms","start":"2026-05-30T09:42:51.320622Z","end":"2026-05-30T09:42:51.43508Z","steps":["trace[1207306000] 'process raft request' (duration: 114.21033ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:42:51.435192Z","caller":"traceutil/trace.go:171","msg":"trace[581320585] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"114.526163ms","start":"2026-05-30T09:42:51.320656Z","end":"2026-05-30T09:42:51.435183Z","steps":["trace[581320585] 'process raft request' (duration: 114.302161ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:42:51.435145Z","caller":"traceutil/trace.go:171","msg":"trace[471337917] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"114.21374ms","start":"2026-05-30T09:42:51.320911Z","end":"2026-05-30T09:42:51.435125Z","steps":["trace[471337917] 'process raft request' (duration: 114.096659ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:42:51.435577Z","caller":"traceutil/trace.go:171","msg":"trace[1897545741] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"114.356981ms","start":"2026-05-30T09:42:51.321168Z","end":"2026-05-30T09:42:51.435525Z","steps":["trace[1897545741] 'process raft request' (duration: 113.887748ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:42:53.320694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.216786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/expand-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:42:53.32079Z","caller":"traceutil/trace.go:171","msg":"trace[2064197595] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/expand-controller; range_end:; response_count:0; response_revision:331; }","duration":"180.352226ms","start":"2026-05-30T09:42:53.140412Z","end":"2026-05-30T09:42:53.320764Z","steps":["trace[2064197595] 'range keys from in-memory index tree' (duration: 180.130155ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:42:53.639697Z","caller":"traceutil/trace.go:171","msg":"trace[800769349] linearizableReadLoop","detail":"{readStateIndex:345; appliedIndex:344; }","duration":"149.660168ms","start":"2026-05-30T09:42:53.490004Z","end":"2026-05-30T09:42:53.639665Z","steps":["trace[800769349] 'read index received' (duration: 103.246594ms)","trace[800769349] 'applied index is now lower than readState.Index' (duration: 46.412464ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:42:53.639758Z","caller":"traceutil/trace.go:171","msg":"trace[882021412] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"169.64057ms","start":"2026-05-30T09:42:53.470051Z","end":"2026-05-30T09:42:53.639692Z","steps":["trace[882021412] 'process raft request' (duration: 123.148665ms)","trace[882021412] 'compare' (duration: 46.327583ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:42:53.639872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.98719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-30T09:42:53.639866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.83255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-30T09:42:53.639907Z","caller":"traceutil/trace.go:171","msg":"trace[521937176] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:335; }","duration":"130.05442ms","start":"2026-05-30T09:42:53.509843Z","end":"2026-05-30T09:42:53.639897Z","steps":["trace[521937176] 'agreement among raft nodes before linearized reading' (duration: 129.99833ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:42:53.639925Z","caller":"traceutil/trace.go:171","msg":"trace[1916794782] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:335; }","duration":"149.971461ms","start":"2026-05-30T09:42:53.489941Z","end":"2026-05-30T09:42:53.639913Z","steps":["trace[1916794782] 'agreement among raft nodes before linearized reading' (duration: 149.82339ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:42:53.639974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.909178ms","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-05-30T09:42:53.640071Z","caller":"traceutil/trace.go:171","msg":"trace[1834078289] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:335; }","duration":"130.06482ms","start":"2026-05-30T09:42:53.509982Z","end":"2026-05-30T09:42:53.640047Z","steps":["trace[1834078289] 'agreement among raft nodes before linearized reading' (duration: 129.914379ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:42:53.768006Z","caller":"traceutil/trace.go:171","msg":"trace[447814868] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"123.747359ms","start":"2026-05-30T09:42:53.644214Z","end":"2026-05-30T09:42:53.767962Z","steps":["trace[447814868] 'process raft request' (duration: 104.589114ms)","trace[447814868] 'compare' (duration: 18.995264ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:42:55.760063Z","caller":"traceutil/trace.go:171","msg":"trace[779729929] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"177.055404ms","start":"2026-05-30T09:42:55.582973Z","end":"2026-05-30T09:42:55.760029Z","steps":["trace[779729929] 'process raft request' (duration: 176.830725ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:42:55.760313Z","caller":"traceutil/trace.go:171","msg":"trace[1859866261] linearizableReadLoop","detail":"{readStateIndex:404; appliedIndex:403; }","duration":"117.182147ms","start":"2026-05-30T09:42:55.643117Z","end":"2026-05-30T09:42:55.760299Z","steps":["trace[1859866261] 'read index received' (duration: 116.589243ms)","trace[1859866261] 'applied index is now lower than readState.Index' (duration: 591.914µs)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:42:55.760501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.354184ms","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-05-30T09:42:55.760552Z","caller":"traceutil/trace.go:171","msg":"trace[849678124] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:391; }","duration":"117.459588ms","start":"2026-05-30T09:42:55.643078Z","end":"2026-05-30T09:42:55.760538Z","steps":["trace[849678124] 'agreement among raft nodes before linearized reading' (duration: 117.312292ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:42:55.877797Z","caller":"traceutil/trace.go:171","msg":"trace[663038343] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"112.364105ms","start":"2026-05-30T09:42:55.765403Z","end":"2026-05-30T09:42:55.877767Z","steps":["trace[663038343] 'process raft request' (duration: 98.576665ms)","trace[663038343] 'compare' (duration: 13.679045ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:42:55.87977Z","caller":"traceutil/trace.go:171","msg":"trace[1786773833] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"112.906759ms","start":"2026-05-30T09:42:55.766848Z","end":"2026-05-30T09:42:55.879754Z","steps":["trace[1786773833] 'process raft request' (duration: 112.870007ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:42:55.880063Z","caller":"traceutil/trace.go:171","msg":"trace[1599743038] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"113.896219ms","start":"2026-05-30T09:42:55.766157Z","end":"2026-05-30T09:42:55.880053Z","steps":["trace[1599743038] 'process raft request' (duration: 113.45097ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:42:55.880349Z","caller":"traceutil/trace.go:171","msg":"trace[1804933721] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"113.810276ms","start":"2026-05-30T09:42:55.766529Z","end":"2026-05-30T09:42:55.880339Z","steps":["trace[1804933721] 'process raft request' (duration: 113.145498ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:43:18.655374Z","caller":"traceutil/trace.go:171","msg":"trace[1500834025] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"144.070841ms","start":"2026-05-30T09:43:18.511264Z","end":"2026-05-30T09:43:18.655335Z","steps":["trace[1500834025] 'process raft request' (duration: 143.566215ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:43:19.00791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.516983ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:43:19.008073Z","caller":"traceutil/trace.go:171","msg":"trace[1507730696] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"319.985472ms","start":"2026-05-30T09:43:18.688063Z","end":"2026-05-30T09:43:19.008048Z","steps":["trace[1507730696] 'process raft request' (duration: 214.080956ms)","trace[1507730696] 'compare' (duration: 104.962624ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:43:19.008139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:43:18.688027Z","time spent":"320.082194ms","remote":"127.0.0.1:57256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6516,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T09:43:19.295947Z","caller":"traceutil/trace.go:171","msg":"trace[1169324607] linearizableReadLoop","detail":"{readStateIndex:507; appliedIndex:505; }","duration":"201.120705ms","start":"2026-05-30T09:43:19.094794Z","end":"2026-05-30T09:43:19.295915Z","steps":["trace[1169324607] 'read index received' (duration: 155.317056ms)","trace[1169324607] 'applied index is now lower than readState.Index' (duration: 45.802559ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:43:19.296036Z","caller":"traceutil/trace.go:171","msg":"trace[498524171] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"282.858014ms","start":"2026-05-30T09:43:19.013131Z","end":"2026-05-30T09:43:19.295989Z","steps":["trace[498524171] 'process raft request' (duration: 282.746181ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:43:19.296134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.319301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:43:19.296221Z","caller":"traceutil/trace.go:171","msg":"trace[2005875363] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"283.192595ms","start":"2026-05-30T09:43:19.012948Z","end":"2026-05-30T09:43:19.296141Z","steps":["trace[2005875363] 'process raft request' (duration: 237.149289ms)","trace[2005875363] 'compare' (duration: 45.571501ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:43:19.296564Z","caller":"traceutil/trace.go:171","msg":"trace[128808180] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:489; }","duration":"201.624112ms","start":"2026-05-30T09:43:19.094739Z","end":"2026-05-30T09:43:19.296363Z","steps":["trace[128808180] 'agreement among raft nodes before linearized reading' (duration: 201.303891ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:43:20.516321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.181748ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:43:20.516724Z","caller":"traceutil/trace.go:171","msg":"trace[1934162939] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"399.181367ms","start":"2026-05-30T09:43:20.117509Z","end":"2026-05-30T09:43:20.51669Z","steps":["trace[1934162939] 'process raft request' (duration: 398.969841ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:43:20.516724Z","caller":"traceutil/trace.go:171","msg":"trace[632959615] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"402.672419ms","start":"2026-05-30T09:43:20.114018Z","end":"2026-05-30T09:43:20.51669Z","steps":["trace[632959615] 'process raft request' (duration: 267.803239ms)","trace[632959615] 'compare' (duration: 133.888229ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:43:20.516877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:43:20.117492Z","time spent":"399.302491ms","remote":"127.0.0.1:57408","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-05-30T09:43:20.516944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:43:20.113992Z","time spent":"402.803914ms","remote":"127.0.0.1:57408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:43:21.062623Z","caller":"traceutil/trace.go:171","msg":"trace[193127057] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:538; }","duration":"132.822757ms","start":"2026-05-30T09:43:20.92977Z","end":"2026-05-30T09:43:21.062593Z","steps":["trace[193127057] 'read index received' (duration: 132.479146ms)","trace[193127057] 'applied index is now lower than readState.Index' (duration: 342.031µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:43:21.062675Z","caller":"traceutil/trace.go:171","msg":"trace[417829569] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"143.347433ms","start":"2026-05-30T09:43:20.919275Z","end":"2026-05-30T09:43:21.062622Z","steps":["trace[417829569] 'process raft request' (duration: 143.100745ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:43:21.062893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.083965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:43:21.062996Z","caller":"traceutil/trace.go:171","msg":"trace[1374620586] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:520; }","duration":"133.24931ms","start":"2026-05-30T09:43:20.929721Z","end":"2026-05-30T09:43:21.06297Z","steps":["trace[1374620586] 'agreement among raft nodes before linearized reading' (duration: 132.968521ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:43:21.629433Z","caller":"traceutil/trace.go:171","msg":"trace[837014539] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"114.681059ms","start":"2026-05-30T09:43:21.514718Z","end":"2026-05-30T09:43:21.629399Z","steps":["trace[837014539] 'process raft request' (duration: 107.41811ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:43:22.680734Z","caller":"traceutil/trace.go:171","msg":"trace[1508037345] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:609; }","duration":"159.483302ms","start":"2026-05-30T09:43:22.521221Z","end":"2026-05-30T09:43:22.680704Z","steps":["trace[1508037345] 'read index received' (duration: 158.566313ms)","trace[1508037345] 'applied index is now lower than readState.Index' (duration: 916.119µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:43:22.680834Z","caller":"traceutil/trace.go:171","msg":"trace[182763544] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"380.888714ms","start":"2026-05-30T09:43:22.299906Z","end":"2026-05-30T09:43:22.680795Z","steps":["trace[182763544] 'process raft request' (duration: 380.252814ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:43:22.680957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.823792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-30T09:43:22.681155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:43:22.299867Z","time spent":"381.147532ms","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T09:43:22.68121Z","caller":"traceutil/trace.go:171","msg":"trace[1658628415] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:590; }","duration":"159.115521ms","start":"2026-05-30T09:43:22.522044Z","end":"2026-05-30T09:43:22.681159Z","steps":["trace[1658628415] 'agreement among raft nodes before linearized reading' (duration: 158.811071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:43:22.681368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.02932ms","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-05-30T09:43:22.681543Z","caller":"traceutil/trace.go:171","msg":"trace[1236261021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:590; }","duration":"160.307008ms","start":"2026-05-30T09:43:22.521156Z","end":"2026-05-30T09:43:22.681463Z","steps":["trace[1236261021] 'agreement among raft nodes before linearized reading' (duration: 159.71297ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:43:23.127733Z","caller":"traceutil/trace.go:171","msg":"trace[832685525] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"209.020337ms","start":"2026-05-30T09:43:22.918685Z","end":"2026-05-30T09:43:23.127706Z","steps":["trace[832685525] 'process raft request' (duration: 208.622555ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:43:23.137954Z","caller":"traceutil/trace.go:171","msg":"trace[1143641661] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"178.685344ms","start":"2026-05-30T09:43:22.959247Z","end":"2026-05-30T09:43:23.137932Z","steps":["trace[1143641661] 'process raft request' (duration: 178.510509ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:43:25.754038Z","caller":"traceutil/trace.go:171","msg":"trace[902396400] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"100.033599ms","start":"2026-05-30T09:43:25.653967Z","end":"2026-05-30T09:43:25.754001Z","steps":["trace[902396400] 'process raft request' (duration: 99.297696ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:43:26.523878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.855282ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:43:26.524007Z","caller":"traceutil/trace.go:171","msg":"trace[1805475483] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:633; }","duration":"359.582965ms","start":"2026-05-30T09:43:26.164398Z","end":"2026-05-30T09:43:26.523981Z","steps":["trace[1805475483] 'read index received' (duration: 51.062µs)","trace[1805475483] 'applied index is now lower than readState.Index' (duration: 359.530053ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:43:26.524043Z","caller":"traceutil/trace.go:171","msg":"trace[1336353191] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"714.007624ms","start":"2026-05-30T09:43:25.809979Z","end":"2026-05-30T09:43:26.523986Z","steps":["trace[1336353191] 'process raft request' (duration: 236.943076ms)","trace[1336353191] 'compare' (duration: 476.567123ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:43:26.524241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:43:25.809945Z","time spent":"714.19608ms","remote":"127.0.0.1:57360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-30T09:43:26.524253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.879014ms","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-05-30T09:43:26.524291Z","caller":"traceutil/trace.go:171","msg":"trace[965132585] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:613; }","duration":"359.962686ms","start":"2026-05-30T09:43:26.164316Z","end":"2026-05-30T09:43:26.524279Z","steps":["trace[965132585] 'agreement among raft nodes before linearized reading' (duration: 359.731219ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:43:26.524327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:43:26.164265Z","time spent":"360.053949ms","remote":"127.0.0.1:57408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-30T09:43:26.523565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.155438ms","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-05-30T09:43:26.524712Z","caller":"traceutil/trace.go:171","msg":"trace[1837444828] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:612; }","duration":"306.349434ms","start":"2026-05-30T09:43:26.218346Z","end":"2026-05-30T09:43:26.524696Z","steps":["trace[1837444828] 'range keys from in-memory index tree' (duration: 305.121807ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:43:29.844813Z","caller":"traceutil/trace.go:171","msg":"trace[462306538] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"176.761351ms","start":"2026-05-30T09:43:29.668017Z","end":"2026-05-30T09:43:29.844779Z","steps":["trace[462306538] 'read index received' (duration: 175.204255ms)","trace[462306538] 'applied index is now lower than readState.Index' (duration: 1.555736ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:43:29.844887Z","caller":"traceutil/trace.go:171","msg":"trace[908024886] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"467.019586ms","start":"2026-05-30T09:43:29.377809Z","end":"2026-05-30T09:43:29.844829Z","steps":["trace[908024886] 'process raft request' (duration: 465.799711ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:43:29.84545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:43:29.377764Z","time spent":"467.491489ms","remote":"127.0.0.1:57250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:43:29.845504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.385618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wzwbv\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-30T09:43:29.845848Z","caller":"traceutil/trace.go:171","msg":"trace[524927535] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wzwbv; range_end:; response_count:1; response_revision:635; }","duration":"177.868343ms","start":"2026-05-30T09:43:29.667955Z","end":"2026-05-30T09:43:29.845823Z","steps":["trace[524927535] 'agreement among raft nodes before linearized reading' (duration: 177.161602ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:43:29.964114Z","caller":"traceutil/trace.go:171","msg":"trace[1879106148] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"114.796893ms","start":"2026-05-30T09:43:29.849279Z","end":"2026-05-30T09:43:29.964076Z","steps":["trace[1879106148] 'process raft request' (duration: 114.542594ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:43:30.594963Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219122073031669375,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-30T09:43:31.260231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.055541ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:43:31.261181Z","caller":"traceutil/trace.go:171","msg":"trace[180280432] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"1.401164983s","start":"2026-05-30T09:43:29.859984Z","end":"2026-05-30T09:43:31.261149Z","steps":["trace[180280432] 'process raft request' (duration: 858.082842ms)","trace[180280432] 'compare' (duration: 541.915127ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:43:31.261381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:43:29.859972Z","time spent":"1.401349078s","remote":"127.0.0.1:57332","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-05-30T09:43:31.261404Z","caller":"traceutil/trace.go:171","msg":"trace[217091885] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"1.360467244s","start":"2026-05-30T09:43:29.900892Z","end":"2026-05-30T09:43:31.261359Z","steps":["trace[217091885] 'process raft request' (duration: 1.359459174s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:43:31.2618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:43:29.900854Z","time spent":"1.360789503s","remote":"127.0.0.1:57390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T09:43:31.27606Z","caller":"traceutil/trace.go:171","msg":"trace[1973424726] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:657; }","duration":"1.181144385s","start":"2026-05-30T09:43:30.094881Z","end":"2026-05-30T09:43:31.276025Z","steps":["trace[1973424726] 'read index received' (duration: 623.202969ms)","trace[1973424726] 'applied index is now lower than readState.Index' (duration: 557.939456ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:43:31.276274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.181370632s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:43:31.276317Z","caller":"traceutil/trace.go:171","msg":"trace[1286745567] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"1.181480495s","start":"2026-05-30T09:43:30.094825Z","end":"2026-05-30T09:43:31.276305Z","steps":["trace[1286745567] 'agreement among raft nodes before linearized reading' (duration: 1.181374052s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:43:31.276358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:43:30.094798Z","time spent":"1.181549977s","remote":"127.0.0.1:57136","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-05-30T09:43:31.276509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.786312ms","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-05-30T09:43:31.2766Z","caller":"traceutil/trace.go:171","msg":"trace[1840740814] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:638; }","duration":"713.928136ms","start":"2026-05-30T09:43:30.562649Z","end":"2026-05-30T09:43:31.276577Z","steps":["trace[1840740814] 'agreement among raft nodes before linearized reading' (duration: 713.732891ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:43:31.27664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:43:30.562624Z","time spent":"714.007289ms","remote":"127.0.0.1:57408","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-05-30T09:43:31.276854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.795262ms","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-05-30T09:43:31.276931Z","caller":"traceutil/trace.go:171","msg":"trace[1699070244] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:638; }","duration":"538.927376ms","start":"2026-05-30T09:43:30.737992Z","end":"2026-05-30T09:43:31.276919Z","steps":["trace[1699070244] 'agreement among raft nodes before linearized reading' (duration: 538.785042ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:43:31.276874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.787883ms","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-05-30T09:43:31.277066Z","caller":"traceutil/trace.go:171","msg":"trace[2001654975] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:638; }","duration":"528.197235ms","start":"2026-05-30T09:43:30.748846Z","end":"2026-05-30T09:43:31.277043Z","steps":["trace[2001654975] 'agreement among raft nodes before linearized reading' (duration: 527.674491ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:43:31.277132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:43:30.748821Z","time spent":"528.289867ms","remote":"127.0.0.1:57408","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-05-30T09:43:31.277008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:43:30.737963Z","time spent":"539.012949ms","remote":"127.0.0.1:57408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-30T09:43:33.074824Z","caller":"traceutil/trace.go:171","msg":"trace[953166077] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"134.186247ms","start":"2026-05-30T09:43:32.940604Z","end":"2026-05-30T09:43:33.07479Z","steps":["trace[953166077] 'process raft request' (duration: 134.021783ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:43:42.016717Z","caller":"traceutil/trace.go:171","msg":"trace[657786405] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"145.760477ms","start":"2026-05-30T09:43:41.870924Z","end":"2026-05-30T09:43:42.016684Z","steps":["trace[657786405] 'process raft request' (duration: 145.561592ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:43:46.925202Z","caller":"traceutil/trace.go:171","msg":"trace[1541285705] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"271.49794ms","start":"2026-05-30T09:43:46.653666Z","end":"2026-05-30T09:43:46.925164Z","steps":["trace[1541285705] 'process raft request' (duration: 271.290545ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:43:50.390992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"884.994542ms","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-05-30T09:43:50.391096Z","caller":"traceutil/trace.go:171","msg":"trace[1697158457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:808; }","duration":"885.162146ms","start":"2026-05-30T09:43:49.505911Z","end":"2026-05-30T09:43:50.391074Z","steps":["trace[1697158457] 'range keys from in-memory index tree' (duration: 884.787297ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:43:50.391142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:43:49.505883Z","time spent":"885.248198ms","remote":"127.0.0.1:57408","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-05-30T09:43:50.39177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"885.679069ms","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-05-30T09:43:50.39188Z","caller":"traceutil/trace.go:171","msg":"trace[621820931] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:808; }","duration":"885.846493ms","start":"2026-05-30T09:43:49.506008Z","end":"2026-05-30T09:43:50.391854Z","steps":["trace[621820931] 'range keys from in-memory index tree' (duration: 885.367871ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:43:50.391931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:43:49.505978Z","time spent":"885.943776ms","remote":"127.0.0.1:57408","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-05-30T09:43:50.392528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.18326ms","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-05-30T09:43:50.392576Z","caller":"traceutil/trace.go:171","msg":"trace[230073337] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:808; }","duration":"886.256423ms","start":"2026-05-30T09:43:49.506307Z","end":"2026-05-30T09:43:50.392563Z","steps":["trace[230073337] 'range keys from in-memory index tree' (duration: 885.290538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:43:50.392613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:43:49.506296Z","time spent":"886.304114ms","remote":"127.0.0.1:57408","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-05-30T09:43:50.596849Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219122073031669722,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-30T09:43:50.705289Z","caller":"traceutil/trace.go:171","msg":"trace[1652604083] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"925.47108ms","start":"2026-05-30T09:43:49.779792Z","end":"2026-05-30T09:43:50.705263Z","steps":["trace[1652604083] 'process raft request' (duration: 925.293126ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:43:50.705468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:43:49.779755Z","time spent":"925.606174ms","remote":"127.0.0.1:57390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:43:50.706009Z","caller":"traceutil/trace.go:171","msg":"trace[1992414354] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"310.214293ms","start":"2026-05-30T09:43:50.395762Z","end":"2026-05-30T09:43:50.705976Z","steps":["trace[1992414354] 'process raft request' (duration: 310.016868ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:43:50.706177Z","caller":"traceutil/trace.go:171","msg":"trace[1662006702] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"310.200822ms","start":"2026-05-30T09:43:50.395961Z","end":"2026-05-30T09:43:50.706162Z","steps":["trace[1662006702] 'process raft request' (duration: 309.970777ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:43:50.706374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:43:50.395937Z","time spent":"310.276914ms","remote":"127.0.0.1:57408","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-05-30T09:43:50.706778Z","caller":"traceutil/trace.go:171","msg":"trace[189836062] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"309.935886ms","start":"2026-05-30T09:43:50.396626Z","end":"2026-05-30T09:43:50.706562Z","steps":["trace[189836062] 'process raft request' (duration: 309.491115ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:43:50.707121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:43:50.39662Z","time spent":"310.245524ms","remote":"127.0.0.1:57408","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-05-30T09:43:50.707186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:43:50.395737Z","time spent":"311.372791ms","remote":"127.0.0.1:57408","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-05-30T09:43:50.709085Z","caller":"traceutil/trace.go:171","msg":"trace[733126440] linearizableReadLoop","detail":"{readStateIndex:840; appliedIndex:835; }","duration":"613.229002ms","start":"2026-05-30T09:43:50.095827Z","end":"2026-05-30T09:43:50.709056Z","steps":["trace[733126440] 'read index received' (duration: 609.292566ms)","trace[733126440] 'applied index is now lower than readState.Index' (duration: 3.934936ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:43:50.709195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.369805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:43:50.709247Z","caller":"traceutil/trace.go:171","msg":"trace[439269995] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:812; }","duration":"613.540879ms","start":"2026-05-30T09:43:50.095693Z","end":"2026-05-30T09:43:50.709234Z","steps":["trace[439269995] 'agreement among raft nodes before linearized reading' (duration: 613.451647ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:43:50.709279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:43:50.095667Z","time spent":"613.605331ms","remote":"127.0.0.1:57136","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-05-30T09:43:50.709367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.964591ms","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-05-30T09:43:50.709399Z","caller":"traceutil/trace.go:171","msg":"trace[958387651] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:812; }","duration":"585.080144ms","start":"2026-05-30T09:43:50.124311Z","end":"2026-05-30T09:43:50.709391Z","steps":["trace[958387651] 'agreement among raft nodes before linearized reading' (duration: 584.90519ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:43:50.709426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:43:50.124284Z","time spent":"585.135506ms","remote":"127.0.0.1:57408","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-05-30T09:44:04.218042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.152269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:44:04.218132Z","caller":"traceutil/trace.go:171","msg":"trace[741807983] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:970; }","duration":"123.322633ms","start":"2026-05-30T09:44:04.094791Z","end":"2026-05-30T09:44:04.218114Z","steps":["trace[741807983] 'range keys from in-memory index tree' (duration: 123.075018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:44:04.218157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.609867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-30T09:44:04.218272Z","caller":"traceutil/trace.go:171","msg":"trace[1803760944] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:970; }","duration":"181.76929ms","start":"2026-05-30T09:44:04.036473Z","end":"2026-05-30T09:44:04.218243Z","steps":["trace[1803760944] 'count revisions from in-memory index tree' (duration: 181.545285ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:44:21.124958Z","caller":"traceutil/trace.go:171","msg":"trace[630328543] linearizableReadLoop","detail":"{readStateIndex:1072; appliedIndex:1070; }","duration":"114.956681ms","start":"2026-05-30T09:44:21.009984Z","end":"2026-05-30T09:44:21.124941Z","steps":["trace[630328543] 'read index received' (duration: 3.841664ms)","trace[630328543] 'applied index is now lower than readState.Index' (duration: 111.114427ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:44:21.125049Z","caller":"traceutil/trace.go:171","msg":"trace[897181140] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"127.280656ms","start":"2026-05-30T09:44:20.997762Z","end":"2026-05-30T09:44:21.125043Z","steps":["trace[897181140] 'process raft request' (duration: 72.424791ms)","trace[897181140] 'compare' (duration: 54.567489ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:44:21.125295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.300797ms","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-05-30T09:44:21.125357Z","caller":"traceutil/trace.go:171","msg":"trace[1574168006] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1037; }","duration":"115.394818ms","start":"2026-05-30T09:44:21.009948Z","end":"2026-05-30T09:44:21.125343Z","steps":["trace[1574168006] 'agreement among raft nodes before linearized reading' (duration: 115.279356ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:44:21.292823Z","caller":"traceutil/trace.go:171","msg":"trace[1529235023] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"161.473036ms","start":"2026-05-30T09:44:21.131331Z","end":"2026-05-30T09:44:21.292804Z","steps":["trace[1529235023] 'process raft request' (duration: 161.351774ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:44:21.292836Z","caller":"traceutil/trace.go:171","msg":"trace[794191849] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"161.795822ms","start":"2026-05-30T09:44:21.130998Z","end":"2026-05-30T09:44:21.292794Z","steps":["trace[794191849] 'process raft request' (duration: 160.769563ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:44:21.292752Z","caller":"traceutil/trace.go:171","msg":"trace[1402402023] linearizableReadLoop","detail":"{readStateIndex:1075; appliedIndex:1072; }","duration":"160.024549ms","start":"2026-05-30T09:44:21.132705Z","end":"2026-05-30T09:44:21.292729Z","steps":["trace[1402402023] 'read index received' (duration: 50.105375ms)","trace[1402402023] 'applied index is now lower than readState.Index' (duration: 109.918094ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:44:21.293183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.448707ms","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-05-30T09:44:21.293247Z","caller":"traceutil/trace.go:171","msg":"trace[856997646] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1040; }","duration":"160.549619ms","start":"2026-05-30T09:44:21.132682Z","end":"2026-05-30T09:44:21.293231Z","steps":["trace[856997646] 'agreement among raft nodes before linearized reading' (duration: 160.370036ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:44:45.671388Z","caller":"traceutil/trace.go:171","msg":"trace[1669047901] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"483.75929ms","start":"2026-05-30T09:44:45.187603Z","end":"2026-05-30T09:44:45.671362Z","steps":["trace[1669047901] 'process raft request' (duration: 483.564287ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:44:45.671554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:44:45.187583Z","time spent":"483.901222ms","remote":"127.0.0.1:57408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:44:46.035408Z","caller":"traceutil/trace.go:171","msg":"trace[1956112173] linearizableReadLoop","detail":"{readStateIndex:1159; appliedIndex:1157; }","duration":"737.612445ms","start":"2026-05-30T09:44:45.297767Z","end":"2026-05-30T09:44:46.035379Z","steps":["trace[1956112173] 'read index received' (duration: 373.381695ms)","trace[1956112173] 'applied index is now lower than readState.Index' (duration: 364.22942ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:44:46.035613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.816598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:44:46.035702Z","caller":"traceutil/trace.go:171","msg":"trace[1722182709] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1119; }","duration":"737.960731ms","start":"2026-05-30T09:44:45.29772Z","end":"2026-05-30T09:44:46.035681Z","steps":["trace[1722182709] 'agreement among raft nodes before linearized reading' (duration: 737.800989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:44:46.035751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:44:45.29769Z","time spent":"738.050872ms","remote":"127.0.0.1:57432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-30T09:44:46.035874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.453727ms","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-05-30T09:44:46.035908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.83848ms","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-05-30T09:44:46.03595Z","caller":"traceutil/trace.go:171","msg":"trace[1109029760] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1119; }","duration":"470.585039ms","start":"2026-05-30T09:44:45.565348Z","end":"2026-05-30T09:44:46.035933Z","steps":["trace[1109029760] 'agreement among raft nodes before linearized reading' (duration: 470.397676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:44:46.035902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.964464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-30T09:44:46.036052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.266314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.35\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-30T09:44:46.036076Z","caller":"traceutil/trace.go:171","msg":"trace[550456553] range","detail":"{range_begin:/registry/masterleases/199.204.45.35; range_end:; response_count:1; response_revision:1119; }","duration":"548.313604ms","start":"2026-05-30T09:44:45.487756Z","end":"2026-05-30T09:44:46.03607Z","steps":["trace[550456553] 'agreement among raft nodes before linearized reading' (duration: 548.251213ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:44:46.03603Z","caller":"traceutil/trace.go:171","msg":"trace[188763194] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1119; }","duration":"552.986372ms","start":"2026-05-30T09:44:45.483023Z","end":"2026-05-30T09:44:46.036009Z","steps":["trace[188763194] 'agreement among raft nodes before linearized reading' (duration: 552.677557ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:44:46.036036Z","caller":"traceutil/trace.go:171","msg":"trace[2034878620] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1119; }","duration":"607.224899ms","start":"2026-05-30T09:44:45.428791Z","end":"2026-05-30T09:44:46.036015Z","steps":["trace[2034878620] 'agreement among raft nodes before linearized reading' (duration: 606.965264ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:44:46.036097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:44:45.487742Z","time spent":"548.351255ms","remote":"127.0.0.1:57194","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.35\" "} {"level":"warn","ts":"2026-05-30T09:44:46.03612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:44:45.483001Z","time spent":"553.104384ms","remote":"127.0.0.1:57408","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-05-30T09:44:46.036165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:44:45.428763Z","time spent":"607.356691ms","remote":"127.0.0.1:57270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":6,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-05-30T09:44:46.035983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:44:45.565323Z","time spent":"470.65303ms","remote":"127.0.0.1:57408","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-05-30T09:44:46.036266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.671337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-30T09:44:46.036119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.223396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-30T09:44:46.036353Z","caller":"traceutil/trace.go:171","msg":"trace[576075672] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1119; }","duration":"728.804158ms","start":"2026-05-30T09:44:45.307517Z","end":"2026-05-30T09:44:46.036321Z","steps":["trace[576075672] 'agreement among raft nodes before linearized reading' (duration: 728.245419ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:44:46.036358Z","caller":"traceutil/trace.go:171","msg":"trace[1217032971] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1119; }","duration":"556.48689ms","start":"2026-05-30T09:44:45.479863Z","end":"2026-05-30T09:44:46.03635Z","steps":["trace[1217032971] 'agreement among raft nodes before linearized reading' (duration: 556.212726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:44:46.036388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:44:45.479855Z","time spent":"556.5241ms","remote":"127.0.0.1:57332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":12,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-05-30T09:44:46.036391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:44:45.307508Z","time spent":"728.873779ms","remote":"127.0.0.1:57408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-30T09:44:46.036296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.239597ms","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-05-30T09:44:46.036614Z","caller":"traceutil/trace.go:171","msg":"trace[76195595] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1119; }","duration":"596.960229ms","start":"2026-05-30T09:44:45.439639Z","end":"2026-05-30T09:44:46.036599Z","steps":["trace[76195595] 'agreement among raft nodes before linearized reading' (duration: 596.115825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:44:46.036681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:44:45.439623Z","time spent":"597.04477ms","remote":"127.0.0.1:57408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-30T09:44:46.035608Z","caller":"traceutil/trace.go:171","msg":"trace[213526511] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"742.1714ms","start":"2026-05-30T09:44:45.293317Z","end":"2026-05-30T09:44:46.035488Z","steps":["trace[213526511] 'process raft request' (duration: 741.812414ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:44:46.037725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:44:45.293291Z","time spent":"744.371935ms","remote":"127.0.0.1:57408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:44:48.19021Z","caller":"traceutil/trace.go:171","msg":"trace[2023866200] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"121.395837ms","start":"2026-05-30T09:44:48.068781Z","end":"2026-05-30T09:44:48.190177Z","steps":["trace[2023866200] 'process raft request' (duration: 33.335093ms)","trace[2023866200] 'compare' (duration: 87.664498ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:44:48.194804Z","caller":"traceutil/trace.go:171","msg":"trace[207177952] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"125.849489ms","start":"2026-05-30T09:44:48.06893Z","end":"2026-05-30T09:44:48.194779Z","steps":["trace[207177952] 'process raft request' (duration: 125.738657ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:44:48.194819Z","caller":"traceutil/trace.go:171","msg":"trace[573464183] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"125.9196ms","start":"2026-05-30T09:44:48.068843Z","end":"2026-05-30T09:44:48.194762Z","steps":["trace[573464183] 'process raft request' (duration: 125.688176ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:44:48.19508Z","caller":"traceutil/trace.go:171","msg":"trace[1235889244] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"125.949661ms","start":"2026-05-30T09:44:48.069081Z","end":"2026-05-30T09:44:48.195031Z","steps":["trace[1235889244] 'process raft request' (duration: 125.630825ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:45:15.994728Z","caller":"traceutil/trace.go:171","msg":"trace[1248400860] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"158.821717ms","start":"2026-05-30T09:45:15.835877Z","end":"2026-05-30T09:45:15.994698Z","steps":["trace[1248400860] 'process raft request' (duration: 158.631985ms)"],"step_count":1}