{"level":"warn","ts":"2026-04-09T11:15:40.196476Z","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-09T11:15:40.196607Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.3: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.3:2380","--initial-cluster=instance=https://199.204.45.3:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.3:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.3: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-09T11:15:40.196707Z","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-09T11:15:40.196719Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-04-09T11:15:40.196742Z","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-09T11:15:40.197873Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"]} {"level":"info","ts":"2026-04-09T11:15:40.197976Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.3: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-09T11:15:40.204255Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.985049ms"} {"level":"info","ts":"2026-04-09T11:15:40.21684Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"597401b06ce1cf3d","cluster-id":"dfc54657ad8a2432"} {"level":"info","ts":"2026-04-09T11:15:40.21703Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=()"} {"level":"info","ts":"2026-04-09T11:15:40.217122Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 0"} {"level":"info","ts":"2026-04-09T11:15:40.21731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 597401b06ce1cf3d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-09T11:15:40.217416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 1"} {"level":"info","ts":"2026-04-09T11:15:40.217587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"warn","ts":"2026-04-09T11:15:40.229341Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-09T11:15:40.230611Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-09T11:15:40.231889Z","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-09T11:15:40.235383Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"597401b06ce1cf3d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-09T11:15:40.235478Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"597401b06ce1cf3d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-09T11:15:40.24102Z","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-09T11:15:40.241174Z","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-09T11:15:40.241313Z","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-09T11:15:40.242185Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"info","ts":"2026-04-09T11:15:40.242356Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","added-peer-id":"597401b06ce1cf3d","added-peer-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-04-09T11:15:40.246447Z","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-09T11:15:40.24659Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-04-09T11:15:40.246689Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-04-09T11:15:40.251953Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"597401b06ce1cf3d","initial-advertise-peer-urls":["https://199.204.45.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-09T11:15:40.252007Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-09T11:15:40.618945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d is starting a new election at term 1"} {"level":"info","ts":"2026-04-09T11:15:40.619019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-09T11:15:40.619042Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgPreVoteResp from 597401b06ce1cf3d at term 1"} {"level":"info","ts":"2026-04-09T11:15:40.619063Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became candidate at term 2"} {"level":"info","ts":"2026-04-09T11:15:40.619074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgVoteResp from 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-04-09T11:15:40.619096Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became leader at term 2"} {"level":"info","ts":"2026-04-09T11:15:40.619116Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 597401b06ce1cf3d elected leader 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-04-09T11:15:40.620306Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T11:15:40.621694Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T11:15:40.621905Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T11:15:40.621825Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"597401b06ce1cf3d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.3:2379]}","request-path":"/0/members/597401b06ce1cf3d/attributes","cluster-id":"dfc54657ad8a2432","publish-timeout":"7s"} {"level":"info","ts":"2026-04-09T11:15:40.622033Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T11:15:40.621975Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T11:15:40.621939Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T11:15:40.622326Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-09T11:15:40.622354Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-09T11:15:40.624164Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-09T11:15:40.624757Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.3:2379"} {"level":"warn","ts":"2026-04-09T11:15:54.403631Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933265052188818547,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-09T11:15:54.491788Z","caller":"traceutil/trace.go:171","msg":"trace[1332600535] linearizableReadLoop","detail":"{readStateIndex:409; appliedIndex:408; }","duration":"588.496669ms","start":"2026-04-09T11:15:53.903267Z","end":"2026-04-09T11:15:54.491764Z","steps":["trace[1332600535] 'read index received' (duration: 538.445124ms)","trace[1332600535] 'applied index is now lower than readState.Index' (duration: 50.050685ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T11:15:54.491899Z","caller":"traceutil/trace.go:171","msg":"trace[286389093] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"687.320325ms","start":"2026-04-09T11:15:53.804551Z","end":"2026-04-09T11:15:54.491871Z","steps":["trace[286389093] 'process raft request' (duration: 637.132059ms)","trace[286389093] 'compare' (duration: 49.925974ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T11:15:54.492119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.821741ms","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-09T11:15:54.492204Z","caller":"traceutil/trace.go:171","msg":"trace[1682262630] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:397; }","duration":"588.974513ms","start":"2026-04-09T11:15:53.903213Z","end":"2026-04-09T11:15:54.492188Z","steps":["trace[1682262630] 'agreement among raft nodes before linearized reading' (duration: 588.750141ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:15:54.492258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:15:53.903192Z","time spent":"589.054433ms","remote":"127.0.0.1:60450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-09T11:15:54.492469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.628207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-09T11:15:54.492578Z","caller":"traceutil/trace.go:171","msg":"trace[3254843] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:397; }","duration":"442.802329ms","start":"2026-04-09T11:15:54.049754Z","end":"2026-04-09T11:15:54.492556Z","steps":["trace[3254843] 'agreement among raft nodes before linearized reading' (duration: 442.559238ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:15:54.49263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:15:54.049733Z","time spent":"442.888019ms","remote":"127.0.0.1:60450","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-09T11:15:54.492475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.09153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-09T11:15:54.492691Z","caller":"traceutil/trace.go:171","msg":"trace[656740299] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:397; }","duration":"338.310832ms","start":"2026-04-09T11:15:54.154361Z","end":"2026-04-09T11:15:54.492672Z","steps":["trace[656740299] 'agreement among raft nodes before linearized reading' (duration: 338.01168ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:15:54.492753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:15:54.154295Z","time spent":"338.443093ms","remote":"127.0.0.1:60450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-09T11:15:54.493112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:15:53.80453Z","time spent":"687.553536ms","remote":"127.0.0.1:60232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":116,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T11:15:55.340883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.453355ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T11:15:55.34097Z","caller":"traceutil/trace.go:171","msg":"trace[2049771534] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"843.145257ms","start":"2026-04-09T11:15:54.497811Z","end":"2026-04-09T11:15:55.340957Z","steps":["trace[2049771534] 'process raft request' (duration: 267.40408ms)","trace[2049771534] 'compare' (duration: 575.346345ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T11:15:55.341009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:15:54.49779Z","time spent":"843.201747ms","remote":"127.0.0.1:60450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T11:15:55.343156Z","caller":"traceutil/trace.go:171","msg":"trace[227560007] linearizableReadLoop","detail":"{readStateIndex:412; appliedIndex:409; }","duration":"844.378634ms","start":"2026-04-09T11:15:54.498766Z","end":"2026-04-09T11:15:55.343145Z","steps":["trace[227560007] 'read index received' (duration: 266.477124ms)","trace[227560007] 'applied index is now lower than readState.Index' (duration: 577.90075ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T11:15:55.343312Z","caller":"traceutil/trace.go:171","msg":"trace[1879256612] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"845.176579ms","start":"2026-04-09T11:15:54.498128Z","end":"2026-04-09T11:15:55.343305Z","steps":["trace[1879256612] 'process raft request' (duration: 844.941688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:15:55.343359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:15:54.498106Z","time spent":"845.22753ms","remote":"127.0.0.1:60450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T11:15:55.343484Z","caller":"traceutil/trace.go:171","msg":"trace[1235824700] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"845.076688ms","start":"2026-04-09T11:15:54.498401Z","end":"2026-04-09T11:15:55.343477Z","steps":["trace[1235824700] 'process raft request' (duration: 844.718736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:15:55.343525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:15:54.498392Z","time spent":"845.113959ms","remote":"127.0.0.1:60450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T11:15:55.343661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.883177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-09T11:15:55.343718Z","caller":"traceutil/trace.go:171","msg":"trace[169751287] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:400; }","duration":"844.953308ms","start":"2026-04-09T11:15:54.498751Z","end":"2026-04-09T11:15:55.343705Z","steps":["trace[169751287] 'agreement among raft nodes before linearized reading' (duration: 844.846927ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:15:55.343744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.686335ms","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-09T11:15:55.34376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:15:54.498745Z","time spent":"845.007248ms","remote":"127.0.0.1:60466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-04-09T11:15:55.343797Z","caller":"traceutil/trace.go:171","msg":"trace[1484548606] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:400; }","duration":"724.770766ms","start":"2026-04-09T11:15:54.619013Z","end":"2026-04-09T11:15:55.343784Z","steps":["trace[1484548606] 'agreement among raft nodes before linearized reading' (duration: 724.707076ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:15:55.343833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:15:54.618994Z","time spent":"724.832657ms","remote":"127.0.0.1:60450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-09T11:16:01.49552Z","caller":"traceutil/trace.go:171","msg":"trace[932249678] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"121.365485ms","start":"2026-04-09T11:16:01.374134Z","end":"2026-04-09T11:16:01.495499Z","steps":["trace[932249678] 'process raft request' (duration: 121.225424ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:16:01.569823Z","caller":"traceutil/trace.go:171","msg":"trace[1413323802] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"192.459874ms","start":"2026-04-09T11:16:01.377345Z","end":"2026-04-09T11:16:01.569805Z","steps":["trace[1413323802] 'process raft request' (duration: 192.434224ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:16:01.569864Z","caller":"traceutil/trace.go:171","msg":"trace[1217448345] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"192.960338ms","start":"2026-04-09T11:16:01.376871Z","end":"2026-04-09T11:16:01.569832Z","steps":["trace[1217448345] 'process raft request' (duration: 192.794517ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:16:01.570056Z","caller":"traceutil/trace.go:171","msg":"trace[1809741727] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"192.848067ms","start":"2026-04-09T11:16:01.377202Z","end":"2026-04-09T11:16:01.570051Z","steps":["trace[1809741727] 'process raft request' (duration: 192.555765ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:16:11.881835Z","caller":"traceutil/trace.go:171","msg":"trace[1537329755] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"559.677936ms","start":"2026-04-09T11:16:11.322126Z","end":"2026-04-09T11:16:11.881804Z","steps":["trace[1537329755] 'process raft request' (duration: 559.462205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:16:11.881977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:16:11.322105Z","time spent":"559.811184ms","remote":"127.0.0.1:60252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T11:16:11.892946Z","caller":"traceutil/trace.go:171","msg":"trace[1047593270] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:523; }","duration":"466.683049ms","start":"2026-04-09T11:16:11.426247Z","end":"2026-04-09T11:16:11.89293Z","steps":["trace[1047593270] 'read index received' (duration: 455.335403ms)","trace[1047593270] 'applied index is now lower than readState.Index' (duration: 11.347016ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T11:16:11.893108Z","caller":"traceutil/trace.go:171","msg":"trace[1034600366] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"552.821467ms","start":"2026-04-09T11:16:11.340275Z","end":"2026-04-09T11:16:11.893097Z","steps":["trace[1034600366] 'process raft request' (duration: 552.390144ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:16:11.893136Z","caller":"traceutil/trace.go:171","msg":"trace[894277465] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"560.414136ms","start":"2026-04-09T11:16:11.332706Z","end":"2026-04-09T11:16:11.89312Z","steps":["trace[894277465] 'process raft request' (duration: 559.837436ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:16:11.893217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:16:11.340254Z","time spent":"552.88072ms","remote":"127.0.0.1:60252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T11:16:11.893251Z","caller":"traceutil/trace.go:171","msg":"trace[1269175696] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"565.763397ms","start":"2026-04-09T11:16:11.32748Z","end":"2026-04-09T11:16:11.893244Z","steps":["trace[1269175696] 'process raft request' (duration: 564.975645ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:16:11.893205Z","caller":"traceutil/trace.go:171","msg":"trace[1551510028] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"555.3583ms","start":"2026-04-09T11:16:11.337814Z","end":"2026-04-09T11:16:11.893172Z","steps":["trace[1551510028] 'process raft request' (duration: 554.760389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:16:11.893292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:16:11.327467Z","time spent":"565.81134ms","remote":"127.0.0.1:60252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T11:16:11.893325Z","caller":"traceutil/trace.go:171","msg":"trace[1062684757] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"555.427804ms","start":"2026-04-09T11:16:11.337888Z","end":"2026-04-09T11:16:11.893315Z","steps":["trace[1062684757] 'process raft request' (duration: 554.747298ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:16:11.893359Z","caller":"traceutil/trace.go:171","msg":"trace[2024502239] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"565.730506ms","start":"2026-04-09T11:16:11.327623Z","end":"2026-04-09T11:16:11.893353Z","steps":["trace[2024502239] 'process raft request' (duration: 564.886771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:16:11.893415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:16:11.327618Z","time spent":"565.783838ms","remote":"127.0.0.1:60252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T11:16:11.893405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:16:11.3378Z","time spent":"555.505798ms","remote":"127.0.0.1:60252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T11:16:11.893402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:16:11.33788Z","time spent":"555.467826ms","remote":"127.0.0.1:60252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T11:16:11.893475Z","caller":"traceutil/trace.go:171","msg":"trace[940380298] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"523.714367ms","start":"2026-04-09T11:16:11.369756Z","end":"2026-04-09T11:16:11.89347Z","steps":["trace[940380298] 'process raft request' (duration: 523.063632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:16:11.893218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:16:11.332682Z","time spent":"560.49378ms","remote":"127.0.0.1:60252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T11:16:11.893509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:16:11.369733Z","time spent":"523.762909ms","remote":"127.0.0.1:60252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T11:16:11.893549Z","caller":"traceutil/trace.go:171","msg":"trace[144657896] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"551.002691ms","start":"2026-04-09T11:16:11.342534Z","end":"2026-04-09T11:16:11.893537Z","steps":["trace[144657896] 'process raft request' (duration: 550.194809ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:16:11.893571Z","caller":"traceutil/trace.go:171","msg":"trace[2000204381] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"545.336714ms","start":"2026-04-09T11:16:11.348226Z","end":"2026-04-09T11:16:11.893563Z","steps":["trace[2000204381] 'process raft request' (duration: 544.536712ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:16:11.893598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:16:11.348202Z","time spent":"545.383336ms","remote":"127.0.0.1:60252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T11:16:11.893604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:16:11.342528Z","time spent":"551.054254ms","remote":"127.0.0.1:60252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T11:16:11.8933Z","caller":"traceutil/trace.go:171","msg":"trace[538427732] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"401.983869ms","start":"2026-04-09T11:16:11.491162Z","end":"2026-04-09T11:16:11.893146Z","steps":["trace[538427732] 'process raft request' (duration: 401.728755ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:16:11.893573Z","caller":"traceutil/trace.go:171","msg":"trace[993683876] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"551.700528ms","start":"2026-04-09T11:16:11.341801Z","end":"2026-04-09T11:16:11.893501Z","steps":["trace[993683876] 'process raft request' (duration: 550.894026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:16:11.89356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.285601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T11:16:11.893824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:16:11.341787Z","time spent":"551.913129ms","remote":"127.0.0.1:60252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T11:16:11.893848Z","caller":"traceutil/trace.go:171","msg":"trace[1435393803] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:519; }","duration":"467.618378ms","start":"2026-04-09T11:16:11.426198Z","end":"2026-04-09T11:16:11.893817Z","steps":["trace[1435393803] 'agreement among raft nodes before linearized reading' (duration: 466.990445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:16:11.893836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:16:11.491136Z","time spent":"402.600841ms","remote":"127.0.0.1:60252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T11:16:11.893906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:16:11.42618Z","time spent":"467.713463ms","remote":"127.0.0.1:60212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-09T11:16:11.906039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.800803ms","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-09T11:16:11.906119Z","caller":"traceutil/trace.go:171","msg":"trace[1575277018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:520; }","duration":"211.92561ms","start":"2026-04-09T11:16:11.694173Z","end":"2026-04-09T11:16:11.906099Z","steps":["trace[1575277018] 'agreement among raft nodes before linearized reading' (duration: 211.762021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:16:11.906744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.255128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-09T11:16:11.906798Z","caller":"traceutil/trace.go:171","msg":"trace[279158091] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:520; }","duration":"236.355503ms","start":"2026-04-09T11:16:11.67043Z","end":"2026-04-09T11:16:11.906786Z","steps":["trace[279158091] 'agreement among raft nodes before linearized reading' (duration: 236.239577ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:16:11.906983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.620338ms","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-09T11:16:11.907005Z","caller":"traceutil/trace.go:171","msg":"trace[1189663299] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:520; }","duration":"322.693631ms","start":"2026-04-09T11:16:11.584304Z","end":"2026-04-09T11:16:11.906997Z","steps":["trace[1189663299] 'agreement among raft nodes before linearized reading' (duration: 322.653119ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:16:11.907024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:16:11.584276Z","time spent":"322.743254ms","remote":"127.0.0.1:60450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-09T11:16:11.906277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.849541ms","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-09T11:16:11.908804Z","caller":"traceutil/trace.go:171","msg":"trace[882498706] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:520; }","duration":"236.390935ms","start":"2026-04-09T11:16:11.672382Z","end":"2026-04-09T11:16:11.908773Z","steps":["trace[882498706] 'agreement among raft nodes before linearized reading' (duration: 233.865442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:16:18.929609Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933265052188818951,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-09T11:16:18.94038Z","caller":"traceutil/trace.go:171","msg":"trace[518149599] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"511.464295ms","start":"2026-04-09T11:16:18.428838Z","end":"2026-04-09T11:16:18.940302Z","steps":["trace[518149599] 'read index received' (duration: 508.862089ms)","trace[518149599] 'applied index is now lower than readState.Index' (duration: 2.599616ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T11:16:18.940473Z","caller":"traceutil/trace.go:171","msg":"trace[1945799468] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"598.097321ms","start":"2026-04-09T11:16:18.342341Z","end":"2026-04-09T11:16:18.940438Z","steps":["trace[1945799468] 'process raft request' (duration: 595.612472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:16:18.940849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:16:18.342311Z","time spent":"598.412866ms","remote":"127.0.0.1:60314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T11:16:18.940935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.089655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T11:16:18.940977Z","caller":"traceutil/trace.go:171","msg":"trace[1374553114] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"512.160078ms","start":"2026-04-09T11:16:18.428797Z","end":"2026-04-09T11:16:18.940957Z","steps":["trace[1374553114] 'agreement among raft nodes before linearized reading' (duration: 511.822762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:16:18.94101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:16:18.428778Z","time spent":"512.224622ms","remote":"127.0.0.1:60212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-09T11:16:18.941282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.648341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zs8rb\" ","response":"range_response_count:1 size:16957"} {"level":"info","ts":"2026-04-09T11:16:18.941446Z","caller":"traceutil/trace.go:171","msg":"trace[590391798] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zs8rb; range_end:; response_count:1; response_revision:621; }","duration":"262.845951ms","start":"2026-04-09T11:16:18.67858Z","end":"2026-04-09T11:16:18.941426Z","steps":["trace[590391798] 'agreement among raft nodes before linearized reading' (duration: 262.060723ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:16:19.266756Z","caller":"traceutil/trace.go:171","msg":"trace[1311513278] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"299.527943ms","start":"2026-04-09T11:16:18.967205Z","end":"2026-04-09T11:16:19.266733Z","steps":["trace[1311513278] 'process raft request' (duration: 299.381707ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:16:19.29907Z","caller":"traceutil/trace.go:171","msg":"trace[940453217] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"302.242455ms","start":"2026-04-09T11:16:18.996805Z","end":"2026-04-09T11:16:19.299047Z","steps":["trace[940453217] 'process raft request' (duration: 302.143791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:16:19.299263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:16:18.996781Z","time spent":"302.420054ms","remote":"127.0.0.1:60426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T11:16:20.25231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"940.097236ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-09T11:16:20.252451Z","caller":"traceutil/trace.go:171","msg":"trace[1036100122] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"948.029154ms","start":"2026-04-09T11:16:19.304401Z","end":"2026-04-09T11:16:20.25243Z","steps":["trace[1036100122] 'read index received' (duration: 7.913027ms)","trace[1036100122] 'applied index is now lower than readState.Index' (duration: 940.114497ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T11:16:20.252755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"948.332758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-04-09T11:16:20.252892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.79109ms","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-09T11:16:20.252847Z","caller":"traceutil/trace.go:171","msg":"trace[1971218929] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:623; }","duration":"948.460623ms","start":"2026-04-09T11:16:19.304366Z","end":"2026-04-09T11:16:20.252826Z","steps":["trace[1971218929] 'agreement among raft nodes before linearized reading' (duration: 948.306536ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:16:20.252989Z","caller":"traceutil/trace.go:171","msg":"trace[1518701986] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:623; }","duration":"280.884104ms","start":"2026-04-09T11:16:19.972061Z","end":"2026-04-09T11:16:20.252945Z","steps":["trace[1518701986] 'agreement among raft nodes before linearized reading' (duration: 280.679704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:16:20.253022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.59132ms","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-09T11:16:20.253242Z","caller":"traceutil/trace.go:171","msg":"trace[1821132709] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:623; }","duration":"284.807ms","start":"2026-04-09T11:16:19.968422Z","end":"2026-04-09T11:16:20.253229Z","steps":["trace[1821132709] 'agreement among raft nodes before linearized reading' (duration: 284.545338ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:16:20.253284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:16:19.30435Z","time spent":"948.663503ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-04-09T11:16:20.252935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.722486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-09T11:16:20.253699Z","caller":"traceutil/trace.go:171","msg":"trace[250619170] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:623; }","duration":"281.509123ms","start":"2026-04-09T11:16:19.97218Z","end":"2026-04-09T11:16:20.253689Z","steps":["trace[250619170] 'agreement among raft nodes before linearized reading' (duration: 280.685474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:16:20.253924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.331576ms","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-09T11:16:20.254228Z","caller":"traceutil/trace.go:171","msg":"trace[2116390416] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:623; }","duration":"293.429901ms","start":"2026-04-09T11:16:19.960546Z","end":"2026-04-09T11:16:20.253976Z","steps":["trace[2116390416] 'agreement among raft nodes before linearized reading' (duration: 292.169761ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:16:24.99193Z","caller":"traceutil/trace.go:171","msg":"trace[1906956032] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"119.416413ms","start":"2026-04-09T11:16:24.872489Z","end":"2026-04-09T11:16:24.991906Z","steps":["trace[1906956032] 'process raft request' (duration: 119.232195ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:16:32.494025Z","caller":"traceutil/trace.go:171","msg":"trace[1032246712] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:788; }","duration":"124.810394ms","start":"2026-04-09T11:16:32.369189Z","end":"2026-04-09T11:16:32.493999Z","steps":["trace[1032246712] 'read index received' (duration: 124.560524ms)","trace[1032246712] 'applied index is now lower than readState.Index' (duration: 248.91µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T11:16:32.494058Z","caller":"traceutil/trace.go:171","msg":"trace[776843305] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"142.889852ms","start":"2026-04-09T11:16:32.35113Z","end":"2026-04-09T11:16:32.49402Z","steps":["trace[776843305] 'process raft request' (duration: 142.703044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:16:32.494354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.170899ms","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-09T11:16:32.494463Z","caller":"traceutil/trace.go:171","msg":"trace[719543648] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:765; }","duration":"125.347586ms","start":"2026-04-09T11:16:32.369093Z","end":"2026-04-09T11:16:32.49444Z","steps":["trace[719543648] 'agreement among raft nodes before linearized reading' (duration: 125.023093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:16:32.494469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.836643ms","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-09T11:16:32.494564Z","caller":"traceutil/trace.go:171","msg":"trace[737413901] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:765; }","duration":"121.950228ms","start":"2026-04-09T11:16:32.372554Z","end":"2026-04-09T11:16:32.494505Z","steps":["trace[737413901] 'agreement among raft nodes before linearized reading' (duration: 121.772411ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:16:32.494968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.841053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-09T11:16:32.495006Z","caller":"traceutil/trace.go:171","msg":"trace[910874270] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:765; }","duration":"122.890515ms","start":"2026-04-09T11:16:32.372094Z","end":"2026-04-09T11:16:32.494984Z","steps":["trace[910874270] 'agreement among raft nodes before linearized reading' (duration: 122.167016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:16:35.004496Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933265052188819325,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-09T11:16:35.504881Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933265052188819325,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-09T11:16:36.005547Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933265052188819325,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-09T11:16:36.506078Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933265052188819325,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-09T11:16:36.670122Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.168670334s","expected-duration":"1s"} {"level":"info","ts":"2026-04-09T11:16:36.670448Z","caller":"traceutil/trace.go:171","msg":"trace[1515256848] linearizableReadLoop","detail":"{readStateIndex:805; appliedIndex:804; }","duration":"2.165988806s","start":"2026-04-09T11:16:34.504436Z","end":"2026-04-09T11:16:36.670425Z","steps":["trace[1515256848] 'read index received' (duration: 2.16581805s)","trace[1515256848] 'applied index is now lower than readState.Index' (duration: 169.336µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T11:16:36.670562Z","caller":"traceutil/trace.go:171","msg":"trace[419358296] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"2.169228416s","start":"2026-04-09T11:16:34.501324Z","end":"2026-04-09T11:16:36.670552Z","steps":["trace[419358296] 'process raft request' (duration: 2.168970576s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:16:36.67069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:16:34.501303Z","time spent":"2.169297849s","remote":"127.0.0.1:60450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T11:16:36.670763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.163783129s","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-09T11:16:36.67083Z","caller":"traceutil/trace.go:171","msg":"trace[2134490563] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:780; }","duration":"2.163888012s","start":"2026-04-09T11:16:34.506924Z","end":"2026-04-09T11:16:36.670812Z","steps":["trace[2134490563] 'agreement among raft nodes before linearized reading' (duration: 2.163725596s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:16:36.670861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:16:34.506902Z","time spent":"2.163953305s","remote":"127.0.0.1:60450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-09T11:16:36.670978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.166551079s","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-09T11:16:36.671001Z","caller":"traceutil/trace.go:171","msg":"trace[841621401] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:780; }","duration":"2.166614002s","start":"2026-04-09T11:16:34.504381Z","end":"2026-04-09T11:16:36.670995Z","steps":["trace[841621401] 'agreement among raft nodes before linearized reading' (duration: 2.166556689s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:16:36.671021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:16:34.504371Z","time spent":"2.166643472s","remote":"127.0.0.1:60450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-09T11:16:36.671032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.242379945s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T11:16:36.67111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.903401974s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-4mkps\" ","response":"range_response_count:1 size:3963"} {"level":"info","ts":"2026-04-09T11:16:36.671115Z","caller":"traceutil/trace.go:171","msg":"trace[902862186] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:780; }","duration":"1.24249172s","start":"2026-04-09T11:16:35.428592Z","end":"2026-04-09T11:16:36.671084Z","steps":["trace[902862186] 'agreement among raft nodes before linearized reading' (duration: 1.242396596s)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:16:36.671134Z","caller":"traceutil/trace.go:171","msg":"trace[1411055831] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-4mkps; range_end:; response_count:1; response_revision:780; }","duration":"1.903457406s","start":"2026-04-09T11:16:34.767669Z","end":"2026-04-09T11:16:36.671126Z","steps":["trace[1411055831] 'agreement among raft nodes before linearized reading' (duration: 1.903396153s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:16:36.671158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:16:34.767651Z","time spent":"1.903502268s","remote":"127.0.0.1:60374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3986,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-4mkps\" "} {"level":"warn","ts":"2026-04-09T11:16:36.671283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.042792116s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-09T11:16:36.671327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.163756727s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-09T11:16:36.671329Z","caller":"traceutil/trace.go:171","msg":"trace[884775160] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:780; }","duration":"1.042864829s","start":"2026-04-09T11:16:35.628453Z","end":"2026-04-09T11:16:36.671318Z","steps":["trace[884775160] 'agreement among raft nodes before linearized reading' (duration: 1.042722904s)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:16:36.671358Z","caller":"traceutil/trace.go:171","msg":"trace[1297824250] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:780; }","duration":"2.163803768s","start":"2026-04-09T11:16:34.507547Z","end":"2026-04-09T11:16:36.67135Z","steps":["trace[1297824250] 'agreement among raft nodes before linearized reading' (duration: 2.163720025s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:16:36.671158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:16:35.428566Z","time spent":"1.242582874s","remote":"127.0.0.1:60212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-09T11:16:36.671372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:16:35.628436Z","time spent":"1.042926362s","remote":"127.0.0.1:60450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-09T11:16:36.671384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T11:16:34.507536Z","time spent":"2.16384202s","remote":"127.0.0.1:60450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-09T11:16:36.842135Z","caller":"traceutil/trace.go:171","msg":"trace[2039298400] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"166.941518ms","start":"2026-04-09T11:16:36.675158Z","end":"2026-04-09T11:16:36.842099Z","steps":["trace[2039298400] 'process raft request' (duration: 73.457233ms)","trace[2039298400] 'compare' (duration: 93.170433ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T11:16:36.842931Z","caller":"traceutil/trace.go:171","msg":"trace[793927371] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"167.094674ms","start":"2026-04-09T11:16:36.67582Z","end":"2026-04-09T11:16:36.842915Z","steps":["trace[793927371] 'process raft request' (duration: 166.799002ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:16:36.84308Z","caller":"traceutil/trace.go:171","msg":"trace[696221340] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"167.038772ms","start":"2026-04-09T11:16:36.676029Z","end":"2026-04-09T11:16:36.843068Z","steps":["trace[696221340] 'process raft request' (duration: 166.776081ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:16:36.843051Z","caller":"traceutil/trace.go:171","msg":"trace[485609299] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"164.229713ms","start":"2026-04-09T11:16:36.678809Z","end":"2026-04-09T11:16:36.843038Z","steps":["trace[485609299] 'process raft request' (duration: 164.082797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T11:16:36.84323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.88212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T11:16:36.843282Z","caller":"traceutil/trace.go:171","msg":"trace[1951869584] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:785; }","duration":"162.945333ms","start":"2026-04-09T11:16:36.680328Z","end":"2026-04-09T11:16:36.843273Z","steps":["trace[1951869584] 'agreement among raft nodes before linearized reading' (duration: 162.86313ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:16:36.842977Z","caller":"traceutil/trace.go:171","msg":"trace[1766484801] linearizableReadLoop","detail":"{readStateIndex:810; appliedIndex:805; }","duration":"162.602159ms","start":"2026-04-09T11:16:36.680342Z","end":"2026-04-09T11:16:36.842945Z","steps":["trace[1766484801] 'read index received' (duration: 68.282902ms)","trace[1766484801] 'applied index is now lower than readState.Index' (duration: 94.318397ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T11:16:36.844341Z","caller":"traceutil/trace.go:171","msg":"trace[402797751] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"166.795863ms","start":"2026-04-09T11:16:36.676223Z","end":"2026-04-09T11:16:36.843019Z","steps":["trace[402797751] 'process raft request' (duration: 166.631716ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:16:51.099325Z","caller":"traceutil/trace.go:171","msg":"trace[2092872002] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"164.603977ms","start":"2026-04-09T11:16:50.934694Z","end":"2026-04-09T11:16:51.099298Z","steps":["trace[2092872002] 'process raft request' (duration: 133.895331ms)","trace[2092872002] 'compare' (duration: 30.486838ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T11:16:51.099615Z","caller":"traceutil/trace.go:171","msg":"trace[1884372599] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"164.751051ms","start":"2026-04-09T11:16:50.93484Z","end":"2026-04-09T11:16:51.099592Z","steps":["trace[1884372599] 'process raft request' (duration: 164.676369ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:16:51.099929Z","caller":"traceutil/trace.go:171","msg":"trace[1815957125] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"165.155525ms","start":"2026-04-09T11:16:50.934722Z","end":"2026-04-09T11:16:51.099877Z","steps":["trace[1815957125] 'process raft request' (duration: 164.534624ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T11:18:26.418368Z","caller":"traceutil/trace.go:171","msg":"trace[1511863362] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"183.318829ms","start":"2026-04-09T11:18:26.235023Z","end":"2026-04-09T11:18:26.418342Z","steps":["trace[1511863362] 'process raft request' (duration: 183.142686ms)"],"step_count":1}