{"level":"warn","ts":"2026-04-21T19:16:42.135235Z","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-21T19:16:42.135333Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.235:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.235:2380","--initial-cluster=instance=https://199.204.45.235:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.235:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.235: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-21T19:16:42.135409Z","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-21T19:16:42.135422Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-04-21T19:16:42.135442Z","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-21T19:16:42.136305Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"]} {"level":"info","ts":"2026-04-21T19:16:42.136394Z","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":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.235: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-21T19:16:42.140932Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.118198ms"} {"level":"info","ts":"2026-04-21T19:16:42.147205Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"aab6040abcf11","cluster-id":"2bae7d3d8857c059"} {"level":"info","ts":"2026-04-21T19:16:42.147293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=()"} {"level":"info","ts":"2026-04-21T19:16:42.14732Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 0"} {"level":"info","ts":"2026-04-21T19:16:42.14734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft aab6040abcf11 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-21T19:16:42.147355Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 1"} {"level":"info","ts":"2026-04-21T19:16:42.147405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"warn","ts":"2026-04-21T19:16:42.167443Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-21T19:16:42.169729Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-21T19:16:42.17212Z","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-21T19:16:42.17502Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"aab6040abcf11","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-21T19:16:42.175293Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"aab6040abcf11","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-21T19:16:42.175314Z","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-21T19:16:42.17558Z","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-21T19:16:42.175615Z","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-21T19:16:42.177367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"info","ts":"2026-04-21T19:16:42.178825Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","added-peer-id":"aab6040abcf11","added-peer-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-04-21T19:16:42.180711Z","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-21T19:16:42.18086Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-04-21T19:16:42.18098Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-04-21T19:16:42.181109Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"aab6040abcf11","initial-advertise-peer-urls":["https://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-21T19:16:42.181173Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-21T19:16:42.748413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 is starting a new election at term 1"} {"level":"info","ts":"2026-04-21T19:16:42.74848Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-21T19:16:42.748521Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgPreVoteResp from aab6040abcf11 at term 1"} {"level":"info","ts":"2026-04-21T19:16:42.748563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became candidate at term 2"} {"level":"info","ts":"2026-04-21T19:16:42.748575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgVoteResp from aab6040abcf11 at term 2"} {"level":"info","ts":"2026-04-21T19:16:42.748596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became leader at term 2"} {"level":"info","ts":"2026-04-21T19:16:42.74861Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: aab6040abcf11 elected leader aab6040abcf11 at term 2"} {"level":"info","ts":"2026-04-21T19:16:42.749954Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T19:16:42.751207Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"aab6040abcf11","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.235:2379]}","request-path":"/0/members/aab6040abcf11/attributes","cluster-id":"2bae7d3d8857c059","publish-timeout":"7s"} {"level":"info","ts":"2026-04-21T19:16:42.751215Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-21T19:16:42.751379Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-21T19:16:42.751624Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-21T19:16:42.751731Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-21T19:16:42.752004Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T19:16:42.752312Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T19:16:42.752442Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T19:16:42.754841Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-21T19:16:42.754855Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.235:2379"} {"level":"info","ts":"2026-04-21T19:16:55.698217Z","caller":"traceutil/trace.go:171","msg":"trace[937878272] linearizableReadLoop","detail":"{readStateIndex:409; appliedIndex:408; }","duration":"262.696742ms","start":"2026-04-21T19:16:55.435485Z","end":"2026-04-21T19:16:55.698181Z","steps":["trace[937878272] 'read index received' (duration: 262.472908ms)","trace[937878272] 'applied index is now lower than readState.Index' (duration: 222.404µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:16:55.698301Z","caller":"traceutil/trace.go:171","msg":"trace[473195908] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"374.934089ms","start":"2026-04-21T19:16:55.323321Z","end":"2026-04-21T19:16:55.698255Z","steps":["trace[473195908] 'process raft request' (duration: 374.661794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:16:55.698419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.921768ms","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-21T19:16:55.698507Z","caller":"traceutil/trace.go:171","msg":"trace[1937940882] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:395; }","duration":"263.106521ms","start":"2026-04-21T19:16:55.435384Z","end":"2026-04-21T19:16:55.69849Z","steps":["trace[1937940882] 'agreement among raft nodes before linearized reading' (duration: 262.960438ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:16:55.69891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:16:55.323298Z","time spent":"375.083482ms","remote":"127.0.0.1:53340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T19:16:56.419662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.600837ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T19:16:56.419775Z","caller":"traceutil/trace.go:171","msg":"trace[46396971] linearizableReadLoop","detail":"{readStateIndex:410; appliedIndex:409; }","duration":"560.746823ms","start":"2026-04-21T19:16:55.859013Z","end":"2026-04-21T19:16:56.41976Z","steps":["trace[46396971] 'read index received' (duration: 405.7895ms)","trace[46396971] 'applied index is now lower than readState.Index' (duration: 154.956143ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:16:56.419841Z","caller":"traceutil/trace.go:171","msg":"trace[1656278972] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"715.505111ms","start":"2026-04-21T19:16:55.704287Z","end":"2026-04-21T19:16:56.419792Z","steps":["trace[1656278972] 'process raft request' (duration: 560.569239ms)","trace[1656278972] 'compare' (duration: 154.485594ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T19:16:56.419946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.227752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T19:16:56.419894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.885935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.235\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-21T19:16:56.420041Z","caller":"traceutil/trace.go:171","msg":"trace[2081004287] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:396; }","duration":"163.365294ms","start":"2026-04-21T19:16:56.256652Z","end":"2026-04-21T19:16:56.420017Z","steps":["trace[2081004287] 'agreement among raft nodes before linearized reading' (duration: 163.233482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:16:56.4201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:16:55.704258Z","time spent":"715.710305ms","remote":"127.0.0.1:53340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T19:16:56.420102Z","caller":"traceutil/trace.go:171","msg":"trace[1123482457] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; response_count:1; response_revision:396; }","duration":"561.122739ms","start":"2026-04-21T19:16:55.858953Z","end":"2026-04-21T19:16:56.420076Z","steps":["trace[1123482457] 'agreement among raft nodes before linearized reading' (duration: 560.892535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:16:56.42018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:16:55.858925Z","time spent":"561.235921ms","remote":"127.0.0.1:53082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.235\" "} {"level":"info","ts":"2026-04-21T19:16:56.553062Z","caller":"traceutil/trace.go:171","msg":"trace[623373032] linearizableReadLoop","detail":"{readStateIndex:411; appliedIndex:410; }","duration":"127.947116ms","start":"2026-04-21T19:16:56.425079Z","end":"2026-04-21T19:16:56.553026Z","steps":["trace[623373032] 'read index received' (duration: 115.890225ms)","trace[623373032] 'applied index is now lower than readState.Index' (duration: 12.05519ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T19:16:56.553303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.210551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7303"} {"level":"info","ts":"2026-04-21T19:16:56.553379Z","caller":"traceutil/trace.go:171","msg":"trace[1128569178] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:396; }","duration":"128.307573ms","start":"2026-04-21T19:16:56.42505Z","end":"2026-04-21T19:16:56.553358Z","steps":["trace[1128569178] 'agreement among raft nodes before linearized reading' (duration: 128.15011ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:16:56.553391Z","caller":"traceutil/trace.go:171","msg":"trace[446425479] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"126.059991ms","start":"2026-04-21T19:16:56.427297Z","end":"2026-04-21T19:16:56.553357Z","steps":["trace[446425479] 'process raft request' (duration: 125.891517ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:17:00.825541Z","caller":"traceutil/trace.go:171","msg":"trace[1357791739] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"197.743331ms","start":"2026-04-21T19:17:00.62776Z","end":"2026-04-21T19:17:00.825503Z","steps":["trace[1357791739] 'process raft request' (duration: 197.536177ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:17:00.86952Z","caller":"traceutil/trace.go:171","msg":"trace[1826298965] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"241.354205ms","start":"2026-04-21T19:17:00.628131Z","end":"2026-04-21T19:17:00.869485Z","steps":["trace[1826298965] 'process raft request' (duration: 241.196692ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:17:01.060188Z","caller":"traceutil/trace.go:171","msg":"trace[90158802] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"169.219276ms","start":"2026-04-21T19:17:00.890932Z","end":"2026-04-21T19:17:01.060151Z","steps":["trace[90158802] 'process raft request' (duration: 162.707991ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:17:01.929833Z","caller":"traceutil/trace.go:171","msg":"trace[2121302294] linearizableReadLoop","detail":"{readStateIndex:460; appliedIndex:459; }","duration":"200.549534ms","start":"2026-04-21T19:17:01.729255Z","end":"2026-04-21T19:17:01.929804Z","steps":["trace[2121302294] 'read index received' (duration: 200.430472ms)","trace[2121302294] 'applied index is now lower than readState.Index' (duration: 115.032µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:17:01.929985Z","caller":"traceutil/trace.go:171","msg":"trace[274661340] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"328.774737ms","start":"2026-04-21T19:17:01.601147Z","end":"2026-04-21T19:17:01.929922Z","steps":["trace[274661340] 'process raft request' (duration: 328.493251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:17:01.930047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.776988ms","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-21T19:17:01.930103Z","caller":"traceutil/trace.go:171","msg":"trace[1246906879] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:444; }","duration":"200.892401ms","start":"2026-04-21T19:17:01.729198Z","end":"2026-04-21T19:17:01.930091Z","steps":["trace[1246906879] 'agreement among raft nodes before linearized reading' (duration: 200.734848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:17:01.93026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:17:01.601125Z","time spent":"328.987461ms","remote":"127.0.0.1:53268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6950,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T19:17:07.391504Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920880426023003894,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T19:17:07.892245Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920880426023003894,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T19:17:08.134518Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.277265715s","expected-duration":"1s"} {"level":"info","ts":"2026-04-21T19:17:08.135012Z","caller":"traceutil/trace.go:171","msg":"trace[177767437] linearizableReadLoop","detail":"{readStateIndex:476; appliedIndex:475; }","duration":"1.243828734s","start":"2026-04-21T19:17:06.891152Z","end":"2026-04-21T19:17:08.134981Z","steps":["trace[177767437] 'read index received' (duration: 1.243614121s)","trace[177767437] 'applied index is now lower than readState.Index' (duration: 213.343µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:17:08.135119Z","caller":"traceutil/trace.go:171","msg":"trace[1713086440] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"1.277931977s","start":"2026-04-21T19:17:06.857148Z","end":"2026-04-21T19:17:08.13508Z","steps":["trace[1713086440] 'process raft request' (duration: 1.277691823s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:17:08.135185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"919.353168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T19:17:08.135226Z","caller":"traceutil/trace.go:171","msg":"trace[372358600] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:459; }","duration":"919.464831ms","start":"2026-04-21T19:17:07.215749Z","end":"2026-04-21T19:17:08.135214Z","steps":["trace[372358600] 'agreement among raft nodes before linearized reading' (duration: 919.38316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:17:08.135263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:17:07.215727Z","time spent":"919.527262ms","remote":"127.0.0.1:43012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T19:17:08.135321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:17:06.857129Z","time spent":"1.278086949s","remote":"127.0.0.1:53340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T19:17:08.135476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.834529ms","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-21T19:17:08.135658Z","caller":"traceutil/trace.go:171","msg":"trace[2145783900] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:459; }","duration":"172.055813ms","start":"2026-04-21T19:17:07.963541Z","end":"2026-04-21T19:17:08.135597Z","steps":["trace[2145783900] 'agreement among raft nodes before linearized reading' (duration: 171.723587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:17:08.136124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.244952255s","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-21T19:17:08.13618Z","caller":"traceutil/trace.go:171","msg":"trace[178695490] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:459; }","duration":"1.245080188s","start":"2026-04-21T19:17:06.891085Z","end":"2026-04-21T19:17:08.136165Z","steps":["trace[178695490] 'agreement among raft nodes before linearized reading' (duration: 1.244038329s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:17:08.136305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:17:06.891056Z","time spent":"1.245189179s","remote":"127.0.0.1:53340","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-21T19:17:08.342008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.180801ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T19:17:08.342184Z","caller":"traceutil/trace.go:171","msg":"trace[1613548517] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"201.34574ms","start":"2026-04-21T19:17:08.140795Z","end":"2026-04-21T19:17:08.34214Z","steps":["trace[1613548517] 'process raft request' (duration: 88.841783ms)","trace[1613548517] 'compare' (duration: 112.029679ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:17:08.34527Z","caller":"traceutil/trace.go:171","msg":"trace[396797553] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"203.240323ms","start":"2026-04-21T19:17:08.14199Z","end":"2026-04-21T19:17:08.345231Z","steps":["trace[396797553] 'process raft request' (duration: 203.007499ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:17:08.345832Z","caller":"traceutil/trace.go:171","msg":"trace[1286471027] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"179.08817ms","start":"2026-04-21T19:17:08.166726Z","end":"2026-04-21T19:17:08.345814Z","steps":["trace[1286471027] 'process raft request' (duration: 178.438768ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:17:08.345876Z","caller":"traceutil/trace.go:171","msg":"trace[1736712664] linearizableReadLoop","detail":"{readStateIndex:479; appliedIndex:476; }","duration":"130.415309ms","start":"2026-04-21T19:17:08.215403Z","end":"2026-04-21T19:17:08.345818Z","steps":["trace[1736712664] 'read index received' (duration: 14.223866ms)","trace[1736712664] 'applied index is now lower than readState.Index' (duration: 116.188733ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T19:17:08.346003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.581071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T19:17:08.346054Z","caller":"traceutil/trace.go:171","msg":"trace[2101892947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:462; }","duration":"130.701644ms","start":"2026-04-21T19:17:08.215336Z","end":"2026-04-21T19:17:08.346038Z","steps":["trace[2101892947] 'agreement among raft nodes before linearized reading' (duration: 130.600682ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:17:10.655872Z","caller":"traceutil/trace.go:171","msg":"trace[1760453833] linearizableReadLoop","detail":"{readStateIndex:481; appliedIndex:480; }","duration":"440.469224ms","start":"2026-04-21T19:17:10.215365Z","end":"2026-04-21T19:17:10.655834Z","steps":["trace[1760453833] 'read index received' (duration: 440.20517ms)","trace[1760453833] 'applied index is now lower than readState.Index' (duration: 262.784µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:17:10.656022Z","caller":"traceutil/trace.go:171","msg":"trace[1284616200] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"512.581649ms","start":"2026-04-21T19:17:10.143428Z","end":"2026-04-21T19:17:10.65601Z","steps":["trace[1284616200] 'process raft request' (duration: 512.235882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:17:10.656163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:17:10.14341Z","time spent":"512.64214ms","remote":"127.0.0.1:53340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T19:17:10.656381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.988165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T19:17:10.656422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.873115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-21T19:17:10.656467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.413473ms","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-21T19:17:10.656495Z","caller":"traceutil/trace.go:171","msg":"trace[1476230442] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:464; }","duration":"441.172978ms","start":"2026-04-21T19:17:10.215298Z","end":"2026-04-21T19:17:10.656471Z","steps":["trace[1476230442] 'agreement among raft nodes before linearized reading' (duration: 440.980075ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:17:10.656524Z","caller":"traceutil/trace.go:171","msg":"trace[1835655183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:464; }","duration":"145.997277ms","start":"2026-04-21T19:17:10.510486Z","end":"2026-04-21T19:17:10.656483Z","steps":["trace[1835655183] 'agreement among raft nodes before linearized reading' (duration: 145.819723ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:17:10.656552Z","caller":"traceutil/trace.go:171","msg":"trace[1364705470] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:464; }","duration":"306.522724ms","start":"2026-04-21T19:17:10.35001Z","end":"2026-04-21T19:17:10.656532Z","steps":["trace[1364705470] 'agreement among raft nodes before linearized reading' (duration: 306.373772ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:17:10.656552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:17:10.215272Z","time spent":"441.27185ms","remote":"127.0.0.1:43012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T19:17:10.656607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:17:10.349997Z","time spent":"306.597686ms","remote":"127.0.0.1:53340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-21T19:17:10.656734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.451787ms","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-21T19:17:10.657141Z","caller":"traceutil/trace.go:171","msg":"trace[1046824184] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:464; }","duration":"309.969876ms","start":"2026-04-21T19:17:10.34714Z","end":"2026-04-21T19:17:10.65711Z","steps":["trace[1046824184] 'agreement among raft nodes before linearized reading' (duration: 309.149912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:17:10.657223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:17:10.347123Z","time spent":"310.082178ms","remote":"127.0.0.1:53340","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-21T19:17:11.870283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.192945ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:26"} {"level":"info","ts":"2026-04-21T19:17:12.898652Z","caller":"traceutil/trace.go:171","msg":"trace[1663713458] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"128.645846ms","start":"2026-04-21T19:17:12.769974Z","end":"2026-04-21T19:17:12.89862Z","steps":["trace[1663713458] 'process raft request' (duration: 120.820207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:17:13.52368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.055424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T19:17:13.523801Z","caller":"traceutil/trace.go:171","msg":"trace[816216822] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:471; }","duration":"307.255338ms","start":"2026-04-21T19:17:13.216519Z","end":"2026-04-21T19:17:13.523774Z","steps":["trace[816216822] 'range keys from in-memory index tree' (duration: 306.934803ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:17:13.523853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:17:13.216493Z","time spent":"307.35029ms","remote":"127.0.0.1:43024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T19:17:13.523975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.2375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12665"} {"level":"info","ts":"2026-04-21T19:17:13.524134Z","caller":"traceutil/trace.go:171","msg":"trace[1791660025] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:471; }","duration":"479.492734ms","start":"2026-04-21T19:17:13.044604Z","end":"2026-04-21T19:17:13.524096Z","steps":["trace[1791660025] 'range keys from in-memory index tree' (duration: 479.019316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:17:13.524211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:17:13.044578Z","time spent":"479.617367ms","remote":"127.0.0.1:53162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":12686,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"warn","ts":"2026-04-21T19:17:16.724523Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920880426023004012,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-21T19:17:17.103108Z","caller":"traceutil/trace.go:171","msg":"trace[1689396] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"917.390234ms","start":"2026-04-21T19:17:16.185677Z","end":"2026-04-21T19:17:17.103068Z","steps":["trace[1689396] 'process raft request' (duration: 917.17659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:17:17.103309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:17:16.185639Z","time spent":"917.571897ms","remote":"127.0.0.1:53100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T19:17:17.110121Z","caller":"traceutil/trace.go:171","msg":"trace[1842031833] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"919.9775ms","start":"2026-04-21T19:17:16.190103Z","end":"2026-04-21T19:17:17.11008Z","steps":["trace[1842031833] 'process raft request' (duration: 919.844638ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:17:17.110191Z","caller":"traceutil/trace.go:171","msg":"trace[457120612] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"910.394588ms","start":"2026-04-21T19:17:16.199722Z","end":"2026-04-21T19:17:17.110117Z","steps":["trace[457120612] 'process raft request' (duration: 910.274716ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:17:17.110253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:17:16.190053Z","time spent":"920.144342ms","remote":"127.0.0.1:53100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T19:17:17.110276Z","caller":"traceutil/trace.go:171","msg":"trace[1458753228] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"919.97974ms","start":"2026-04-21T19:17:16.190062Z","end":"2026-04-21T19:17:17.110042Z","steps":["trace[1458753228] 'process raft request' (duration: 919.693465ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:17:17.110384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:17:16.199697Z","time spent":"910.597492ms","remote":"127.0.0.1:53100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T19:17:17.110421Z","caller":"traceutil/trace.go:171","msg":"trace[286924688] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"901.107222ms","start":"2026-04-21T19:17:16.20927Z","end":"2026-04-21T19:17:17.110377Z","steps":["trace[286924688] 'process raft request' (duration: 900.837027ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:17:17.110528Z","caller":"traceutil/trace.go:171","msg":"trace[896379904] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"896.314117ms","start":"2026-04-21T19:17:16.214203Z","end":"2026-04-21T19:17:17.110517Z","steps":["trace[896379904] 'process raft request' (duration: 895.95242ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:17:17.110567Z","caller":"traceutil/trace.go:171","msg":"trace[1189968508] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"896.300696ms","start":"2026-04-21T19:17:16.214244Z","end":"2026-04-21T19:17:17.110544Z","steps":["trace[1189968508] 'process raft request' (duration: 896.070581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:17:17.1106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:17:16.214197Z","time spent":"896.376778ms","remote":"127.0.0.1:53100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T19:17:17.110441Z","caller":"traceutil/trace.go:171","msg":"trace[1693950608] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"904.054484ms","start":"2026-04-21T19:17:16.206337Z","end":"2026-04-21T19:17:17.110391Z","steps":["trace[1693950608] 'process raft request' (duration: 903.705268ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:17:17.110607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:17:16.190036Z","time spent":"920.331286ms","remote":"127.0.0.1:53100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T19:17:17.110629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:17:16.21424Z","time spent":"896.362227ms","remote":"127.0.0.1:53100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T19:17:17.110652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:17:16.209263Z","time spent":"901.264955ms","remote":"127.0.0.1:53100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T19:17:17.110732Z","caller":"traceutil/trace.go:171","msg":"trace[609213309] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"886.246085ms","start":"2026-04-21T19:17:16.224397Z","end":"2026-04-21T19:17:17.110643Z","steps":["trace[609213309] 'process raft request' (duration: 885.971079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:17:17.110801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:17:16.206313Z","time spent":"904.357241ms","remote":"127.0.0.1:53100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T19:17:17.110794Z","caller":"traceutil/trace.go:171","msg":"trace[1683863938] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"865.30165ms","start":"2026-04-21T19:17:16.245383Z","end":"2026-04-21T19:17:17.110685Z","steps":["trace[1683863938] 'process raft request' (duration: 865.150917ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:17:17.110907Z","caller":"traceutil/trace.go:171","msg":"trace[198731244] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"738.899764ms","start":"2026-04-21T19:17:16.371996Z","end":"2026-04-21T19:17:17.110895Z","steps":["trace[198731244] 'process raft request' (duration: 738.814823ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:17:17.110906Z","caller":"traceutil/trace.go:171","msg":"trace[1001743808] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"839.433187ms","start":"2026-04-21T19:17:16.271457Z","end":"2026-04-21T19:17:17.11089Z","steps":["trace[1001743808] 'process raft request' (duration: 839.145962ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:17:17.110959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:17:16.371943Z","time spent":"738.995736ms","remote":"127.0.0.1:53100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T19:17:17.110911Z","caller":"traceutil/trace.go:171","msg":"trace[544128141] linearizableReadLoop","detail":"{readStateIndex:542; appliedIndex:530; }","duration":"886.52098ms","start":"2026-04-21T19:17:16.224361Z","end":"2026-04-21T19:17:17.110882Z","steps":["trace[544128141] 'read index received' (duration: 878.556157ms)","trace[544128141] 'applied index is now lower than readState.Index' (duration: 7.963663ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T19:17:17.110972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:17:16.271438Z","time spent":"839.506118ms","remote":"127.0.0.1:53100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T19:17:17.110953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:17:16.224382Z","time spent":"886.47501ms","remote":"127.0.0.1:53100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T19:17:17.110992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:17:16.245339Z","time spent":"865.518364ms","remote":"127.0.0.1:53100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T19:17:17.111071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.705233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T19:17:17.11111Z","caller":"traceutil/trace.go:171","msg":"trace[1206358850] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:523; }","duration":"886.798745ms","start":"2026-04-21T19:17:16.2243Z","end":"2026-04-21T19:17:17.111099Z","steps":["trace[1206358850] 'agreement among raft nodes before linearized reading' (duration: 886.661883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:17:17.111137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:17:16.224277Z","time spent":"886.856177ms","remote":"127.0.0.1:43024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T19:17:17.115109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.861313ms","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-21T19:17:17.115197Z","caller":"traceutil/trace.go:171","msg":"trace[1308083945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:524; }","duration":"431.014535ms","start":"2026-04-21T19:17:16.684163Z","end":"2026-04-21T19:17:17.115178Z","steps":["trace[1308083945] 'agreement among raft nodes before linearized reading' (duration: 430.771471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:17:17.115253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:17:16.684134Z","time spent":"431.104297ms","remote":"127.0.0.1:53340","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-21T19:17:17.115533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.443114ms","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-21T19:17:17.115573Z","caller":"traceutil/trace.go:171","msg":"trace[1262241107] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:524; }","duration":"286.548867ms","start":"2026-04-21T19:17:16.829014Z","end":"2026-04-21T19:17:17.115563Z","steps":["trace[1262241107] 'agreement among raft nodes before linearized reading' (duration: 286.052218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:17:17.115674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.460705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-21T19:17:17.115121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.170278ms","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-21T19:17:17.115705Z","caller":"traceutil/trace.go:171","msg":"trace[609887136] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:524; }","duration":"281.542526ms","start":"2026-04-21T19:17:16.834155Z","end":"2026-04-21T19:17:17.115697Z","steps":["trace[609887136] 'agreement among raft nodes before linearized reading' (duration: 280.839784ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:17:17.115725Z","caller":"traceutil/trace.go:171","msg":"trace[293784616] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:524; }","duration":"200.82035ms","start":"2026-04-21T19:17:16.914893Z","end":"2026-04-21T19:17:17.115713Z","steps":["trace[293784616] 'agreement among raft nodes before linearized reading' (duration: 200.155958ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:17:17.362764Z","caller":"traceutil/trace.go:171","msg":"trace[649760563] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"108.73733ms","start":"2026-04-21T19:17:17.253994Z","end":"2026-04-21T19:17:17.362732Z","steps":["trace[649760563] 'process raft request' (duration: 95.794867ms)","trace[649760563] 'compare' (duration: 12.7678ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:17:17.366846Z","caller":"traceutil/trace.go:171","msg":"trace[5881510] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"105.420161ms","start":"2026-04-21T19:17:17.261369Z","end":"2026-04-21T19:17:17.366789Z","steps":["trace[5881510] 'process raft request' (duration: 101.958718ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:17:23.771057Z","caller":"traceutil/trace.go:171","msg":"trace[48499687] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"532.77434ms","start":"2026-04-21T19:17:23.238258Z","end":"2026-04-21T19:17:23.771032Z","steps":["trace[48499687] 'process raft request' (duration: 532.579126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:17:23.771375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:17:23.238241Z","time spent":"533.042074ms","remote":"127.0.0.1:53340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T19:17:23.771522Z","caller":"traceutil/trace.go:171","msg":"trace[1296190258] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"533.122166ms","start":"2026-04-21T19:17:23.238329Z","end":"2026-04-21T19:17:23.771451Z","steps":["trace[1296190258] 'process raft request' (duration: 532.662838ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:17:23.771787Z","caller":"traceutil/trace.go:171","msg":"trace[1510508746] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"533.36605ms","start":"2026-04-21T19:17:23.23837Z","end":"2026-04-21T19:17:23.771736Z","steps":["trace[1510508746] 'process raft request' (duration: 532.891692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:17:23.771939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:17:23.238322Z","time spent":"533.429751ms","remote":"127.0.0.1:53340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T19:17:23.772004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:17:23.238364Z","time spent":"533.537693ms","remote":"127.0.0.1:53340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T19:17:23.772361Z","caller":"traceutil/trace.go:171","msg":"trace[1852160855] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"528.158178ms","start":"2026-04-21T19:17:23.244187Z","end":"2026-04-21T19:17:23.772345Z","steps":["trace[1852160855] 'process raft request' (duration: 527.512526ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:17:23.772441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:17:23.244178Z","time spent":"528.216719ms","remote":"127.0.0.1:53162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T19:17:24.181878Z","caller":"traceutil/trace.go:171","msg":"trace[686868793] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"394.779786ms","start":"2026-04-21T19:17:23.787059Z","end":"2026-04-21T19:17:24.181838Z","steps":["trace[686868793] 'process raft request' (duration: 394.631934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:17:24.182017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:17:23.787026Z","time spent":"394.919189ms","remote":"127.0.0.1:53268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T19:17:26.215832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00001607s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-21T19:17:26.215934Z","caller":"traceutil/trace.go:171","msg":"trace[542956779] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000194913s","start":"2026-04-21T19:17:24.215717Z","end":"2026-04-21T19:17:26.215912Z","steps":["trace[542956779] 'agreement among raft nodes before linearized reading' (duration: 2.000054821s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:17:26.21599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:17:24.215695Z","time spent":"2.000284294s","remote":"127.0.0.1:43024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T19:17:26.301946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.997360232s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-21T19:17:26.302233Z","caller":"traceutil/trace.go:171","msg":"trace[443201192] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"1.027342724s","start":"2026-04-21T19:17:25.274865Z","end":"2026-04-21T19:17:26.302208Z","steps":["trace[443201192] 'process raft request' (duration: 1.027213232s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:17:26.302338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:17:25.274834Z","time spent":"1.027439965s","remote":"127.0.0.1:53340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T19:17:26.30247Z","caller":"traceutil/trace.go:171","msg":"trace[2017336249] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"2.086652433s","start":"2026-04-21T19:17:24.215785Z","end":"2026-04-21T19:17:26.302437Z","steps":["trace[2017336249] 'read index received' (duration: 88.6267ms)","trace[2017336249] 'applied index is now lower than readState.Index' (duration: 1.998007523s)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:17:26.302612Z","caller":"traceutil/trace.go:171","msg":"trace[1238683383] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"2.472524799s","start":"2026-04-21T19:17:23.83003Z","end":"2026-04-21T19:17:26.302554Z","steps":["trace[1238683383] 'process raft request' (duration: 474.439655ms)","trace[1238683383] 'compare' (duration: 1.996921303s)"],"step_count":2} {"level":"warn","ts":"2026-04-21T19:17:26.302711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.745545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.235\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-21T19:17:26.302769Z","caller":"traceutil/trace.go:171","msg":"trace[1057844973] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; response_count:1; response_revision:624; }","duration":"442.835008ms","start":"2026-04-21T19:17:25.859918Z","end":"2026-04-21T19:17:26.302753Z","steps":["trace[1057844973] 'agreement among raft nodes before linearized reading' (duration: 442.709645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:17:26.302821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:17:25.859907Z","time spent":"442.90138ms","remote":"127.0.0.1:53082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.235\" "} {"level":"warn","ts":"2026-04-21T19:17:26.302822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:17:23.829984Z","time spent":"2.472735652s","remote":"127.0.0.1:53572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T19:17:26.302828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.484369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-21T19:17:26.302872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.439778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-21T19:17:26.302865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"996.605764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-21T19:17:26.302952Z","caller":"traceutil/trace.go:171","msg":"trace[528838791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:624; }","duration":"525.55007ms","start":"2026-04-21T19:17:25.77738Z","end":"2026-04-21T19:17:26.30293Z","steps":["trace[528838791] 'agreement among raft nodes before linearized reading' (duration: 525.308735ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:17:26.302978Z","caller":"traceutil/trace.go:171","msg":"trace[578393006] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:624; }","duration":"996.760787ms","start":"2026-04-21T19:17:25.306186Z","end":"2026-04-21T19:17:26.302947Z","steps":["trace[578393006] 'agreement among raft nodes before linearized reading' (duration: 996.445541ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:17:26.303006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:17:25.777366Z","time spent":"525.629152ms","remote":"127.0.0.1:53340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-21T19:17:26.302966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.907297ms","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-21T19:17:26.303092Z","caller":"traceutil/trace.go:171","msg":"trace[244118736] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:624; }","duration":"526.099361ms","start":"2026-04-21T19:17:25.776963Z","end":"2026-04-21T19:17:26.303063Z","steps":["trace[244118736] 'agreement among raft nodes before linearized reading' (duration: 525.722074ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:17:26.303153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:17:25.776928Z","time spent":"526.216792ms","remote":"127.0.0.1:53340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-21T19:17:26.303025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:17:25.306174Z","time spent":"996.841968ms","remote":"127.0.0.1:53340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-21T19:17:26.302891Z","caller":"traceutil/trace.go:171","msg":"trace[464705713] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:624; }","duration":"525.586102ms","start":"2026-04-21T19:17:25.777285Z","end":"2026-04-21T19:17:26.302871Z","steps":["trace[464705713] 'agreement among raft nodes before linearized reading' (duration: 525.385238ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:17:26.303315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:17:25.777255Z","time spent":"525.990798ms","remote":"127.0.0.1:53340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-21T19:17:26.819967Z","caller":"traceutil/trace.go:171","msg":"trace[672079367] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"105.319627ms","start":"2026-04-21T19:17:26.71462Z","end":"2026-04-21T19:17:26.819939Z","steps":["trace[672079367] 'process raft request' (duration: 104.453893ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:17:28.253281Z","caller":"traceutil/trace.go:171","msg":"trace[2132608339] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"278.300259ms","start":"2026-04-21T19:17:27.974941Z","end":"2026-04-21T19:17:28.253241Z","steps":["trace[2132608339] 'process raft request' (duration: 278.116196ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:17:32.441624Z","caller":"traceutil/trace.go:171","msg":"trace[255005108] linearizableReadLoop","detail":"{readStateIndex:745; appliedIndex:744; }","duration":"269.555371ms","start":"2026-04-21T19:17:32.172035Z","end":"2026-04-21T19:17:32.44159Z","steps":["trace[255005108] 'read index received' (duration: 269.417219ms)","trace[255005108] 'applied index is now lower than readState.Index' (duration: 137.252µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:17:32.441699Z","caller":"traceutil/trace.go:171","msg":"trace[2081711322] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"277.500124ms","start":"2026-04-21T19:17:32.164143Z","end":"2026-04-21T19:17:32.441643Z","steps":["trace[2081711322] 'process raft request' (duration: 277.26416ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:17:32.441918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.680315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T19:17:32.441972Z","caller":"traceutil/trace.go:171","msg":"trace[1757549253] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:717; }","duration":"225.845179ms","start":"2026-04-21T19:17:32.21611Z","end":"2026-04-21T19:17:32.441955Z","steps":["trace[1757549253] 'agreement among raft nodes before linearized reading' (duration: 225.710096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:17:32.442056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.0165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-04-21T19:17:32.442122Z","caller":"traceutil/trace.go:171","msg":"trace[2043509933] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:717; }","duration":"270.134152ms","start":"2026-04-21T19:17:32.171972Z","end":"2026-04-21T19:17:32.442107Z","steps":["trace[2043509933] 'agreement among raft nodes before linearized reading' (duration: 269.711904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:17:32.926867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.966149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T19:17:32.927573Z","caller":"traceutil/trace.go:171","msg":"trace[1021414837] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:723; }","duration":"140.679831ms","start":"2026-04-21T19:17:32.786848Z","end":"2026-04-21T19:17:32.927528Z","steps":["trace[1021414837] 'agreement among raft nodes before linearized reading' (duration: 139.951339ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:17:32.927167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.721472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T19:17:32.928278Z","caller":"traceutil/trace.go:171","msg":"trace[1103989706] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:723; }","duration":"141.464925ms","start":"2026-04-21T19:17:32.78637Z","end":"2026-04-21T19:17:32.927835Z","steps":["trace[1103989706] 'agreement among raft nodes before linearized reading' (duration: 140.763362ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:17:40.040504Z","caller":"traceutil/trace.go:171","msg":"trace[1247728748] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"149.736444ms","start":"2026-04-21T19:17:39.890742Z","end":"2026-04-21T19:17:40.040478Z","steps":["trace[1247728748] 'process raft request' (duration: 149.56683ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:17:40.825309Z","caller":"traceutil/trace.go:171","msg":"trace[405563184] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"284.139904ms","start":"2026-04-21T19:17:40.541127Z","end":"2026-04-21T19:17:40.825267Z","steps":["trace[405563184] 'process raft request' (duration: 284.065653ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:17:40.825617Z","caller":"traceutil/trace.go:171","msg":"trace[785548787] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"285.389806ms","start":"2026-04-21T19:17:40.540214Z","end":"2026-04-21T19:17:40.825604Z","steps":["trace[785548787] 'process raft request' (duration: 284.753265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:17:44.563045Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920880426023004577,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T19:17:44.755064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.797433ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T19:17:44.755267Z","caller":"traceutil/trace.go:171","msg":"trace[357504152] linearizableReadLoop","detail":"{readStateIndex:823; appliedIndex:822; }","duration":"692.247059ms","start":"2026-04-21T19:17:44.062993Z","end":"2026-04-21T19:17:44.75524Z","steps":["trace[357504152] 'read index received' (duration: 595.897332ms)","trace[357504152] 'applied index is now lower than readState.Index' (duration: 96.348607ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:17:44.755267Z","caller":"traceutil/trace.go:171","msg":"trace[1025151106] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:791; }","duration":"662.014097ms","start":"2026-04-21T19:17:44.093219Z","end":"2026-04-21T19:17:44.755233Z","steps":["trace[1025151106] 'range keys from in-memory index tree' (duration: 661.767733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:17:44.755442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.143132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T19:17:44.755499Z","caller":"traceutil/trace.go:171","msg":"trace[801447523] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:792; }","duration":"540.246214ms","start":"2026-04-21T19:17:44.215235Z","end":"2026-04-21T19:17:44.755481Z","steps":["trace[801447523] 'agreement among raft nodes before linearized reading' (duration: 540.156542ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:17:44.755537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:17:44.215207Z","time spent":"540.319526ms","remote":"127.0.0.1:43024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-21T19:17:44.755338Z","caller":"traceutil/trace.go:171","msg":"trace[2068642904] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"918.429433ms","start":"2026-04-21T19:17:43.836868Z","end":"2026-04-21T19:17:44.755297Z","steps":["trace[2068642904] 'process raft request' (duration: 822.005864ms)","trace[2068642904] 'compare' (duration: 95.818167ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T19:17:44.755636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.616315ms","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":"warn","ts":"2026-04-21T19:17:44.755695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.612072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-21T19:17:44.755701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.688661ms","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-21T19:17:44.755753Z","caller":"traceutil/trace.go:171","msg":"trace[1014456190] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:792; }","duration":"192.715784ms","start":"2026-04-21T19:17:44.563019Z","end":"2026-04-21T19:17:44.755735Z","steps":["trace[1014456190] 'agreement among raft nodes before linearized reading' (duration: 192.545181ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:17:44.755762Z","caller":"traceutil/trace.go:171","msg":"trace[106849928] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:792; }","duration":"692.795368ms","start":"2026-04-21T19:17:44.062935Z","end":"2026-04-21T19:17:44.75573Z","steps":["trace[106849928] 'agreement among raft nodes before linearized reading' (duration: 692.396511ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:17:44.755802Z","caller":"traceutil/trace.go:171","msg":"trace[1241981514] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:792; }","duration":"164.832024ms","start":"2026-04-21T19:17:44.590948Z","end":"2026-04-21T19:17:44.75578Z","steps":["trace[1241981514] 'agreement among raft nodes before linearized reading' (duration: 164.455988ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:17:44.755822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:17:44.062908Z","time spent":"692.903361ms","remote":"127.0.0.1:53340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-21T19:17:44.7558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:17:43.836835Z","time spent":"918.773698ms","remote":"127.0.0.1:53310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T19:17:45.026671Z","caller":"traceutil/trace.go:171","msg":"trace[2114453437] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"265.381837ms","start":"2026-04-21T19:17:44.761257Z","end":"2026-04-21T19:17:45.026639Z","steps":["trace[2114453437] 'process raft request' (duration: 260.816694ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:17:45.02719Z","caller":"traceutil/trace.go:171","msg":"trace[1721986541] linearizableReadLoop","detail":"{readStateIndex:826; appliedIndex:823; }","duration":"201.256588ms","start":"2026-04-21T19:17:44.825898Z","end":"2026-04-21T19:17:45.027155Z","steps":["trace[1721986541] 'read index received' (duration: 196.263639ms)","trace[1721986541] 'applied index is now lower than readState.Index' (duration: 4.990969ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:17:45.027229Z","caller":"traceutil/trace.go:171","msg":"trace[458671939] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"265.847875ms","start":"2026-04-21T19:17:44.76135Z","end":"2026-04-21T19:17:45.027197Z","steps":["trace[458671939] 'process raft request' (duration: 265.585621ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:17:45.027351Z","caller":"traceutil/trace.go:171","msg":"trace[1747270259] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"265.847165ms","start":"2026-04-21T19:17:44.76149Z","end":"2026-04-21T19:17:45.027338Z","steps":["trace[1747270259] 'process raft request' (duration: 265.614531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:17:45.027586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.187233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-21T19:17:45.027614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.278647ms","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-21T19:17:45.027591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.616485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-21T19:17:45.027653Z","caller":"traceutil/trace.go:171","msg":"trace[2008421088] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:795; }","duration":"182.334718ms","start":"2026-04-21T19:17:44.845307Z","end":"2026-04-21T19:17:45.027642Z","steps":["trace[2008421088] 'agreement among raft nodes before linearized reading' (duration: 182.201676ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:17:45.027645Z","caller":"traceutil/trace.go:171","msg":"trace[1509770871] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:795; }","duration":"183.305445ms","start":"2026-04-21T19:17:44.844324Z","end":"2026-04-21T19:17:45.02763Z","steps":["trace[1509770871] 'agreement among raft nodes before linearized reading' (duration: 183.102391ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:17:45.027661Z","caller":"traceutil/trace.go:171","msg":"trace[1324617802] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:795; }","duration":"201.839078ms","start":"2026-04-21T19:17:44.825807Z","end":"2026-04-21T19:17:45.027646Z","steps":["trace[1324617802] 'agreement among raft nodes before linearized reading' (duration: 201.656405ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:17:55.206926Z","caller":"traceutil/trace.go:171","msg":"trace[426100510] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"122.666689ms","start":"2026-04-21T19:17:55.084221Z","end":"2026-04-21T19:17:55.206888Z","steps":["trace[426100510] 'process raft request' (duration: 117.926244ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:17:55.256873Z","caller":"traceutil/trace.go:171","msg":"trace[61378982] linearizableReadLoop","detail":"{readStateIndex:951; appliedIndex:949; }","duration":"171.746598ms","start":"2026-04-21T19:17:55.085095Z","end":"2026-04-21T19:17:55.256842Z","steps":["trace[61378982] 'read index received' (duration: 117.073088ms)","trace[61378982] 'applied index is now lower than readState.Index' (duration: 54.67245ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:17:55.257039Z","caller":"traceutil/trace.go:171","msg":"trace[1661922751] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"172.174626ms","start":"2026-04-21T19:17:55.084847Z","end":"2026-04-21T19:17:55.257021Z","steps":["trace[1661922751] 'process raft request' (duration: 171.736348ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:17:55.25732Z","caller":"traceutil/trace.go:171","msg":"trace[754160304] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"171.846461ms","start":"2026-04-21T19:17:55.085461Z","end":"2026-04-21T19:17:55.257308Z","steps":["trace[754160304] 'process raft request' (duration: 171.28426ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:17:55.25741Z","caller":"traceutil/trace.go:171","msg":"trace[1532890223] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"170.168701ms","start":"2026-04-21T19:17:55.087234Z","end":"2026-04-21T19:17:55.257402Z","steps":["trace[1532890223] 'process raft request' (duration: 169.547259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:17:55.257591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.486741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-21T19:17:55.257627Z","caller":"traceutil/trace.go:171","msg":"trace[126037609] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:919; }","duration":"172.590953ms","start":"2026-04-21T19:17:55.085027Z","end":"2026-04-21T19:17:55.257617Z","steps":["trace[126037609] 'agreement among raft nodes before linearized reading' (duration: 172.442681ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:17:56.74756Z","caller":"traceutil/trace.go:171","msg":"trace[2086495200] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:967; }","duration":"100.474971ms","start":"2026-04-21T19:17:56.647054Z","end":"2026-04-21T19:17:56.747529Z","steps":["trace[2086495200] 'read index received' (duration: 25.133861ms)","trace[2086495200] 'applied index is now lower than readState.Index' (duration: 75.33978ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:17:56.747573Z","caller":"traceutil/trace.go:171","msg":"trace[2053348154] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"100.687324ms","start":"2026-04-21T19:17:56.646847Z","end":"2026-04-21T19:17:56.747535Z","steps":["trace[2053348154] 'process raft request' (duration: 85.751526ms)","trace[2053348154] 'compare' (duration: 14.651764ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T19:17:56.747742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.645354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T19:17:56.74785Z","caller":"traceutil/trace.go:171","msg":"trace[1495470252] range","detail":"{range_begin:/registry/limitranges/local-path-storage/; range_end:/registry/limitranges/local-path-storage0; response_count:0; response_revision:935; }","duration":"100.839768ms","start":"2026-04-21T19:17:56.646994Z","end":"2026-04-21T19:17:56.747834Z","steps":["trace[1495470252] 'agreement among raft nodes before linearized reading' (duration: 100.655374ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:17:56.990932Z","caller":"traceutil/trace.go:171","msg":"trace[1472914590] linearizableReadLoop","detail":"{readStateIndex:975; appliedIndex:972; }","duration":"170.16072ms","start":"2026-04-21T19:17:56.820739Z","end":"2026-04-21T19:17:56.9909Z","steps":["trace[1472914590] 'read index received' (duration: 86.069373ms)","trace[1472914590] 'applied index is now lower than readState.Index' (duration: 84.089967ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:17:56.991004Z","caller":"traceutil/trace.go:171","msg":"trace[1627538891] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"228.982415ms","start":"2026-04-21T19:17:56.761967Z","end":"2026-04-21T19:17:56.990949Z","steps":["trace[1627538891] 'process raft request' (duration: 228.740141ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:17:56.99112Z","caller":"traceutil/trace.go:171","msg":"trace[52728708] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"228.408874ms","start":"2026-04-21T19:17:56.762686Z","end":"2026-04-21T19:17:56.991095Z","steps":["trace[52728708] 'process raft request' (duration: 228.111609ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:17:56.991301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.534237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12755"} {"level":"info","ts":"2026-04-21T19:17:56.991349Z","caller":"traceutil/trace.go:171","msg":"trace[98171229] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:940; }","duration":"170.685629ms","start":"2026-04-21T19:17:56.820649Z","end":"2026-04-21T19:17:56.991335Z","steps":["trace[98171229] 'agreement among raft nodes before linearized reading' (duration: 170.462056ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:17:56.991496Z","caller":"traceutil/trace.go:171","msg":"trace[59134994] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"230.909469ms","start":"2026-04-21T19:17:56.759967Z","end":"2026-04-21T19:17:56.990876Z","steps":["trace[59134994] 'process raft request' (duration: 147.015976ms)","trace[59134994] 'compare' (duration: 83.562538ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T19:17:57.113911Z","caller":"traceutil/trace.go:171","msg":"trace[561607126] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"111.213195ms","start":"2026-04-21T19:17:57.002671Z","end":"2026-04-21T19:17:57.113884Z","steps":["trace[561607126] 'process raft request' (duration: 110.856618ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:17:57.114092Z","caller":"traceutil/trace.go:171","msg":"trace[848218872] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"110.200316ms","start":"2026-04-21T19:17:57.00386Z","end":"2026-04-21T19:17:57.114061Z","steps":["trace[848218872] 'process raft request' (duration: 109.943001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:18:13.450645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.820129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-21T19:18:13.450768Z","caller":"traceutil/trace.go:171","msg":"trace[597852922] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1191; }","duration":"144.007792ms","start":"2026-04-21T19:18:13.306736Z","end":"2026-04-21T19:18:13.450743Z","steps":["trace[597852922] 'range keys from in-memory index tree' (duration: 143.585525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:18:14.570073Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.088123187s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-21T19:18:14.583225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.013558ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T19:18:14.583409Z","caller":"traceutil/trace.go:171","msg":"trace[667063683] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1192; }","duration":"490.236212ms","start":"2026-04-21T19:18:14.093148Z","end":"2026-04-21T19:18:14.583384Z","steps":["trace[667063683] 'range keys from in-memory index tree' (duration: 489.986818ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T19:18:14.583453Z","caller":"traceutil/trace.go:171","msg":"trace[1574517353] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"1.101616243s","start":"2026-04-21T19:18:13.481801Z","end":"2026-04-21T19:18:14.583417Z","steps":["trace[1574517353] 'process raft request' (duration: 1.088585795s)","trace[1574517353] 'compare' (duration: 12.172694ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T19:18:14.583582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:18:13.481779Z","time spent":"1.101730016s","remote":"127.0.0.1:53340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T19:18:14.586125Z","caller":"traceutil/trace.go:171","msg":"trace[88335583] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"1.10371676s","start":"2026-04-21T19:18:13.482389Z","end":"2026-04-21T19:18:14.586106Z","steps":["trace[88335583] 'process raft request' (duration: 1.103545517s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:18:14.58622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:18:13.482353Z","time spent":"1.103822681s","remote":"127.0.0.1:53340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T19:18:14.586339Z","caller":"traceutil/trace.go:171","msg":"trace[1396974556] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"1.10255728s","start":"2026-04-21T19:18:13.483764Z","end":"2026-04-21T19:18:14.586321Z","steps":["trace[1396974556] 'process raft request' (duration: 1.102299956s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:18:14.586447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:18:13.483753Z","time spent":"1.102640541s","remote":"127.0.0.1:53340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T19:18:14.586822Z","caller":"traceutil/trace.go:171","msg":"trace[45389130] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"1.102346915s","start":"2026-04-21T19:18:13.484421Z","end":"2026-04-21T19:18:14.586768Z","steps":["trace[45389130] 'process raft request' (duration: 1.101830927s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T19:18:14.587173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T19:18:13.48441Z","time spent":"1.102533958s","remote":"127.0.0.1:53340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T19:18:26.683059Z","caller":"traceutil/trace.go:171","msg":"trace[356748949] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"116.127516ms","start":"2026-04-21T19:18:26.566894Z","end":"2026-04-21T19:18:26.683021Z","steps":["trace[356748949] 'process raft request' (duration: 115.80497ms)"],"step_count":1}