{"level":"warn","ts":"2026-04-20T14:08:35.270431Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T14:08:35.270552Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.194: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.19.213.194:2380","--initial-cluster=instance=https://199.19.213.194:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.194:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.194:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-20T14:08:35.270644Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T14:08:35.270657Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.194:2380"]} {"level":"info","ts":"2026-04-20T14:08:35.270679Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-20T14:08:35.27178Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.194:2379"]} {"level":"info","ts":"2026-04-20T14:08:35.271919Z","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.19.213.194:2380"],"listen-peer-urls":["https://199.19.213.194:2380"],"advertise-client-urls":["https://199.19.213.194:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.194:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.194:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-20T14:08:35.281034Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.647115ms"} {"level":"info","ts":"2026-04-20T14:08:35.29502Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1444e0f5dfca45ef","cluster-id":"46f0df2abd5471c9"} {"level":"info","ts":"2026-04-20T14:08:35.295124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T14:08:35.295144Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef became follower at term 0"} {"level":"info","ts":"2026-04-20T14:08:35.295154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1444e0f5dfca45ef [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T14:08:35.295162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef became follower at term 1"} {"level":"info","ts":"2026-04-20T14:08:35.295212Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef switched to configuration voters=(1460539525801068015)"} {"level":"warn","ts":"2026-04-20T14:08:35.310444Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T14:08:35.31503Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T14:08:35.316546Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-20T14:08:35.318593Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1444e0f5dfca45ef","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T14:08:35.319314Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1444e0f5dfca45ef","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T14:08:35.319771Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-20T14:08:35.319831Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-20T14:08:35.319851Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-20T14:08:35.32085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef switched to configuration voters=(1460539525801068015)"} {"level":"info","ts":"2026-04-20T14:08:35.320957Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"46f0df2abd5471c9","local-member-id":"1444e0f5dfca45ef","added-peer-id":"1444e0f5dfca45ef","added-peer-peer-urls":["https://199.19.213.194:2380"]} {"level":"info","ts":"2026-04-20T14:08:35.322465Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-20T14:08:35.32256Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.194:2380"} {"level":"info","ts":"2026-04-20T14:08:35.32258Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.194:2380"} {"level":"info","ts":"2026-04-20T14:08:35.322673Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1444e0f5dfca45ef","initial-advertise-peer-urls":["https://199.19.213.194:2380"],"listen-peer-urls":["https://199.19.213.194:2380"],"advertise-client-urls":["https://199.19.213.194:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.194:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T14:08:35.322716Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T14:08:35.495684Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T14:08:35.495762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T14:08:35.495791Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef received MsgPreVoteResp from 1444e0f5dfca45ef at term 1"} {"level":"info","ts":"2026-04-20T14:08:35.495818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef became candidate at term 2"} {"level":"info","ts":"2026-04-20T14:08:35.495832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef received MsgVoteResp from 1444e0f5dfca45ef at term 2"} {"level":"info","ts":"2026-04-20T14:08:35.495891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef became leader at term 2"} {"level":"info","ts":"2026-04-20T14:08:35.495912Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1444e0f5dfca45ef elected leader 1444e0f5dfca45ef at term 2"} {"level":"info","ts":"2026-04-20T14:08:35.497298Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1444e0f5dfca45ef","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.194:2379]}","request-path":"/0/members/1444e0f5dfca45ef/attributes","cluster-id":"46f0df2abd5471c9","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T14:08:35.497339Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T14:08:35.497998Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T14:08:35.498154Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T14:08:35.498248Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T14:08:35.498268Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T14:08:35.500711Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"46f0df2abd5471c9","local-member-id":"1444e0f5dfca45ef","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T14:08:35.500999Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T14:08:35.501086Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T14:08:35.501646Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T14:08:35.502697Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.194:2379"} {"level":"info","ts":"2026-04-20T14:08:57.390535Z","caller":"traceutil/trace.go:171","msg":"trace[720744764] linearizableReadLoop","detail":"{readStateIndex:465; appliedIndex:464; }","duration":"220.146577ms","start":"2026-04-20T14:08:57.170363Z","end":"2026-04-20T14:08:57.390509Z","steps":["trace[720744764] 'read index received' (duration: 219.9021ms)","trace[720744764] 'applied index is now lower than readState.Index' (duration: 243.307µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:08:57.390604Z","caller":"traceutil/trace.go:171","msg":"trace[1286938463] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"296.145803ms","start":"2026-04-20T14:08:57.094417Z","end":"2026-04-20T14:08:57.390563Z","steps":["trace[1286938463] 'process raft request' (duration: 295.940917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:08:57.390683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.294292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-20T14:08:57.390749Z","caller":"traceutil/trace.go:171","msg":"trace[2098586211] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:453; }","duration":"220.417096ms","start":"2026-04-20T14:08:57.170322Z","end":"2026-04-20T14:08:57.390739Z","steps":["trace[2098586211] 'agreement among raft nodes before linearized reading' (duration: 220.300382ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:09:02.076514Z","caller":"traceutil/trace.go:171","msg":"trace[766815924] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"332.589813ms","start":"2026-04-20T14:09:01.743896Z","end":"2026-04-20T14:09:02.076486Z","steps":["trace[766815924] 'process raft request' (duration: 332.458431ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:09:02.077349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:09:01.743877Z","time spent":"332.725324ms","remote":"127.0.0.1:53272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T14:09:02.084288Z","caller":"traceutil/trace.go:171","msg":"trace[1089761211] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"320.773403ms","start":"2026-04-20T14:09:01.763492Z","end":"2026-04-20T14:09:02.084265Z","steps":["trace[1089761211] 'process raft request' (duration: 320.638661ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:09:02.084283Z","caller":"traceutil/trace.go:171","msg":"trace[1241496421] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"335.178553ms","start":"2026-04-20T14:09:01.749049Z","end":"2026-04-20T14:09:02.084227Z","steps":["trace[1241496421] 'process raft request' (duration: 334.987531ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:09:02.084293Z","caller":"traceutil/trace.go:171","msg":"trace[871663034] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"311.601193ms","start":"2026-04-20T14:09:01.772675Z","end":"2026-04-20T14:09:02.084276Z","steps":["trace[871663034] 'process raft request' (duration: 311.504992ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:09:02.084362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:09:01.763485Z","time spent":"320.844754ms","remote":"127.0.0.1:53272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T14:09:02.08441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:09:01.772651Z","time spent":"311.701755ms","remote":"127.0.0.1:53272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T14:09:02.084424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:09:01.749038Z","time spent":"335.314025ms","remote":"127.0.0.1:53272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T14:09:02.08448Z","caller":"traceutil/trace.go:171","msg":"trace[915026057] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"323.90883ms","start":"2026-04-20T14:09:01.760565Z","end":"2026-04-20T14:09:02.084474Z","steps":["trace[915026057] 'process raft request' (duration: 323.538585ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:09:02.084508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:09:01.76055Z","time spent":"323.946941ms","remote":"127.0.0.1:53272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T14:09:02.084674Z","caller":"traceutil/trace.go:171","msg":"trace[1649129424] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"311.934178ms","start":"2026-04-20T14:09:01.772724Z","end":"2026-04-20T14:09:02.084659Z","steps":["trace[1649129424] 'process raft request' (duration: 311.485031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:09:02.084753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:09:01.772719Z","time spent":"312.00693ms","remote":"127.0.0.1:53272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T14:09:02.085656Z","caller":"traceutil/trace.go:171","msg":"trace[1497882932] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"312.860713ms","start":"2026-04-20T14:09:01.772785Z","end":"2026-04-20T14:09:02.085646Z","steps":["trace[1497882932] 'process raft request' (duration: 312.438146ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:09:02.085708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:09:01.772781Z","time spent":"312.904084ms","remote":"127.0.0.1:53272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T14:09:02.085809Z","caller":"traceutil/trace.go:171","msg":"trace[209998535] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"309.360768ms","start":"2026-04-20T14:09:01.77644Z","end":"2026-04-20T14:09:02.085801Z","steps":["trace[209998535] 'process raft request' (duration: 308.851811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:09:02.085846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:09:01.776433Z","time spent":"309.3961ms","remote":"127.0.0.1:53272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T14:09:02.085931Z","caller":"traceutil/trace.go:171","msg":"trace[1415517516] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"303.835315ms","start":"2026-04-20T14:09:01.782089Z","end":"2026-04-20T14:09:02.085924Z","steps":["trace[1415517516] 'process raft request' (duration: 303.242436ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:09:02.085965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:09:01.782081Z","time spent":"303.868805ms","remote":"127.0.0.1:53272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T14:09:02.086054Z","caller":"traceutil/trace.go:171","msg":"trace[912033984] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"286.277397ms","start":"2026-04-20T14:09:01.799769Z","end":"2026-04-20T14:09:02.086047Z","steps":["trace[912033984] 'process raft request' (duration: 285.594487ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:09:02.086577Z","caller":"traceutil/trace.go:171","msg":"trace[104636543] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"156.779281ms","start":"2026-04-20T14:09:01.929782Z","end":"2026-04-20T14:09:02.086562Z","steps":["trace[104636543] 'process raft request' (duration: 155.670965ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:09:02.086577Z","caller":"traceutil/trace.go:171","msg":"trace[246367301] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"269.672913ms","start":"2026-04-20T14:09:01.816887Z","end":"2026-04-20T14:09:02.08656Z","steps":["trace[246367301] 'process raft request' (duration: 268.516807ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:09:06.451695Z","caller":"traceutil/trace.go:171","msg":"trace[511838912] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:607; }","duration":"326.889035ms","start":"2026-04-20T14:09:06.12478Z","end":"2026-04-20T14:09:06.451669Z","steps":["trace[511838912] 'read index received' (duration: 324.451708ms)","trace[511838912] 'applied index is now lower than readState.Index' (duration: 2.436367ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:09:06.452415Z","caller":"traceutil/trace.go:171","msg":"trace[1959349520] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"396.784822ms","start":"2026-04-20T14:09:06.055479Z","end":"2026-04-20T14:09:06.452264Z","steps":["trace[1959349520] 'process raft request' (duration: 394.480078ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:09:06.452757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.951102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-20T14:09:06.453984Z","caller":"traceutil/trace.go:171","msg":"trace[601769160] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:591; }","duration":"328.582321ms","start":"2026-04-20T14:09:06.124738Z","end":"2026-04-20T14:09:06.453321Z","steps":["trace[601769160] 'agreement among raft nodes before linearized reading' (duration: 327.026578ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:09:06.454773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:09:06.124719Z","time spent":"329.748589ms","remote":"127.0.0.1:53466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T14:09:06.455795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:09:06.055447Z","time spent":"397.569034ms","remote":"127.0.0.1:53294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036081,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:09:07.092688Z","caller":"traceutil/trace.go:171","msg":"trace[1497349993] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:609; }","duration":"412.000774ms","start":"2026-04-20T14:09:06.680654Z","end":"2026-04-20T14:09:07.092654Z","steps":["trace[1497349993] 'read index received' (duration: 411.7135ms)","trace[1497349993] 'applied index is now lower than readState.Index' (duration: 285.914µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:09:07.092822Z","caller":"traceutil/trace.go:171","msg":"trace[1116974176] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"571.825552ms","start":"2026-04-20T14:09:06.520957Z","end":"2026-04-20T14:09:07.092783Z","steps":["trace[1116974176] 'process raft request' (duration: 571.511027ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:09:07.09303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:09:06.520935Z","time spent":"571.972324ms","remote":"127.0.0.1:53540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1044,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T14:09:07.09303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.706632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T14:09:07.093121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.455261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T14:09:07.093171Z","caller":"traceutil/trace.go:171","msg":"trace[1182916105] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:593; }","duration":"412.558042ms","start":"2026-04-20T14:09:06.680598Z","end":"2026-04-20T14:09:07.093156Z","steps":["trace[1182916105] 'agreement among raft nodes before linearized reading' (duration: 412.218347ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:09:07.093168Z","caller":"traceutil/trace.go:171","msg":"trace[1148608545] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:593; }","duration":"377.889864ms","start":"2026-04-20T14:09:06.715254Z","end":"2026-04-20T14:09:07.093144Z","steps":["trace[1148608545] 'agreement among raft nodes before linearized reading' (duration: 377.699711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:09:07.093315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:09:06.715231Z","time spent":"378.072738ms","remote":"127.0.0.1:53226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T14:09:07.09325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:09:06.680569Z","time spent":"412.670045ms","remote":"127.0.0.1:53466","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-04-20T14:09:07.218674Z","caller":"traceutil/trace.go:171","msg":"trace[1059707335] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"116.818543ms","start":"2026-04-20T14:09:07.101828Z","end":"2026-04-20T14:09:07.218647Z","steps":["trace[1059707335] 'read index received' (duration: 94.537543ms)","trace[1059707335] 'applied index is now lower than readState.Index' (duration: 22.27961ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:09:07.218905Z","caller":"traceutil/trace.go:171","msg":"trace[440797175] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"121.078886ms","start":"2026-04-20T14:09:07.097788Z","end":"2026-04-20T14:09:07.218867Z","steps":["trace[440797175] 'process raft request' (duration: 98.708075ms)","trace[440797175] 'compare' (duration: 22.028056ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:09:07.218958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.071647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:09:07.219265Z","caller":"traceutil/trace.go:171","msg":"trace[573461721] range","detail":"{range_begin:/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:0; response_revision:594; }","duration":"117.432662ms","start":"2026-04-20T14:09:07.101808Z","end":"2026-04-20T14:09:07.219241Z","steps":["trace[573461721] 'agreement among raft nodes before linearized reading' (duration: 117.023616ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:09:08.343816Z","caller":"traceutil/trace.go:171","msg":"trace[547877595] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"227.280147ms","start":"2026-04-20T14:09:08.11651Z","end":"2026-04-20T14:09:08.343791Z","steps":["trace[547877595] 'process raft request' (duration: 226.753429ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:09:09.388392Z","caller":"traceutil/trace.go:171","msg":"trace[774065740] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"160.64434ms","start":"2026-04-20T14:09:09.227729Z","end":"2026-04-20T14:09:09.388374Z","steps":["trace[774065740] 'process raft request' (duration: 160.556078ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:09:11.705479Z","caller":"traceutil/trace.go:171","msg":"trace[1267707535] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"309.036533ms","start":"2026-04-20T14:09:11.396409Z","end":"2026-04-20T14:09:11.705445Z","steps":["trace[1267707535] 'process raft request' (duration: 305.51873ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:09:11.70546Z","caller":"traceutil/trace.go:171","msg":"trace[709436518] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:645; }","duration":"229.509791ms","start":"2026-04-20T14:09:11.475917Z","end":"2026-04-20T14:09:11.705427Z","steps":["trace[709436518] 'read index received' (duration: 226.023948ms)","trace[709436518] 'applied index is now lower than readState.Index' (duration: 3.484473ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:09:11.705718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.783376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-20T14:09:11.70578Z","caller":"traceutil/trace.go:171","msg":"trace[1472051487] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:628; }","duration":"229.886617ms","start":"2026-04-20T14:09:11.475875Z","end":"2026-04-20T14:09:11.705761Z","steps":["trace[1472051487] 'agreement among raft nodes before linearized reading' (duration: 229.676294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:09:11.705792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.767649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-20T14:09:11.705833Z","caller":"traceutil/trace.go:171","msg":"trace[2029539126] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:628; }","duration":"224.83065ms","start":"2026-04-20T14:09:11.480989Z","end":"2026-04-20T14:09:11.70582Z","steps":["trace[2029539126] 'agreement among raft nodes before linearized reading' (duration: 224.670437ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:09:11.706517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:09:11.396378Z","time spent":"309.211776ms","remote":"127.0.0.1:53466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:09:26.273537Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039419866767680931,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T14:09:26.389838Z","caller":"traceutil/trace.go:171","msg":"trace[794968702] linearizableReadLoop","detail":"{readStateIndex:797; appliedIndex:796; }","duration":"617.275846ms","start":"2026-04-20T14:09:25.77254Z","end":"2026-04-20T14:09:26.389816Z","steps":["trace[794968702] 'read index received' (duration: 617.070173ms)","trace[794968702] 'applied index is now lower than readState.Index' (duration: 204.363µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:09:26.389911Z","caller":"traceutil/trace.go:171","msg":"trace[2120780769] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"618.690697ms","start":"2026-04-20T14:09:25.771208Z","end":"2026-04-20T14:09:26.389898Z","steps":["trace[2120780769] 'process raft request' (duration: 618.454044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:09:26.390047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.495079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-20T14:09:26.390078Z","caller":"traceutil/trace.go:171","msg":"trace[1594992478] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:775; }","duration":"617.534289ms","start":"2026-04-20T14:09:25.772534Z","end":"2026-04-20T14:09:26.390069Z","steps":["trace[1594992478] 'agreement among raft nodes before linearized reading' (duration: 617.411548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:09:26.390108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.722164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:489"} {"level":"warn","ts":"2026-04-20T14:09:26.390112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:09:25.772497Z","time spent":"617.605951ms","remote":"127.0.0.1:53466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-20T14:09:26.390133Z","caller":"traceutil/trace.go:171","msg":"trace[640248629] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:775; }","duration":"286.781594ms","start":"2026-04-20T14:09:26.103345Z","end":"2026-04-20T14:09:26.390126Z","steps":["trace[640248629] 'agreement among raft nodes before linearized reading' (duration: 286.691082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:09:26.390171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.391777ms","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-04-20T14:09:26.390253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:09:25.771185Z","time spent":"618.762428ms","remote":"127.0.0.1:53466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:09:26.390253Z","caller":"traceutil/trace.go:171","msg":"trace[498625426] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:775; }","duration":"617.503269ms","start":"2026-04-20T14:09:25.772725Z","end":"2026-04-20T14:09:26.390229Z","steps":["trace[498625426] 'agreement among raft nodes before linearized reading' (duration: 617.283025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:09:26.390309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:09:25.772718Z","time spent":"617.58284ms","remote":"127.0.0.1:53466","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":"info","ts":"2026-04-20T14:09:26.635193Z","caller":"traceutil/trace.go:171","msg":"trace[289799798] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"240.57105ms","start":"2026-04-20T14:09:26.394581Z","end":"2026-04-20T14:09:26.635152Z","steps":["trace[289799798] 'process raft request' (duration: 238.078872ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:09:26.641476Z","caller":"traceutil/trace.go:171","msg":"trace[1734251543] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"214.4459ms","start":"2026-04-20T14:09:26.427008Z","end":"2026-04-20T14:09:26.641454Z","steps":["trace[1734251543] 'process raft request' (duration: 214.39166ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:09:26.641535Z","caller":"traceutil/trace.go:171","msg":"trace[1502029514] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"244.805014ms","start":"2026-04-20T14:09:26.396681Z","end":"2026-04-20T14:09:26.641486Z","steps":["trace[1502029514] 'process raft request' (duration: 244.689592ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:09:26.645611Z","caller":"traceutil/trace.go:171","msg":"trace[1671990219] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"247.019928ms","start":"2026-04-20T14:09:26.395055Z","end":"2026-04-20T14:09:26.642075Z","steps":["trace[1671990219] 'process raft request' (duration: 246.216016ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:10:39.079132Z","caller":"traceutil/trace.go:171","msg":"trace[1926788561] linearizableReadLoop","detail":"{readStateIndex:1153; appliedIndex:1152; }","duration":"115.444696ms","start":"2026-04-20T14:10:38.963667Z","end":"2026-04-20T14:10:39.079112Z","steps":["trace[1926788561] 'read index received' (duration: 115.221082ms)","trace[1926788561] 'applied index is now lower than readState.Index' (duration: 222.784µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:10:39.079299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.61603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-20T14:10:39.079324Z","caller":"traceutil/trace.go:171","msg":"trace[1495104244] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1114; }","duration":"115.688321ms","start":"2026-04-20T14:10:38.963628Z","end":"2026-04-20T14:10:39.079317Z","steps":["trace[1495104244] 'agreement among raft nodes before linearized reading' (duration: 115.588059ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:10:39.079414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.548931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-20T14:10:39.079668Z","caller":"traceutil/trace.go:171","msg":"trace[1130995470] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1114; }","duration":"114.833485ms","start":"2026-04-20T14:10:38.964819Z","end":"2026-04-20T14:10:39.079653Z","steps":["trace[1130995470] 'agreement among raft nodes before linearized reading' (duration: 114.424898ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:10:39.07976Z","caller":"traceutil/trace.go:171","msg":"trace[53595465] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"118.246547ms","start":"2026-04-20T14:10:38.96093Z","end":"2026-04-20T14:10:39.079177Z","steps":["trace[53595465] 'process raft request' (duration: 118.050533ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:10:40.852641Z","caller":"traceutil/trace.go:171","msg":"trace[2066426718] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"125.309987ms","start":"2026-04-20T14:10:40.727303Z","end":"2026-04-20T14:10:40.852613Z","steps":["trace[2066426718] 'process raft request' (duration: 125.152274ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:12:50.010425Z","caller":"traceutil/trace.go:171","msg":"trace[1207417486] transaction","detail":"{read_only:false; response_revision:1543; number_of_response:1; }","duration":"150.405141ms","start":"2026-04-20T14:12:49.859985Z","end":"2026-04-20T14:12:50.01039Z","steps":["trace[1207417486] 'process raft request' (duration: 150.067274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:12:50.010465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.262377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-20T14:12:50.010645Z","caller":"traceutil/trace.go:171","msg":"trace[1553336743] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1543; }","duration":"100.42141ms","start":"2026-04-20T14:12:49.910182Z","end":"2026-04-20T14:12:50.010604Z","steps":["trace[1553336743] 'agreement among raft nodes before linearized reading' (duration: 100.175835ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:12:50.83595Z","caller":"traceutil/trace.go:171","msg":"trace[1809351244] linearizableReadLoop","detail":"{readStateIndex:1612; appliedIndex:1611; }","duration":"121.303993ms","start":"2026-04-20T14:12:50.714617Z","end":"2026-04-20T14:12:50.835921Z","steps":["trace[1809351244] 'read index received' (duration: 121.102489ms)","trace[1809351244] 'applied index is now lower than readState.Index' (duration: 199.824µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:12:50.836103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.465787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:12:50.83615Z","caller":"traceutil/trace.go:171","msg":"trace[20002641] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1547; }","duration":"121.569339ms","start":"2026-04-20T14:12:50.714567Z","end":"2026-04-20T14:12:50.836136Z","steps":["trace[20002641] 'agreement among raft nodes before linearized reading' (duration: 121.473387ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:12:50.836096Z","caller":"traceutil/trace.go:171","msg":"trace[493391257] transaction","detail":"{read_only:false; response_revision:1547; number_of_response:1; }","duration":"322.390089ms","start":"2026-04-20T14:12:50.513676Z","end":"2026-04-20T14:12:50.836066Z","steps":["trace[493391257] 'process raft request' (duration: 322.023141ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:12:50.836363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:12:50.513649Z","time spent":"322.628073ms","remote":"127.0.0.1:53466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:12:51.688503Z","caller":"traceutil/trace.go:171","msg":"trace[573437258] linearizableReadLoop","detail":"{readStateIndex:1613; appliedIndex:1612; }","duration":"116.242023ms","start":"2026-04-20T14:12:51.572222Z","end":"2026-04-20T14:12:51.688464Z","steps":["trace[573437258] 'read index received' (duration: 115.949708ms)","trace[573437258] 'applied index is now lower than readState.Index' (duration: 291.235µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:12:51.688549Z","caller":"traceutil/trace.go:171","msg":"trace[832307349] transaction","detail":"{read_only:false; response_revision:1548; number_of_response:1; }","duration":"260.743775ms","start":"2026-04-20T14:12:51.427777Z","end":"2026-04-20T14:12:51.688521Z","steps":["trace[832307349] 'process raft request' (duration: 260.517201ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:12:51.688718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.484389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T14:12:51.688752Z","caller":"traceutil/trace.go:171","msg":"trace[2116721322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1548; }","duration":"116.568841ms","start":"2026-04-20T14:12:51.572173Z","end":"2026-04-20T14:12:51.688742Z","steps":["trace[2116721322] 'agreement among raft nodes before linearized reading' (duration: 116.417757ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:13:13.201983Z","caller":"traceutil/trace.go:171","msg":"trace[1678437277] linearizableReadLoop","detail":"{readStateIndex:1689; appliedIndex:1688; }","duration":"289.69245ms","start":"2026-04-20T14:13:12.912265Z","end":"2026-04-20T14:13:13.201957Z","steps":["trace[1678437277] 'read index received' (duration: 289.622909ms)","trace[1678437277] 'applied index is now lower than readState.Index' (duration: 68.251µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:13:13.202133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.867124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:13:13.202179Z","caller":"traceutil/trace.go:171","msg":"trace[1528271042] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1620; }","duration":"289.976625ms","start":"2026-04-20T14:13:12.912188Z","end":"2026-04-20T14:13:13.202164Z","steps":["trace[1528271042] 'agreement among raft nodes before linearized reading' (duration: 289.877003ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:13:13.20216Z","caller":"traceutil/trace.go:171","msg":"trace[129710863] transaction","detail":"{read_only:false; response_revision:1620; number_of_response:1; }","duration":"303.775969ms","start":"2026-04-20T14:13:12.898332Z","end":"2026-04-20T14:13:13.202108Z","steps":["trace[129710863] 'process raft request' (duration: 303.448383ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:13:13.202449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:13:12.898292Z","time spent":"304.011364ms","remote":"127.0.0.1:53466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:13:13.913071Z","caller":"traceutil/trace.go:171","msg":"trace[1553163614] linearizableReadLoop","detail":"{readStateIndex:1690; appliedIndex:1689; }","duration":"121.137011ms","start":"2026-04-20T14:13:13.791899Z","end":"2026-04-20T14:13:13.913036Z","steps":["trace[1553163614] 'read index received' (duration: 67.355114ms)","trace[1553163614] 'applied index is now lower than readState.Index' (duration: 53.779747ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:13:13.913382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.436366ms","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-04-20T14:13:13.913496Z","caller":"traceutil/trace.go:171","msg":"trace[1235197061] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1620; }","duration":"121.65276ms","start":"2026-04-20T14:13:13.791793Z","end":"2026-04-20T14:13:13.913446Z","steps":["trace[1235197061] 'agreement among raft nodes before linearized reading' (duration: 121.328964ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:13:16.084714Z","caller":"traceutil/trace.go:171","msg":"trace[262366220] transaction","detail":"{read_only:false; response_revision:1628; number_of_response:1; }","duration":"143.621666ms","start":"2026-04-20T14:13:15.941068Z","end":"2026-04-20T14:13:16.08469Z","steps":["trace[262366220] 'process raft request' (duration: 143.503763ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:13:16.470506Z","caller":"traceutil/trace.go:171","msg":"trace[1139436446] linearizableReadLoop","detail":"{readStateIndex:1699; appliedIndex:1698; }","duration":"330.193162ms","start":"2026-04-20T14:13:16.140287Z","end":"2026-04-20T14:13:16.47048Z","steps":["trace[1139436446] 'read index received' (duration: 298.648218ms)","trace[1139436446] 'applied index is now lower than readState.Index' (duration: 31.543644ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:13:16.470847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.813276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-20T14:13:16.470889Z","caller":"traceutil/trace.go:171","msg":"trace[488089719] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1629; }","duration":"316.901799ms","start":"2026-04-20T14:13:16.153977Z","end":"2026-04-20T14:13:16.470879Z","steps":["trace[488089719] 'agreement among raft nodes before linearized reading' (duration: 316.760166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:13:16.470924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:13:16.153964Z","time spent":"316.951669ms","remote":"127.0.0.1:53466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T14:13:16.47093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.63375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-20T14:13:16.470987Z","caller":"traceutil/trace.go:171","msg":"trace[23835217] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1629; }","duration":"330.741062ms","start":"2026-04-20T14:13:16.140229Z","end":"2026-04-20T14:13:16.47097Z","steps":["trace[23835217] 'agreement among raft nodes before linearized reading' (duration: 330.419636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:13:16.471066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:13:16.140195Z","time spent":"330.822194ms","remote":"127.0.0.1:53466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-20T14:13:16.47105Z","caller":"traceutil/trace.go:171","msg":"trace[745470114] transaction","detail":"{read_only:false; response_revision:1629; number_of_response:1; }","duration":"335.725231ms","start":"2026-04-20T14:13:16.135267Z","end":"2026-04-20T14:13:16.470992Z","steps":["trace[745470114] 'process raft request' (duration: 303.681317ms)","trace[745470114] 'compare' (duration: 31.085455ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:13:16.471123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.303722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T14:13:16.471173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.637195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:489"} {"level":"info","ts":"2026-04-20T14:13:16.471197Z","caller":"traceutil/trace.go:171","msg":"trace[945023351] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1629; }","duration":"288.417064ms","start":"2026-04-20T14:13:16.18276Z","end":"2026-04-20T14:13:16.471177Z","steps":["trace[945023351] 'agreement among raft nodes before linearized reading' (duration: 288.217579ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:13:16.471207Z","caller":"traceutil/trace.go:171","msg":"trace[547423814] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1629; }","duration":"308.696047ms","start":"2026-04-20T14:13:16.162502Z","end":"2026-04-20T14:13:16.471198Z","steps":["trace[547423814] 'agreement among raft nodes before linearized reading' (duration: 308.607525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:13:16.47124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:13:16.162487Z","time spent":"308.746217ms","remote":"127.0.0.1:53466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":511,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T14:13:16.471233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:13:16.135247Z","time spent":"335.885995ms","remote":"127.0.0.1:53466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:13:28.903987Z","caller":"traceutil/trace.go:171","msg":"trace[1851791962] linearizableReadLoop","detail":"{readStateIndex:1792; appliedIndex:1791; }","duration":"396.179219ms","start":"2026-04-20T14:13:28.507792Z","end":"2026-04-20T14:13:28.903971Z","steps":["trace[1851791962] 'read index received' (duration: 396.066977ms)","trace[1851791962] 'applied index is now lower than readState.Index' (duration: 111.712µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:13:28.904068Z","caller":"traceutil/trace.go:171","msg":"trace[108473492] transaction","detail":"{read_only:false; response_revision:1718; number_of_response:1; }","duration":"767.40951ms","start":"2026-04-20T14:13:28.136651Z","end":"2026-04-20T14:13:28.90406Z","steps":["trace[108473492] 'process raft request' (duration: 767.221437ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:13:28.904135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:13:28.136618Z","time spent":"767.468171ms","remote":"127.0.0.1:53466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:13:28.904269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.487315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.194\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-20T14:13:28.90429Z","caller":"traceutil/trace.go:171","msg":"trace[523004446] range","detail":"{range_begin:/registry/masterleases/199.19.213.194; range_end:; response_count:1; response_revision:1718; }","duration":"396.506896ms","start":"2026-04-20T14:13:28.507777Z","end":"2026-04-20T14:13:28.904284Z","steps":["trace[523004446] 'agreement among raft nodes before linearized reading' (duration: 396.418573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:13:28.904304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:13:28.507719Z","time spent":"396.582247ms","remote":"127.0.0.1:53250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.194\" "} {"level":"warn","ts":"2026-04-20T14:13:28.904468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.302985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-20T14:13:28.904495Z","caller":"traceutil/trace.go:171","msg":"trace[1790612284] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1718; }","duration":"388.395778ms","start":"2026-04-20T14:13:28.516093Z","end":"2026-04-20T14:13:28.904489Z","steps":["trace[1790612284] 'agreement among raft nodes before linearized reading' (duration: 388.333926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:13:28.904518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:13:28.516073Z","time spent":"388.438378ms","remote":"127.0.0.1:53466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T14:13:28.904506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.775088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:13:28.904553Z","caller":"traceutil/trace.go:171","msg":"trace[1593603366] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1718; }","duration":"189.85911ms","start":"2026-04-20T14:13:28.71468Z","end":"2026-04-20T14:13:28.904539Z","steps":["trace[1593603366] 'agreement among raft nodes before linearized reading' (duration: 189.785768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:13:28.904634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.722851ms","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-04-20T14:13:28.904663Z","caller":"traceutil/trace.go:171","msg":"trace[1799979361] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1718; }","duration":"306.772042ms","start":"2026-04-20T14:13:28.597884Z","end":"2026-04-20T14:13:28.904657Z","steps":["trace[1799979361] 'agreement among raft nodes before linearized reading' (duration: 306.70638ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:13:28.90468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:13:28.597875Z","time spent":"306.800893ms","remote":"127.0.0.1:53466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T14:13:28.904782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.558298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-20T14:13:28.904801Z","caller":"traceutil/trace.go:171","msg":"trace[2056657903] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1718; }","duration":"307.606859ms","start":"2026-04-20T14:13:28.597189Z","end":"2026-04-20T14:13:28.904796Z","steps":["trace[2056657903] 'agreement among raft nodes before linearized reading' (duration: 307.555288ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:13:28.904796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.239987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-20T14:13:28.904813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:13:28.597168Z","time spent":"307.64238ms","remote":"127.0.0.1:53466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-20T14:13:28.90483Z","caller":"traceutil/trace.go:171","msg":"trace[1515554340] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1718; }","duration":"145.310229ms","start":"2026-04-20T14:13:28.75951Z","end":"2026-04-20T14:13:28.90482Z","steps":["trace[1515554340] 'agreement among raft nodes before linearized reading' (duration: 145.251368ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:13:28.904892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.719665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-20T14:13:28.904917Z","caller":"traceutil/trace.go:171","msg":"trace[1138083371] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1718; }","duration":"343.768566ms","start":"2026-04-20T14:13:28.561144Z","end":"2026-04-20T14:13:28.904913Z","steps":["trace[1138083371] 'agreement among raft nodes before linearized reading' (duration: 343.720095ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:13:28.90493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:13:28.561133Z","time spent":"343.793616ms","remote":"127.0.0.1:53466","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-04-20T14:13:29.519965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:13:28.906285Z","time spent":"613.674508ms","remote":"127.0.0.1:53250","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-20T14:13:29.521465Z","caller":"traceutil/trace.go:171","msg":"trace[1677337252] transaction","detail":"{read_only:false; response_revision:1719; number_of_response:1; }","duration":"613.060136ms","start":"2026-04-20T14:13:28.908382Z","end":"2026-04-20T14:13:29.521442Z","steps":["trace[1677337252] 'process raft request' (duration: 612.852022ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:13:29.521486Z","caller":"traceutil/trace.go:171","msg":"trace[257312669] transaction","detail":"{read_only:false; response_revision:1720; number_of_response:1; }","duration":"612.340141ms","start":"2026-04-20T14:13:28.90913Z","end":"2026-04-20T14:13:29.52147Z","steps":["trace[257312669] 'process raft request' (duration: 612.231039ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:13:29.521455Z","caller":"traceutil/trace.go:171","msg":"trace[1308252031] transaction","detail":"{read_only:false; response_revision:1721; number_of_response:1; }","duration":"611.905733ms","start":"2026-04-20T14:13:28.909531Z","end":"2026-04-20T14:13:29.521437Z","steps":["trace[1308252031] 'process raft request' (duration: 611.862102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:13:29.521576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:13:28.909124Z","time spent":"612.396342ms","remote":"127.0.0.1:53466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:13:29.521607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:13:28.908372Z","time spent":"613.145287ms","remote":"127.0.0.1:53466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:13:29.521626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:13:28.909527Z","time spent":"612.005225ms","remote":"127.0.0.1:53466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:13:29.52169Z","caller":"traceutil/trace.go:171","msg":"trace[1435914137] transaction","detail":"{read_only:false; response_revision:1722; number_of_response:1; }","duration":"611.650547ms","start":"2026-04-20T14:13:28.910027Z","end":"2026-04-20T14:13:29.521678Z","steps":["trace[1435914137] 'process raft request' (duration: 611.388532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:13:29.521764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:13:28.91002Z","time spent":"611.715419ms","remote":"127.0.0.1:53466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:13:29.521777Z","caller":"traceutil/trace.go:171","msg":"trace[2030019847] linearizableReadLoop","detail":"{readStateIndex:1797; appliedIndex:1792; }","duration":"283.802434ms","start":"2026-04-20T14:13:29.237966Z","end":"2026-04-20T14:13:29.521768Z","steps":["trace[2030019847] 'read index received' (duration: 259.086161ms)","trace[2030019847] 'applied index is now lower than readState.Index' (duration: 24.715713ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:13:29.521843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.881786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:13:29.52187Z","caller":"traceutil/trace.go:171","msg":"trace[46504308] transaction","detail":"{read_only:false; response_revision:1723; number_of_response:1; }","duration":"150.42397ms","start":"2026-04-20T14:13:29.371438Z","end":"2026-04-20T14:13:29.521862Z","steps":["trace[46504308] 'process raft request' (duration: 150.178764ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:13:29.521878Z","caller":"traceutil/trace.go:171","msg":"trace[440230717] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1723; }","duration":"283.953637ms","start":"2026-04-20T14:13:29.237915Z","end":"2026-04-20T14:13:29.521869Z","steps":["trace[440230717] 'agreement among raft nodes before linearized reading' (duration: 283.878405ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:13:43.702278Z","caller":"traceutil/trace.go:171","msg":"trace[1695342742] linearizableReadLoop","detail":"{readStateIndex:1852; appliedIndex:1851; }","duration":"123.166165ms","start":"2026-04-20T14:13:43.579073Z","end":"2026-04-20T14:13:43.702239Z","steps":["trace[1695342742] 'read index received' (duration: 123.126054ms)","trace[1695342742] 'applied index is now lower than readState.Index' (duration: 38.921µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:13:43.702434Z","caller":"traceutil/trace.go:171","msg":"trace[1189820282] transaction","detail":"{read_only:false; response_revision:1775; number_of_response:1; }","duration":"134.865685ms","start":"2026-04-20T14:13:43.567552Z","end":"2026-04-20T14:13:43.702418Z","steps":["trace[1189820282] 'process raft request' (duration: 134.484557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:13:43.70263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.548064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T14:13:43.702677Z","caller":"traceutil/trace.go:171","msg":"trace[15415923] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1775; }","duration":"123.636806ms","start":"2026-04-20T14:13:43.579027Z","end":"2026-04-20T14:13:43.702664Z","steps":["trace[15415923] 'agreement among raft nodes before linearized reading' (duration: 123.507313ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:13:43.703063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.526407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-20T14:13:43.703088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.921354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-20T14:13:43.703104Z","caller":"traceutil/trace.go:171","msg":"trace[262264337] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1775; }","duration":"113.587879ms","start":"2026-04-20T14:13:43.589505Z","end":"2026-04-20T14:13:43.703093Z","steps":["trace[262264337] 'agreement among raft nodes before linearized reading' (duration: 113.449466ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:13:43.703134Z","caller":"traceutil/trace.go:171","msg":"trace[1833035866] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1775; }","duration":"113.969145ms","start":"2026-04-20T14:13:43.589151Z","end":"2026-04-20T14:13:43.70312Z","steps":["trace[1833035866] 'agreement among raft nodes before linearized reading' (duration: 113.839883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:13:43.703141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.30566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-20T14:13:43.703232Z","caller":"traceutil/trace.go:171","msg":"trace[772571327] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1775; }","duration":"119.438533ms","start":"2026-04-20T14:13:43.583775Z","end":"2026-04-20T14:13:43.703214Z","steps":["trace[772571327] 'agreement among raft nodes before linearized reading' (duration: 119.175008ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:13:43.703454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.448493ms","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-04-20T14:13:43.703503Z","caller":"traceutil/trace.go:171","msg":"trace[1515296635] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1775; }","duration":"118.536064ms","start":"2026-04-20T14:13:43.584955Z","end":"2026-04-20T14:13:43.703491Z","steps":["trace[1515296635] 'agreement among raft nodes before linearized reading' (duration: 118.408292ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:13:44.138888Z","caller":"traceutil/trace.go:171","msg":"trace[2022221519] transaction","detail":"{read_only:false; response_revision:1778; number_of_response:1; }","duration":"430.951277ms","start":"2026-04-20T14:13:43.707918Z","end":"2026-04-20T14:13:44.138869Z","steps":["trace[2022221519] 'process raft request' (duration: 430.891636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:13:44.138993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:13:43.707897Z","time spent":"431.041569ms","remote":"127.0.0.1:53466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:13:44.13897Z","caller":"traceutil/trace.go:171","msg":"trace[1429039038] transaction","detail":"{read_only:false; response_revision:1777; number_of_response:1; }","duration":"431.555369ms","start":"2026-04-20T14:13:43.707378Z","end":"2026-04-20T14:13:44.138933Z","steps":["trace[1429039038] 'process raft request' (duration: 431.331575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:13:44.139157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:13:43.707367Z","time spent":"431.699903ms","remote":"127.0.0.1:53466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:13:44.139691Z","caller":"traceutil/trace.go:171","msg":"trace[1025316148] transaction","detail":"{read_only:false; response_revision:1779; number_of_response:1; }","duration":"431.698682ms","start":"2026-04-20T14:13:43.707974Z","end":"2026-04-20T14:13:44.139673Z","steps":["trace[1025316148] 'process raft request' (duration: 430.873425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:13:44.139806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:13:43.707963Z","time spent":"431.777673ms","remote":"127.0.0.1:53466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:13:50.332609Z","caller":"traceutil/trace.go:171","msg":"trace[343843668] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"167.696056ms","start":"2026-04-20T14:13:50.16489Z","end":"2026-04-20T14:13:50.332586Z","steps":["trace[343843668] 'process raft request' (duration: 167.560164ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:13:51.107324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.252085ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:13:51.107571Z","caller":"traceutil/trace.go:171","msg":"trace[1658292609] linearizableReadLoop","detail":"{readStateIndex:1932; appliedIndex:1929; }","duration":"808.010064ms","start":"2026-04-20T14:13:50.299544Z","end":"2026-04-20T14:13:51.107554Z","steps":["trace[1658292609] 'read index received' (duration: 32.913224ms)","trace[1658292609] 'applied index is now lower than readState.Index' (duration: 775.09584ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:13:51.108059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.480824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-5wdsg\" ","response":"range_response_count:1 size:12642"} {"level":"info","ts":"2026-04-20T14:13:51.108068Z","caller":"traceutil/trace.go:171","msg":"trace[2086196220] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"940.717716ms","start":"2026-04-20T14:13:50.16732Z","end":"2026-04-20T14:13:51.108037Z","steps":["trace[2086196220] 'process raft request' (duration: 940.173225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:13:51.108132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.400577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-04-20T14:13:51.108158Z","caller":"traceutil/trace.go:171","msg":"trace[70569356] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-5wdsg; range_end:; response_count:1; response_revision:1853; }","duration":"808.627456ms","start":"2026-04-20T14:13:50.299511Z","end":"2026-04-20T14:13:51.108138Z","steps":["trace[70569356] 'agreement among raft nodes before linearized reading' (duration: 808.220829ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:13:51.108193Z","caller":"traceutil/trace.go:171","msg":"trace[767303873] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1853; }","duration":"454.498649ms","start":"2026-04-20T14:13:50.653678Z","end":"2026-04-20T14:13:51.108177Z","steps":["trace[767303873] 'agreement among raft nodes before linearized reading' (duration: 454.114872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:13:51.108214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:13:50.299498Z","time spent":"808.696477ms","remote":"127.0.0.1:53396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":12664,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-5wdsg\" "} {"level":"warn","ts":"2026-04-20T14:13:51.108282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:13:50.1673Z","time spent":"940.856169ms","remote":"127.0.0.1:53466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:13:51.108334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:13:50.653653Z","time spent":"454.57503ms","remote":"127.0.0.1:53466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-20T14:13:51.108058Z","caller":"traceutil/trace.go:171","msg":"trace[1354749507] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"940.816789ms","start":"2026-04-20T14:13:50.167182Z","end":"2026-04-20T14:13:51.107998Z","steps":["trace[1354749507] 'process raft request' (duration: 510.700887ms)","trace[1354749507] 'compare' (duration: 429.095401ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:13:51.108347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.024948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:13:51.108484Z","caller":"traceutil/trace.go:171","msg":"trace[409565119] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1853; }","duration":"392.229762ms","start":"2026-04-20T14:13:50.716221Z","end":"2026-04-20T14:13:51.108451Z","steps":["trace[409565119] 'agreement among raft nodes before linearized reading' (duration: 391.941217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:13:51.108505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:13:50.167159Z","time spent":"941.240666ms","remote":"127.0.0.1:53466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:13:51.10854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:13:50.71621Z","time spent":"392.319364ms","remote":"127.0.0.1:53226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-20T14:13:51.494282Z","caller":"traceutil/trace.go:171","msg":"trace[1179507403] linearizableReadLoop","detail":"{readStateIndex:1935; appliedIndex:1933; }","duration":"253.927611ms","start":"2026-04-20T14:13:51.240312Z","end":"2026-04-20T14:13:51.49424Z","steps":["trace[1179507403] 'read index received' (duration: 253.403831ms)","trace[1179507403] 'applied index is now lower than readState.Index' (duration: 521.06µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:13:51.495417Z","caller":"traceutil/trace.go:171","msg":"trace[1943739012] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"375.084676ms","start":"2026-04-20T14:13:51.120276Z","end":"2026-04-20T14:13:51.49536Z","steps":["trace[1943739012] 'process raft request' (duration: 373.700619ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:13:51.495618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:13:51.120236Z","time spent":"375.27415ms","remote":"127.0.0.1:53396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13709,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:13:51.495426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.090163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:13:51.495774Z","caller":"traceutil/trace.go:171","msg":"trace[715911204] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1856; }","duration":"255.448651ms","start":"2026-04-20T14:13:51.240296Z","end":"2026-04-20T14:13:51.495745Z","steps":["trace[715911204] 'agreement among raft nodes before linearized reading' (duration: 255.052112ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:13:51.495137Z","caller":"traceutil/trace.go:171","msg":"trace[1130264185] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"380.492981ms","start":"2026-04-20T14:13:51.114602Z","end":"2026-04-20T14:13:51.495095Z","steps":["trace[1130264185] 'process raft request' (duration: 379.185416ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:13:51.496486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:13:51.114579Z","time spent":"381.811058ms","remote":"127.0.0.1:53466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:13:52.631603Z","caller":"traceutil/trace.go:171","msg":"trace[996375108] linearizableReadLoop","detail":"{readStateIndex:1944; appliedIndex:1943; }","duration":"118.621498ms","start":"2026-04-20T14:13:52.512958Z","end":"2026-04-20T14:13:52.631579Z","steps":["trace[996375108] 'read index received' (duration: 118.425934ms)","trace[996375108] 'applied index is now lower than readState.Index' (duration: 194.083µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:13:52.631718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.74087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:13:52.631753Z","caller":"traceutil/trace.go:171","msg":"trace[1461587024] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1865; }","duration":"118.823602ms","start":"2026-04-20T14:13:52.512918Z","end":"2026-04-20T14:13:52.631741Z","steps":["trace[1461587024] 'agreement among raft nodes before linearized reading' (duration: 118.75017ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:13:52.631675Z","caller":"traceutil/trace.go:171","msg":"trace[1576668704] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"291.188577ms","start":"2026-04-20T14:13:52.340441Z","end":"2026-04-20T14:13:52.63163Z","steps":["trace[1576668704] 'process raft request' (duration: 290.986664ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:13:52.854354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.161277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:13:52.854453Z","caller":"traceutil/trace.go:171","msg":"trace[1757547626] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1865; }","duration":"139.31888ms","start":"2026-04-20T14:13:52.715113Z","end":"2026-04-20T14:13:52.854432Z","steps":["trace[1757547626] 'range keys from in-memory index tree' (duration: 138.996174ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:13:53.317589Z","caller":"traceutil/trace.go:171","msg":"trace[1776516134] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"165.803358ms","start":"2026-04-20T14:13:53.151764Z","end":"2026-04-20T14:13:53.317568Z","steps":["trace[1776516134] 'process raft request' (duration: 165.662995ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:13:53.322963Z","caller":"traceutil/trace.go:171","msg":"trace[1420489253] linearizableReadLoop","detail":"{readStateIndex:1947; appliedIndex:1945; }","duration":"123.508532ms","start":"2026-04-20T14:13:53.199441Z","end":"2026-04-20T14:13:53.322949Z","steps":["trace[1420489253] 'read index received' (duration: 117.995704ms)","trace[1420489253] 'applied index is now lower than readState.Index' (duration: 5.511848ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:13:53.323048Z","caller":"traceutil/trace.go:171","msg":"trace[1509666439] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"170.620503ms","start":"2026-04-20T14:13:53.152399Z","end":"2026-04-20T14:13:53.323019Z","steps":["trace[1509666439] 'process raft request' (duration: 170.45956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:13:53.323125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.668735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1948"} {"level":"info","ts":"2026-04-20T14:13:53.323153Z","caller":"traceutil/trace.go:171","msg":"trace[661440725] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1869; }","duration":"123.755427ms","start":"2026-04-20T14:13:53.199387Z","end":"2026-04-20T14:13:53.323143Z","steps":["trace[661440725] 'agreement among raft nodes before linearized reading' (duration: 123.613084ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:13:53.32314Z","caller":"traceutil/trace.go:171","msg":"trace[223905178] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"115.614208ms","start":"2026-04-20T14:13:53.2075Z","end":"2026-04-20T14:13:53.323114Z","steps":["trace[223905178] 'process raft request' (duration: 115.413095ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:14:05.720601Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039419866767683746,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T14:14:05.724228Z","caller":"traceutil/trace.go:171","msg":"trace[214770990] linearizableReadLoop","detail":"{readStateIndex:2026; appliedIndex:2025; }","duration":"503.737878ms","start":"2026-04-20T14:14:05.220467Z","end":"2026-04-20T14:14:05.724205Z","steps":["trace[214770990] 'read index received' (duration: 503.476613ms)","trace[214770990] 'applied index is now lower than readState.Index' (duration: 260.225µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:14:05.72438Z","caller":"traceutil/trace.go:171","msg":"trace[891727610] transaction","detail":"{read_only:false; response_revision:1944; number_of_response:1; }","duration":"1.036786951s","start":"2026-04-20T14:14:04.687542Z","end":"2026-04-20T14:14:05.724329Z","steps":["trace[891727610] 'process raft request' (duration: 1.036486025s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:14:05.72453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.040915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-04-20T14:14:05.724676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.773062ms","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-04-20T14:14:05.724764Z","caller":"traceutil/trace.go:171","msg":"trace[845852123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1944; }","duration":"349.904704ms","start":"2026-04-20T14:14:05.374841Z","end":"2026-04-20T14:14:05.724746Z","steps":["trace[845852123] 'agreement among raft nodes before linearized reading' (duration: 349.717431ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:14:05.724797Z","caller":"traceutil/trace.go:171","msg":"trace[846198430] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1944; }","duration":"504.327939ms","start":"2026-04-20T14:14:05.220409Z","end":"2026-04-20T14:14:05.724737Z","steps":["trace[846198430] 'agreement among raft nodes before linearized reading' (duration: 503.919092ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:14:05.724814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:14:05.374819Z","time spent":"349.986256ms","remote":"127.0.0.1:53466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T14:14:05.724862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:14:05.220384Z","time spent":"504.464762ms","remote":"127.0.0.1:53286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":170,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-04-20T14:14:05.724857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.245437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-20T14:14:05.724954Z","caller":"traceutil/trace.go:171","msg":"trace[878369845] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1944; }","duration":"357.4031ms","start":"2026-04-20T14:14:05.367533Z","end":"2026-04-20T14:14:05.724936Z","steps":["trace[878369845] 'agreement among raft nodes before linearized reading' (duration: 357.001822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:14:05.725125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:14:05.367466Z","time spent":"357.564075ms","remote":"127.0.0.1:53466","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-04-20T14:14:05.725157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:14:04.687513Z","time spent":"1.037116367s","remote":"127.0.0.1:53466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:14:06.117252Z","caller":"traceutil/trace.go:171","msg":"trace[1521262128] transaction","detail":"{read_only:false; response_revision:1945; number_of_response:1; }","duration":"387.832056ms","start":"2026-04-20T14:14:05.729396Z","end":"2026-04-20T14:14:06.117228Z","steps":["trace[1521262128] 'process raft request' (duration: 304.440226ms)","trace[1521262128] 'compare' (duration: 83.069353ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:14:06.117382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:14:05.729368Z","time spent":"387.943719ms","remote":"127.0.0.1:53466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:14:06.117979Z","caller":"traceutil/trace.go:171","msg":"trace[790955852] linearizableReadLoop","detail":"{readStateIndex:2028; appliedIndex:2026; }","duration":"383.990121ms","start":"2026-04-20T14:14:05.733978Z","end":"2026-04-20T14:14:06.117968Z","steps":["trace[790955852] 'read index received' (duration: 299.840597ms)","trace[790955852] 'applied index is now lower than readState.Index' (duration: 84.148644ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:14:06.118103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.118523ms","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-04-20T14:14:06.118191Z","caller":"traceutil/trace.go:171","msg":"trace[439735122] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1947; }","duration":"384.235195ms","start":"2026-04-20T14:14:05.733947Z","end":"2026-04-20T14:14:06.118182Z","steps":["trace[439735122] 'agreement among raft nodes before linearized reading' (duration: 384.075602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:14:06.118214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:14:05.733933Z","time spent":"384.275566ms","remote":"127.0.0.1:53466","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-04-20T14:14:06.118246Z","caller":"traceutil/trace.go:171","msg":"trace[365161022] transaction","detail":"{read_only:false; response_revision:1947; number_of_response:1; }","duration":"249.715367ms","start":"2026-04-20T14:14:05.868363Z","end":"2026-04-20T14:14:06.118079Z","steps":["trace[365161022] 'process raft request' (duration: 249.551234ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:14:06.118352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.703361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:14:06.118203Z","caller":"traceutil/trace.go:171","msg":"trace[228694891] transaction","detail":"{read_only:false; response_revision:1946; number_of_response:1; }","duration":"388.396996ms","start":"2026-04-20T14:14:05.729637Z","end":"2026-04-20T14:14:06.118034Z","steps":["trace[228694891] 'process raft request' (duration: 387.55452ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:14:06.118373Z","caller":"traceutil/trace.go:171","msg":"trace[1820596437] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1947; }","duration":"198.754552ms","start":"2026-04-20T14:14:05.919613Z","end":"2026-04-20T14:14:06.118367Z","steps":["trace[1820596437] 'agreement among raft nodes before linearized reading' (duration: 198.721922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:14:06.118494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.189591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:14:06.118522Z","caller":"traceutil/trace.go:171","msg":"trace[1844573792] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1947; }","duration":"201.252622ms","start":"2026-04-20T14:14:05.917263Z","end":"2026-04-20T14:14:06.118516Z","steps":["trace[1844573792] 'agreement among raft nodes before linearized reading' (duration: 201.212322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:14:06.118318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.332927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-20T14:14:06.118661Z","caller":"traceutil/trace.go:171","msg":"trace[2055316477] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1947; }","duration":"383.684595ms","start":"2026-04-20T14:14:05.734965Z","end":"2026-04-20T14:14:06.11865Z","steps":["trace[2055316477] 'agreement among raft nodes before linearized reading' (duration: 383.308637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:14:06.118686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:14:05.734955Z","time spent":"383.723415ms","remote":"127.0.0.1:53466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-20T14:14:06.118848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.655474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-20T14:14:06.118879Z","caller":"traceutil/trace.go:171","msg":"trace[175391417] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1947; }","duration":"213.717076ms","start":"2026-04-20T14:14:05.905149Z","end":"2026-04-20T14:14:06.118866Z","steps":["trace[175391417] 'agreement among raft nodes before linearized reading' (duration: 213.652595ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:14:06.118885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:14:05.729601Z","time spent":"388.933728ms","remote":"127.0.0.1:53466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:14:06.269488Z","caller":"traceutil/trace.go:171","msg":"trace[2125758972] transaction","detail":"{read_only:false; response_revision:1949; number_of_response:1; }","duration":"146.776677ms","start":"2026-04-20T14:14:06.122675Z","end":"2026-04-20T14:14:06.269452Z","steps":["trace[2125758972] 'process raft request' (duration: 129.404798ms)","trace[2125758972] 'compare' (duration: 17.223456ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:14:06.269731Z","caller":"traceutil/trace.go:171","msg":"trace[657130278] transaction","detail":"{read_only:false; response_revision:1950; number_of_response:1; }","duration":"146.490312ms","start":"2026-04-20T14:14:06.123208Z","end":"2026-04-20T14:14:06.269698Z","steps":["trace[657130278] 'process raft request' (duration: 146.203486ms)"],"step_count":1}