{"level":"warn","ts":"2026-04-18T22:12:37.774757Z","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-18T22:12:37.774943Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.209: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.209:2380","--initial-cluster=instance=https://199.204.45.209:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.209:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.209: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-18T22:12:37.775059Z","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-18T22:12:37.775079Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.209:2380"]} {"level":"info","ts":"2026-04-18T22:12:37.775107Z","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-18T22:12:37.776352Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.209:2379"]} {"level":"info","ts":"2026-04-18T22:12:37.776452Z","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.209:2380"],"listen-peer-urls":["https://199.204.45.209:2380"],"advertise-client-urls":["https://199.204.45.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.209: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-18T22:12:37.781529Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.79946ms"} {"level":"info","ts":"2026-04-18T22:12:37.788332Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"adfb641115bf6cbd","cluster-id":"3da6b677d4b80c6"} {"level":"info","ts":"2026-04-18T22:12:37.788427Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd switched to configuration voters=()"} {"level":"info","ts":"2026-04-18T22:12:37.788457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became follower at term 0"} {"level":"info","ts":"2026-04-18T22:12:37.788474Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft adfb641115bf6cbd [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-18T22:12:37.788488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became follower at term 1"} {"level":"info","ts":"2026-04-18T22:12:37.78854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd switched to configuration voters=(12536724012257995965)"} {"level":"warn","ts":"2026-04-18T22:12:37.792958Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-18T22:12:37.794272Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-18T22:12:37.79542Z","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-18T22:12:37.797852Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"adfb641115bf6cbd","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-18T22:12:37.798086Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"adfb641115bf6cbd","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-18T22:12:37.798336Z","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-18T22:12:37.798399Z","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-18T22:12:37.798591Z","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-18T22:12:37.801092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd switched to configuration voters=(12536724012257995965)"} {"level":"info","ts":"2026-04-18T22:12:37.802304Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3da6b677d4b80c6","local-member-id":"adfb641115bf6cbd","added-peer-id":"adfb641115bf6cbd","added-peer-peer-urls":["https://199.204.45.209:2380"]} {"level":"info","ts":"2026-04-18T22:12:37.802541Z","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-18T22:12:37.80256Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.209:2380"} {"level":"info","ts":"2026-04-18T22:12:37.802624Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.209:2380"} {"level":"info","ts":"2026-04-18T22:12:37.802906Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"adfb641115bf6cbd","initial-advertise-peer-urls":["https://199.204.45.209:2380"],"listen-peer-urls":["https://199.204.45.209:2380"],"advertise-client-urls":["https://199.204.45.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-18T22:12:37.803041Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-18T22:12:38.588838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd is starting a new election at term 1"} {"level":"info","ts":"2026-04-18T22:12:38.588902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became pre-candidate at term 1"} {"level":"info","ts":"2026-04-18T22:12:38.588943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd received MsgPreVoteResp from adfb641115bf6cbd at term 1"} {"level":"info","ts":"2026-04-18T22:12:38.588963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became candidate at term 2"} {"level":"info","ts":"2026-04-18T22:12:38.588975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd received MsgVoteResp from adfb641115bf6cbd at term 2"} {"level":"info","ts":"2026-04-18T22:12:38.588987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became leader at term 2"} {"level":"info","ts":"2026-04-18T22:12:38.588997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: adfb641115bf6cbd elected leader adfb641115bf6cbd at term 2"} {"level":"info","ts":"2026-04-18T22:12:38.590048Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"adfb641115bf6cbd","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.209:2379]}","request-path":"/0/members/adfb641115bf6cbd/attributes","cluster-id":"3da6b677d4b80c6","publish-timeout":"7s"} {"level":"info","ts":"2026-04-18T22:12:38.590058Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-18T22:12:38.590099Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-18T22:12:38.590108Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T22:12:38.591432Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-18T22:12:38.591454Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-18T22:12:38.592741Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.209:2379"} {"level":"info","ts":"2026-04-18T22:12:38.59297Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3da6b677d4b80c6","local-member-id":"adfb641115bf6cbd","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T22:12:38.5931Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T22:12:38.593146Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T22:12:38.594241Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-18T22:12:41.475662Z","caller":"traceutil/trace.go:171","msg":"trace[576305955] transaction","detail":"{read_only:false; response_revision:92; number_of_response:1; }","duration":"104.836283ms","start":"2026-04-18T22:12:41.370775Z","end":"2026-04-18T22:12:41.475611Z","steps":["trace[576305955] 'process raft request' (duration: 13.80707ms)","trace[576305955] 'compare' (duration: 90.589788ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:12:44.745079Z","caller":"traceutil/trace.go:171","msg":"trace[253812272] transaction","detail":"{read_only:false; response_revision:271; number_of_response:1; }","duration":"140.57243ms","start":"2026-04-18T22:12:44.604462Z","end":"2026-04-18T22:12:44.745034Z","steps":["trace[253812272] 'process raft request' (duration: 140.338028ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:44.74759Z","caller":"traceutil/trace.go:171","msg":"trace[2083050110] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"138.869803ms","start":"2026-04-18T22:12:44.60869Z","end":"2026-04-18T22:12:44.74756Z","steps":["trace[2083050110] 'process raft request' (duration: 138.600251ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:45.803547Z","caller":"traceutil/trace.go:171","msg":"trace[716243398] transaction","detail":"{read_only:false; number_of_response:0; response_revision:309; }","duration":"243.483912ms","start":"2026-04-18T22:12:45.560027Z","end":"2026-04-18T22:12:45.803511Z","steps":["trace[716243398] 'process raft request' (duration: 218.897879ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:45.803629Z","caller":"traceutil/trace.go:171","msg":"trace[1348195975] transaction","detail":"{read_only:false; number_of_response:0; response_revision:309; }","duration":"213.98779ms","start":"2026-04-18T22:12:45.589618Z","end":"2026-04-18T22:12:45.803606Z","steps":["trace[1348195975] 'process raft request' (duration: 213.802879ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:45.803701Z","caller":"traceutil/trace.go:171","msg":"trace[2121497643] transaction","detail":"{read_only:false; number_of_response:0; response_revision:309; }","duration":"214.016681ms","start":"2026-04-18T22:12:45.58963Z","end":"2026-04-18T22:12:45.803647Z","steps":["trace[2121497643] 'process raft request' (duration: 213.893909ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:45.803748Z","caller":"traceutil/trace.go:171","msg":"trace[3348615] transaction","detail":"{read_only:false; number_of_response:0; response_revision:309; }","duration":"213.544526ms","start":"2026-04-18T22:12:45.590146Z","end":"2026-04-18T22:12:45.803691Z","steps":["trace[3348615] 'process raft request' (duration: 213.402095ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:45.803778Z","caller":"traceutil/trace.go:171","msg":"trace[628217706] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"206.985789ms","start":"2026-04-18T22:12:45.596768Z","end":"2026-04-18T22:12:45.803754Z","steps":["trace[628217706] 'process raft request' (duration: 206.930059ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:45.803836Z","caller":"traceutil/trace.go:171","msg":"trace[415715415] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"212.637996ms","start":"2026-04-18T22:12:45.59119Z","end":"2026-04-18T22:12:45.803828Z","steps":["trace[415715415] 'process raft request' (duration: 212.440354ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:45.803893Z","caller":"traceutil/trace.go:171","msg":"trace[609357525] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"213.459795ms","start":"2026-04-18T22:12:45.590428Z","end":"2026-04-18T22:12:45.803888Z","steps":["trace[609357525] 'process raft request' (duration: 213.138862ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:45.803967Z","caller":"traceutil/trace.go:171","msg":"trace[293310149] linearizableReadLoop","detail":"{readStateIndex:316; appliedIndex:314; }","duration":"243.838305ms","start":"2026-04-18T22:12:45.560118Z","end":"2026-04-18T22:12:45.803956Z","steps":["trace[293310149] 'read index received' (duration: 33.823115ms)","trace[293310149] 'applied index is now lower than readState.Index' (duration: 210.01417ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:12:45.804098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.936617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-18T22:12:45.804142Z","caller":"traceutil/trace.go:171","msg":"trace[84516249] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:312; }","duration":"244.069689ms","start":"2026-04-18T22:12:45.560063Z","end":"2026-04-18T22:12:45.804133Z","steps":["trace[84516249] 'agreement among raft nodes before linearized reading' (duration: 243.920046ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:46.079151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.188244ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:12:46.07923Z","caller":"traceutil/trace.go:171","msg":"trace[1077600932] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"238.014664ms","start":"2026-04-18T22:12:45.841202Z","end":"2026-04-18T22:12:46.079217Z","steps":["trace[1077600932] 'process raft request' (duration: 91.398825ms)","trace[1077600932] 'compare' (duration: 146.078923ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:12:46.134351Z","caller":"traceutil/trace.go:171","msg":"trace[1985210809] linearizableReadLoop","detail":"{readStateIndex:326; appliedIndex:325; }","duration":"158.572913ms","start":"2026-04-18T22:12:45.975763Z","end":"2026-04-18T22:12:46.134336Z","steps":["trace[1985210809] 'read index received' (duration: 158.547393ms)","trace[1985210809] 'applied index is now lower than readState.Index' (duration: 24.66µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:12:46.134422Z","caller":"traceutil/trace.go:171","msg":"trace[985620226] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"292.821943ms","start":"2026-04-18T22:12:45.841566Z","end":"2026-04-18T22:12:46.134388Z","steps":["trace[985620226] 'process raft request' (duration: 292.694472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:46.134563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.769156ms","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-18T22:12:46.134707Z","caller":"traceutil/trace.go:171","msg":"trace[2053954180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:316; }","duration":"158.954997ms","start":"2026-04-18T22:12:45.975724Z","end":"2026-04-18T22:12:46.134679Z","steps":["trace[2053954180] 'agreement among raft nodes before linearized reading' (duration: 158.699784ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:46.268808Z","caller":"traceutil/trace.go:171","msg":"trace[563130210] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"127.716978ms","start":"2026-04-18T22:12:46.14107Z","end":"2026-04-18T22:12:46.268787Z","steps":["trace[563130210] 'process raft request' (duration: 75.992659ms)","trace[563130210] 'compare' (duration: 51.613038ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:12:46.794328Z","caller":"traceutil/trace.go:171","msg":"trace[1104803750] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"155.955567ms","start":"2026-04-18T22:12:46.638348Z","end":"2026-04-18T22:12:46.794304Z","steps":["trace[1104803750] 'process raft request' (duration: 103.573051ms)","trace[1104803750] 'compare' (duration: 52.256745ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:12:46.794568Z","caller":"traceutil/trace.go:171","msg":"trace[8180708] linearizableReadLoop","detail":"{readStateIndex:336; appliedIndex:335; }","duration":"136.649016ms","start":"2026-04-18T22:12:46.657904Z","end":"2026-04-18T22:12:46.794553Z","steps":["trace[8180708] 'read index received' (duration: 83.986667ms)","trace[8180708] 'applied index is now lower than readState.Index' (duration: 52.661219ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:12:46.794721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.797998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-18T22:12:46.794763Z","caller":"traceutil/trace.go:171","msg":"trace[1482704701] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:325; }","duration":"136.871199ms","start":"2026-04-18T22:12:46.65788Z","end":"2026-04-18T22:12:46.794751Z","steps":["trace[1482704701] 'agreement among raft nodes before linearized reading' (duration: 136.789568ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:13:03.04318Z","caller":"traceutil/trace.go:171","msg":"trace[616232849] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"144.196654ms","start":"2026-04-18T22:13:02.89896Z","end":"2026-04-18T22:13:03.043157Z","steps":["trace[616232849] 'process raft request' (duration: 51.624765ms)","trace[616232849] 'compare' (duration: 92.445758ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:13:06.390885Z","caller":"traceutil/trace.go:171","msg":"trace[183567967] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"145.756301ms","start":"2026-04-18T22:13:06.245103Z","end":"2026-04-18T22:13:06.39086Z","steps":["trace[183567967] 'process raft request' (duration: 145.31287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:13:09.644386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.57452ms","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-18T22:13:09.64465Z","caller":"traceutil/trace.go:171","msg":"trace[1265976690] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:514; }","duration":"582.893243ms","start":"2026-04-18T22:13:09.061735Z","end":"2026-04-18T22:13:09.644628Z","steps":["trace[1265976690] 'range keys from in-memory index tree' (duration: 582.394432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:13:09.644697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:13:09.061714Z","time spent":"582.969737ms","remote":"127.0.0.1:50256","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":"warn","ts":"2026-04-18T22:13:09.644676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.769654ms","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-18T22:13:09.64675Z","caller":"traceutil/trace.go:171","msg":"trace[1170443503] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:514; }","duration":"485.892039ms","start":"2026-04-18T22:13:09.160834Z","end":"2026-04-18T22:13:09.646726Z","steps":["trace[1170443503] 'range keys from in-memory index tree' (duration: 483.598986ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:13:09.646874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:13:09.160808Z","time spent":"486.043905ms","remote":"127.0.0.1:50256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-18T22:13:09.695518Z","caller":"traceutil/trace.go:171","msg":"trace[1515077759] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"525.645367ms","start":"2026-04-18T22:13:09.169848Z","end":"2026-04-18T22:13:09.695494Z","steps":["trace[1515077759] 'process raft request' (duration: 525.461679ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:13:09.695667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:13:09.169825Z","time spent":"525.774593ms","remote":"127.0.0.1:50032","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-18T22:13:09.962644Z","caller":"traceutil/trace.go:171","msg":"trace[1880510354] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"788.642458ms","start":"2026-04-18T22:13:09.173968Z","end":"2026-04-18T22:13:09.962611Z","steps":["trace[1880510354] 'process raft request' (duration: 788.338555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:13:09.962887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:13:09.173931Z","time spent":"788.875779ms","remote":"127.0.0.1:50032","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-18T22:13:09.962945Z","caller":"traceutil/trace.go:171","msg":"trace[247091403] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"768.115735ms","start":"2026-04-18T22:13:09.194799Z","end":"2026-04-18T22:13:09.962915Z","steps":["trace[247091403] 'process raft request' (duration: 767.822421ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:13:09.962942Z","caller":"traceutil/trace.go:171","msg":"trace[492311955] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"766.19675ms","start":"2026-04-18T22:13:09.1967Z","end":"2026-04-18T22:13:09.962896Z","steps":["trace[492311955] 'process raft request' (duration: 765.953849ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:13:09.963011Z","caller":"traceutil/trace.go:171","msg":"trace[1127013441] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"776.299189ms","start":"2026-04-18T22:13:09.186645Z","end":"2026-04-18T22:13:09.962945Z","steps":["trace[1127013441] 'process raft request' (duration: 775.937583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:13:09.963092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:13:09.19478Z","time spent":"768.240801ms","remote":"127.0.0.1:50032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-18T22:13:09.963098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:13:09.196693Z","time spent":"766.335446ms","remote":"127.0.0.1:50032","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-18T22:13:09.96318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:13:09.186611Z","time spent":"776.504599ms","remote":"127.0.0.1:50032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-18T22:13:09.963225Z","caller":"traceutil/trace.go:171","msg":"trace[232210688] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"765.391424ms","start":"2026-04-18T22:13:09.197825Z","end":"2026-04-18T22:13:09.963216Z","steps":["trace[232210688] 'process raft request' (duration: 764.869522ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:13:09.963239Z","caller":"traceutil/trace.go:171","msg":"trace[1983862104] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"610.535004ms","start":"2026-04-18T22:13:09.352689Z","end":"2026-04-18T22:13:09.963224Z","steps":["trace[1983862104] 'process raft request' (duration: 610.166759ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:13:09.963319Z","caller":"traceutil/trace.go:171","msg":"trace[1213143005] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"777.136206ms","start":"2026-04-18T22:13:09.186172Z","end":"2026-04-18T22:13:09.963308Z","steps":["trace[1213143005] 'process raft request' (duration: 776.361502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:13:09.963361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:13:09.197818Z","time spent":"765.440556ms","remote":"127.0.0.1:50032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-18T22:13:09.96341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:13:09.186142Z","time spent":"777.242562ms","remote":"127.0.0.1:50032","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-18T22:13:09.963457Z","caller":"traceutil/trace.go:171","msg":"trace[1172963637] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"312.812448ms","start":"2026-04-18T22:13:09.650631Z","end":"2026-04-18T22:13:09.963444Z","steps":["trace[1172963637] 'process raft request' (duration: 312.337646ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:13:09.963514Z","caller":"traceutil/trace.go:171","msg":"trace[1101937488] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"711.37652ms","start":"2026-04-18T22:13:09.252125Z","end":"2026-04-18T22:13:09.963501Z","steps":["trace[1101937488] 'process raft request' (duration: 710.667729ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:13:09.963558Z","caller":"traceutil/trace.go:171","msg":"trace[196673364] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"741.968682ms","start":"2026-04-18T22:13:09.221574Z","end":"2026-04-18T22:13:09.963543Z","steps":["trace[196673364] 'process raft request' (duration: 741.165747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:13:09.96358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:13:09.252088Z","time spent":"711.465675ms","remote":"127.0.0.1:50032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-18T22:13:09.963627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:13:09.221535Z","time spent":"742.063667ms","remote":"127.0.0.1:50032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-18T22:13:09.963676Z","caller":"traceutil/trace.go:171","msg":"trace[1264003362] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"312.285145ms","start":"2026-04-18T22:13:09.651381Z","end":"2026-04-18T22:13:09.963667Z","steps":["trace[1264003362] 'process raft request' (duration: 311.634545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:13:09.963712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:13:09.650604Z","time spent":"312.887871ms","remote":"127.0.0.1:50256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:13:09.96266Z","caller":"traceutil/trace.go:171","msg":"trace[1462237403] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"781.771502ms","start":"2026-04-18T22:13:09.180857Z","end":"2026-04-18T22:13:09.962628Z","steps":["trace[1462237403] 'process raft request' (duration: 781.638816ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:13:09.963734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:13:09.651361Z","time spent":"312.333677ms","remote":"127.0.0.1:50256","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-18T22:13:09.9638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:13:09.180848Z","time spent":"782.911684ms","remote":"127.0.0.1:50032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-18T22:13:09.963188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.520149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:13:09.963909Z","caller":"traceutil/trace.go:171","msg":"trace[1801321008] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:528; }","duration":"630.297553ms","start":"2026-04-18T22:13:09.3336Z","end":"2026-04-18T22:13:09.963898Z","steps":["trace[1801321008] 'agreement among raft nodes before linearized reading' (duration: 629.525308ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:13:09.963073Z","caller":"traceutil/trace.go:171","msg":"trace[1930449651] linearizableReadLoop","detail":"{readStateIndex:542; appliedIndex:531; }","duration":"629.395973ms","start":"2026-04-18T22:13:09.333652Z","end":"2026-04-18T22:13:09.963048Z","steps":["trace[1930449651] 'read index received' (duration: 361.686732ms)","trace[1930449651] 'applied index is now lower than readState.Index' (duration: 267.708461ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:13:09.962726Z","caller":"traceutil/trace.go:171","msg":"trace[2040344049] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"788.699592ms","start":"2026-04-18T22:13:09.174018Z","end":"2026-04-18T22:13:09.962717Z","steps":["trace[2040344049] 'process raft request' (duration: 788.433809ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:13:09.963949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:13:09.333567Z","time spent":"630.372116ms","remote":"127.0.0.1:49956","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-18T22:13:09.963988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:13:09.173987Z","time spent":"789.972427ms","remote":"127.0.0.1:50032","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-18T22:13:09.963313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:13:09.352659Z","time spent":"610.610197ms","remote":"127.0.0.1:50032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-18T22:13:10.102908Z","caller":"traceutil/trace.go:171","msg":"trace[105658490] linearizableReadLoop","detail":"{readStateIndex:548; appliedIndex:545; }","duration":"137.665245ms","start":"2026-04-18T22:13:09.965211Z","end":"2026-04-18T22:13:10.102876Z","steps":["trace[105658490] 'read index received' (duration: 137.358451ms)","trace[105658490] 'applied index is now lower than readState.Index' (duration: 305.634µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:13:10.10302Z","caller":"traceutil/trace.go:171","msg":"trace[342468622] transaction","detail":"{read_only:false; number_of_response:1; response_revision:530; }","duration":"399.574397ms","start":"2026-04-18T22:13:09.703407Z","end":"2026-04-18T22:13:10.102982Z","steps":["trace[342468622] 'process raft request' (duration: 399.418431ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:13:10.103074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.829102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:13:10.10313Z","caller":"traceutil/trace.go:171","msg":"trace[2134085504] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:530; }","duration":"137.970968ms","start":"2026-04-18T22:13:09.965144Z","end":"2026-04-18T22:13:10.103115Z","steps":["trace[2134085504] 'agreement among raft nodes before linearized reading' (duration: 137.861323ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:13:10.1032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:13:09.703385Z","time spent":"399.717363ms","remote":"127.0.0.1:50032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7366,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:13:10.10323Z","caller":"traceutil/trace.go:171","msg":"trace[986875479] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"401.254182ms","start":"2026-04-18T22:13:09.701637Z","end":"2026-04-18T22:13:10.102891Z","steps":["trace[986875479] 'process raft request' (duration: 400.916647ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:13:10.103388Z","caller":"traceutil/trace.go:171","msg":"trace[608761610] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"400.898166ms","start":"2026-04-18T22:13:09.702419Z","end":"2026-04-18T22:13:10.103317Z","steps":["trace[608761610] 'process raft request' (duration: 400.327681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:13:10.10355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:13:09.702411Z","time spent":"401.052053ms","remote":"127.0.0.1:50032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:13:10.103573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:13:09.701616Z","time spent":"401.793336ms","remote":"127.0.0.1:50502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-18T22:13:10.13717Z","caller":"traceutil/trace.go:171","msg":"trace[1335067316] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"152.772883ms","start":"2026-04-18T22:13:09.979335Z","end":"2026-04-18T22:13:10.132108Z","steps":["trace[1335067316] 'process raft request' (duration: 130.551675ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:13:10.137273Z","caller":"traceutil/trace.go:171","msg":"trace[1171040934] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"150.800155ms","start":"2026-04-18T22:13:09.981322Z","end":"2026-04-18T22:13:10.132122Z","steps":["trace[1171040934] 'process raft request' (duration: 128.710142ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:13:10.137459Z","caller":"traceutil/trace.go:171","msg":"trace[893006477] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"152.570784ms","start":"2026-04-18T22:13:09.979547Z","end":"2026-04-18T22:13:10.132118Z","steps":["trace[893006477] 'process raft request' (duration: 130.465721ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:13:10.137492Z","caller":"traceutil/trace.go:171","msg":"trace[1013124515] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"148.4445ms","start":"2026-04-18T22:13:09.983979Z","end":"2026-04-18T22:13:10.132424Z","steps":["trace[1013124515] 'process raft request' (duration: 126.17974ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:13:10.137162Z","caller":"traceutil/trace.go:171","msg":"trace[637225551] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"107.95626ms","start":"2026-04-18T22:13:10.024016Z","end":"2026-04-18T22:13:10.131973Z","steps":["trace[637225551] 'process raft request' (duration: 100.896489ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:13:10.137621Z","caller":"traceutil/trace.go:171","msg":"trace[40316042] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"142.014194ms","start":"2026-04-18T22:13:09.990432Z","end":"2026-04-18T22:13:10.132446Z","steps":["trace[40316042] 'process raft request' (duration: 131.990163ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:13:10.137156Z","caller":"traceutil/trace.go:171","msg":"trace[439973084] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"149.052356ms","start":"2026-04-18T22:13:09.982992Z","end":"2026-04-18T22:13:10.132044Z","steps":["trace[439973084] 'process raft request' (duration: 127.069939ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:13:10.137455Z","caller":"traceutil/trace.go:171","msg":"trace[284247824] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"147.739178ms","start":"2026-04-18T22:13:09.984681Z","end":"2026-04-18T22:13:10.13242Z","steps":["trace[284247824] 'process raft request' (duration: 125.510589ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:13:10.137749Z","caller":"traceutil/trace.go:171","msg":"trace[111928153] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"145.75915ms","start":"2026-04-18T22:13:09.986675Z","end":"2026-04-18T22:13:10.132434Z","steps":["trace[111928153] 'process raft request' (duration: 123.633936ms)","trace[111928153] 'compare' (duration: 11.3484ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:13:11.39616Z","caller":"traceutil/trace.go:171","msg":"trace[1377131381] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"141.917314ms","start":"2026-04-18T22:13:11.254185Z","end":"2026-04-18T22:13:11.396102Z","steps":["trace[1377131381] 'process raft request' (duration: 141.546378ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:13:11.396301Z","caller":"traceutil/trace.go:171","msg":"trace[1188237054] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:611; }","duration":"135.639711ms","start":"2026-04-18T22:13:11.260614Z","end":"2026-04-18T22:13:11.396254Z","steps":["trace[1188237054] 'read index received' (duration: 135.61463ms)","trace[1188237054] 'applied index is now lower than readState.Index' (duration: 21.36µs)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:13:11.396592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.938274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoypatchpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:20866"} {"level":"info","ts":"2026-04-18T22:13:11.396701Z","caller":"traceutil/trace.go:171","msg":"trace[1937645126] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoypatchpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:593; }","duration":"136.07514ms","start":"2026-04-18T22:13:11.2606Z","end":"2026-04-18T22:13:11.396675Z","steps":["trace[1937645126] 'agreement among raft nodes before linearized reading' (duration: 135.752656ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:13:16.378498Z","caller":"traceutil/trace.go:171","msg":"trace[1055396847] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"384.249958ms","start":"2026-04-18T22:13:15.994223Z","end":"2026-04-18T22:13:16.378473Z","steps":["trace[1055396847] 'read index received' (duration: 383.478565ms)","trace[1055396847] 'applied index is now lower than readState.Index' (duration: 770.332µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:13:16.378634Z","caller":"traceutil/trace.go:171","msg":"trace[620357734] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"550.357959ms","start":"2026-04-18T22:13:15.828266Z","end":"2026-04-18T22:13:16.378624Z","steps":["trace[620357734] 'process raft request' (duration: 549.684841ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:13:16.37881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:13:15.828241Z","time spent":"550.420111ms","remote":"127.0.0.1:50074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036277,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:13:16.378998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.761358ms","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-18T22:13:16.379067Z","caller":"traceutil/trace.go:171","msg":"trace[934202889] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:622; }","duration":"384.862832ms","start":"2026-04-18T22:13:15.994181Z","end":"2026-04-18T22:13:16.379044Z","steps":["trace[934202889] 'agreement among raft nodes before linearized reading' (duration: 384.660064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:13:16.379114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:13:15.994163Z","time spent":"384.938915ms","remote":"127.0.0.1:50256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-18T22:13:16.379126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.432862ms","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-18T22:13:16.37923Z","caller":"traceutil/trace.go:171","msg":"trace[481766323] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:622; }","duration":"359.601871ms","start":"2026-04-18T22:13:16.019603Z","end":"2026-04-18T22:13:16.379205Z","steps":["trace[481766323] 'agreement among raft nodes before linearized reading' (duration: 359.243455ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:13:16.379287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:13:16.019587Z","time spent":"359.689543ms","remote":"127.0.0.1:50256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-18T22:13:16.585374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.158869ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:13:16.585522Z","caller":"traceutil/trace.go:171","msg":"trace[852003924] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"200.890528ms","start":"2026-04-18T22:13:16.384612Z","end":"2026-04-18T22:13:16.585503Z","steps":["trace[852003924] 'process raft request' (duration: 25.494749ms)","trace[852003924] 'compare' (duration: 174.947731ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:13:16.763671Z","caller":"traceutil/trace.go:171","msg":"trace[236714455] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"316.777477ms","start":"2026-04-18T22:13:16.446862Z","end":"2026-04-18T22:13:16.763639Z","steps":["trace[236714455] 'read index received' (duration: 297.807616ms)","trace[236714455] 'applied index is now lower than readState.Index' (duration: 18.968801ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:13:16.763843Z","caller":"traceutil/trace.go:171","msg":"trace[1621950735] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"321.940269ms","start":"2026-04-18T22:13:16.441824Z","end":"2026-04-18T22:13:16.763764Z","steps":["trace[1621950735] 'process raft request' (duration: 302.940087ms)","trace[1621950735] 'compare' (duration: 18.363566ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:13:16.763956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.800769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qq5mh\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-18T22:13:16.76401Z","caller":"traceutil/trace.go:171","msg":"trace[1945648002] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qq5mh; range_end:; response_count:1; response_revision:625; }","duration":"179.912523ms","start":"2026-04-18T22:13:16.584082Z","end":"2026-04-18T22:13:16.763995Z","steps":["trace[1945648002] 'agreement among raft nodes before linearized reading' (duration: 179.760207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:13:16.764189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:13:16.441799Z","time spent":"322.21206ms","remote":"127.0.0.1:50498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-18T22:13:16.764128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.219365ms","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-18T22:13:16.764331Z","caller":"traceutil/trace.go:171","msg":"trace[1844380901] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:625; }","duration":"317.515478ms","start":"2026-04-18T22:13:16.446794Z","end":"2026-04-18T22:13:16.76431Z","steps":["trace[1844380901] 'agreement among raft nodes before linearized reading' (duration: 317.002426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:13:16.764406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:13:16.446768Z","time spent":"317.627652ms","remote":"127.0.0.1:50256","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-18T22:13:17.460709Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835592248609415449,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-18T22:13:17.961757Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835592248609415449,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-18T22:13:18.174567Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.397102378s","expected-duration":"1s"} {"level":"info","ts":"2026-04-18T22:13:18.18404Z","caller":"traceutil/trace.go:171","msg":"trace[1556574293] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"1.406602761s","start":"2026-04-18T22:13:16.777404Z","end":"2026-04-18T22:13:18.184007Z","steps":["trace[1556574293] 'process raft request' (duration: 1.398109348s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:13:18.184198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:13:16.777397Z","time spent":"1.406716476s","remote":"127.0.0.1:50168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:13:18.217624Z","caller":"traceutil/trace.go:171","msg":"trace[398596905] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:645; }","duration":"1.257041864s","start":"2026-04-18T22:13:16.960534Z","end":"2026-04-18T22:13:18.217576Z","steps":["trace[398596905] 'read index received' (duration: 1.214332825s)","trace[398596905] 'applied index is now lower than readState.Index' (duration: 42.707939ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:13:18.217761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.493485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:13:18.217788Z","caller":"traceutil/trace.go:171","msg":"trace[766578076] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:628; }","duration":"878.574628ms","start":"2026-04-18T22:13:17.339205Z","end":"2026-04-18T22:13:18.21778Z","steps":["trace[766578076] 'agreement among raft nodes before linearized reading' (duration: 878.520396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:13:18.217812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:13:17.339184Z","time spent":"878.62408ms","remote":"127.0.0.1:49956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-18T22:13:18.2183Z","caller":"traceutil/trace.go:171","msg":"trace[1457793140] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"1.057657907s","start":"2026-04-18T22:13:17.160612Z","end":"2026-04-18T22:13:18.21827Z","steps":["trace[1457793140] 'process raft request' (duration: 1.05645832s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:13:18.218291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.257443532s","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-18T22:13:18.218402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:13:17.160576Z","time spent":"1.057770853s","remote":"127.0.0.1:50074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:13:18.218431Z","caller":"traceutil/trace.go:171","msg":"trace[1856223641] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:628; }","duration":"1.257939822s","start":"2026-04-18T22:13:16.960469Z","end":"2026-04-18T22:13:18.218409Z","steps":["trace[1856223641] 'agreement among raft nodes before linearized reading' (duration: 1.257324797s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:13:18.218529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:13:16.960446Z","time spent":"1.258053056s","remote":"127.0.0.1:50256","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-18T22:13:32.398554Z","caller":"traceutil/trace.go:171","msg":"trace[1765932575] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"107.404595ms","start":"2026-04-18T22:13:32.291122Z","end":"2026-04-18T22:13:32.398527Z","steps":["trace[1765932575] 'process raft request' (duration: 107.26468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:13:32.665818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.511674ms","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-18T22:13:32.665903Z","caller":"traceutil/trace.go:171","msg":"trace[1740383933] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:787; }","duration":"202.662879ms","start":"2026-04-18T22:13:32.463222Z","end":"2026-04-18T22:13:32.665885Z","steps":["trace[1740383933] 'range keys from in-memory index tree' (duration: 202.269705ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:15:04.147947Z","caller":"traceutil/trace.go:171","msg":"trace[1079556227] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"100.893581ms","start":"2026-04-18T22:15:04.04702Z","end":"2026-04-18T22:15:04.147913Z","steps":["trace[1079556227] 'process raft request' (duration: 100.586346ms)"],"step_count":1}