{"level":"warn","ts":"2026-04-02T17:41:00.871584Z","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-02T17:41:00.871681Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.89: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.89:2380","--initial-cluster=instance=https://199.204.45.89:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.89:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.89: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-02T17:41:00.871754Z","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-02T17:41:00.87177Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-04-02T17:41:00.871792Z","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-02T17:41:00.872449Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"]} {"level":"info","ts":"2026-04-02T17:41:00.872526Z","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.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.89: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-02T17:41:00.887618Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"13.386003ms"} {"level":"info","ts":"2026-04-02T17:41:00.894442Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c3b8446219deb8c1","cluster-id":"fe02a46d8aa9751f"} {"level":"info","ts":"2026-04-02T17:41:00.894517Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=()"} {"level":"info","ts":"2026-04-02T17:41:00.894548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 0"} {"level":"info","ts":"2026-04-02T17:41:00.894563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c3b8446219deb8c1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-02T17:41:00.894575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 1"} {"level":"info","ts":"2026-04-02T17:41:00.894631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"warn","ts":"2026-04-02T17:41:00.90463Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-02T17:41:00.906109Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-02T17:41:00.907656Z","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-02T17:41:00.910488Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c3b8446219deb8c1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-02T17:41:00.910644Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c3b8446219deb8c1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-02T17:41:00.911858Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"info","ts":"2026-04-02T17:41:00.912038Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","added-peer-id":"c3b8446219deb8c1","added-peer-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-04-02T17:41:00.911177Z","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-02T17:41:00.912338Z","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-02T17:41:00.91235Z","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-02T17:41:00.914913Z","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-02T17:41:00.915019Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-04-02T17:41:00.915073Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-04-02T17:41:00.915155Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c3b8446219deb8c1","initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-02T17:41:00.915188Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-02T17:41:01.39803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 is starting a new election at term 1"} {"level":"info","ts":"2026-04-02T17:41:01.398084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-02T17:41:01.398226Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgPreVoteResp from c3b8446219deb8c1 at term 1"} {"level":"info","ts":"2026-04-02T17:41:01.398264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became candidate at term 2"} {"level":"info","ts":"2026-04-02T17:41:01.398376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgVoteResp from c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-04-02T17:41:01.398392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became leader at term 2"} {"level":"info","ts":"2026-04-02T17:41:01.398399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c3b8446219deb8c1 elected leader c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-04-02T17:41:01.433685Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c3b8446219deb8c1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.89:2379]}","request-path":"/0/members/c3b8446219deb8c1/attributes","cluster-id":"fe02a46d8aa9751f","publish-timeout":"7s"} {"level":"info","ts":"2026-04-02T17:41:01.433917Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T17:41:01.434395Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T17:41:01.434572Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-02T17:41:01.434595Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-02T17:41:01.43419Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T17:41:01.437019Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-02T17:41:01.437081Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T17:41:01.437271Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T17:41:01.437336Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T17:41:01.437678Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.89:2379"} {"level":"info","ts":"2026-04-02T17:41:11.533548Z","caller":"traceutil/trace.go:171","msg":"trace[302057892] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"163.552051ms","start":"2026-04-02T17:41:11.369966Z","end":"2026-04-02T17:41:11.533518Z","steps":["trace[302057892] 'process raft request' (duration: 98.313376ms)","trace[302057892] 'compare' (duration: 65.09436ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:41:11.533731Z","caller":"traceutil/trace.go:171","msg":"trace[654118970] linearizableReadLoop","detail":"{readStateIndex:327; appliedIndex:325; }","duration":"141.084072ms","start":"2026-04-02T17:41:11.392615Z","end":"2026-04-02T17:41:11.533699Z","steps":["trace[654118970] 'read index received' (duration: 75.683923ms)","trace[654118970] 'applied index is now lower than readState.Index' (duration: 65.398379ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:41:11.533809Z","caller":"traceutil/trace.go:171","msg":"trace[1646438860] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"161.0577ms","start":"2026-04-02T17:41:11.372713Z","end":"2026-04-02T17:41:11.53377Z","steps":["trace[1646438860] 'process raft request' (duration: 160.75787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:41:11.533964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.33349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-02T17:41:11.534061Z","caller":"traceutil/trace.go:171","msg":"trace[785939281] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:317; }","duration":"141.473665ms","start":"2026-04-02T17:41:11.392574Z","end":"2026-04-02T17:41:11.534048Z","steps":["trace[785939281] 'agreement among raft nodes before linearized reading' (duration: 141.33338ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:41:11.699306Z","caller":"traceutil/trace.go:171","msg":"trace[107379457] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"105.389835ms","start":"2026-04-02T17:41:11.593888Z","end":"2026-04-02T17:41:11.699277Z","steps":["trace[107379457] 'process raft request' (duration: 105.322303ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:41:11.699317Z","caller":"traceutil/trace.go:171","msg":"trace[1713737602] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"128.02947ms","start":"2026-04-02T17:41:11.571258Z","end":"2026-04-02T17:41:11.699287Z","steps":["trace[1713737602] 'process raft request' (duration: 127.885675ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:41:11.699313Z","caller":"traceutil/trace.go:171","msg":"trace[1931578266] linearizableReadLoop","detail":"{readStateIndex:335; appliedIndex:333; }","duration":"107.3877ms","start":"2026-04-02T17:41:11.591897Z","end":"2026-04-02T17:41:11.699285Z","steps":["trace[1931578266] 'read index received' (duration: 52.681547ms)","trace[1931578266] 'applied index is now lower than readState.Index' (duration: 54.703513ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:41:11.699681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.768603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"info","ts":"2026-04-02T17:41:11.699754Z","caller":"traceutil/trace.go:171","msg":"trace[449122080] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:326; }","duration":"107.891327ms","start":"2026-04-02T17:41:11.591846Z","end":"2026-04-02T17:41:11.699737Z","steps":["trace[449122080] 'agreement among raft nodes before linearized reading' (duration: 107.522985ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:41:11.699462Z","caller":"traceutil/trace.go:171","msg":"trace[52833432] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"130.22205ms","start":"2026-04-02T17:41:11.569215Z","end":"2026-04-02T17:41:11.699437Z","steps":["trace[52833432] 'process raft request' (duration: 75.355172ms)","trace[52833432] 'compare' (duration: 54.432414ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:41:11.699697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.415419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-04-02T17:41:11.700541Z","caller":"traceutil/trace.go:171","msg":"trace[388488992] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:326; }","duration":"107.300398ms","start":"2026-04-02T17:41:11.593222Z","end":"2026-04-02T17:41:11.700522Z","steps":["trace[388488992] 'agreement among raft nodes before linearized reading' (duration: 106.414149ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:41:11.9064Z","caller":"traceutil/trace.go:171","msg":"trace[531437270] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"153.710093ms","start":"2026-04-02T17:41:11.75263Z","end":"2026-04-02T17:41:11.90634Z","steps":["trace[531437270] 'process raft request' (duration: 121.715635ms)","trace[531437270] 'compare' (duration: 31.812302ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:41:11.90645Z","caller":"traceutil/trace.go:171","msg":"trace[392777606] linearizableReadLoop","detail":"{readStateIndex:344; appliedIndex:343; }","duration":"151.622365ms","start":"2026-04-02T17:41:11.754794Z","end":"2026-04-02T17:41:11.906416Z","steps":["trace[392777606] 'read index received' (duration: 119.639458ms)","trace[392777606] 'applied index is now lower than readState.Index' (duration: 31.977317ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:41:11.906675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.868572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-fbvx2\" ","response":"range_response_count:1 size:3439"} {"level":"info","ts":"2026-04-02T17:41:11.906732Z","caller":"traceutil/trace.go:171","msg":"trace[1763534477] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-fbvx2; range_end:; response_count:1; response_revision:335; }","duration":"151.967136ms","start":"2026-04-02T17:41:11.754741Z","end":"2026-04-02T17:41:11.906709Z","steps":["trace[1763534477] 'agreement among raft nodes before linearized reading' (duration: 151.812391ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:41:11.906406Z","caller":"traceutil/trace.go:171","msg":"trace[1888156098] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"147.77105ms","start":"2026-04-02T17:41:11.75861Z","end":"2026-04-02T17:41:11.906381Z","steps":["trace[1888156098] 'process raft request' (duration: 147.716498ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:41:11.906901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.728885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2901"} {"level":"info","ts":"2026-04-02T17:41:11.906934Z","caller":"traceutil/trace.go:171","msg":"trace[1904624661] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:335; }","duration":"150.794738ms","start":"2026-04-02T17:41:11.756131Z","end":"2026-04-02T17:41:11.906926Z","steps":["trace[1904624661] 'agreement among raft nodes before linearized reading' (duration: 150.735956ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:41:12.171997Z","caller":"traceutil/trace.go:171","msg":"trace[124618696] linearizableReadLoop","detail":"{readStateIndex:354; appliedIndex:351; }","duration":"108.825483ms","start":"2026-04-02T17:41:12.063149Z","end":"2026-04-02T17:41:12.171975Z","steps":["trace[124618696] 'read index received' (duration: 14.551557ms)","trace[124618696] 'applied index is now lower than readState.Index' (duration: 94.273036ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:41:12.172188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.02819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5326"} {"level":"info","ts":"2026-04-02T17:41:12.172157Z","caller":"traceutil/trace.go:171","msg":"trace[1285069861] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"198.992338ms","start":"2026-04-02T17:41:11.973116Z","end":"2026-04-02T17:41:12.172109Z","steps":["trace[1285069861] 'process raft request' (duration: 198.76274ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:41:12.172246Z","caller":"traceutil/trace.go:171","msg":"trace[206214582] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"198.305266ms","start":"2026-04-02T17:41:11.973933Z","end":"2026-04-02T17:41:12.172238Z","steps":["trace[206214582] 'process raft request' (duration: 198.006656ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:41:12.172274Z","caller":"traceutil/trace.go:171","msg":"trace[179945258] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"201.80946ms","start":"2026-04-02T17:41:11.970421Z","end":"2026-04-02T17:41:12.172231Z","steps":["trace[179945258] 'process raft request' (duration: 107.325627ms)","trace[179945258] 'compare' (duration: 93.827441ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:41:12.17222Z","caller":"traceutil/trace.go:171","msg":"trace[2002103230] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:344; }","duration":"109.099202ms","start":"2026-04-02T17:41:12.063111Z","end":"2026-04-02T17:41:12.17221Z","steps":["trace[2002103230] 'agreement among raft nodes before linearized reading' (duration: 108.950957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:41:25.909212Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313094937332375940,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T17:41:26.251016Z","caller":"traceutil/trace.go:171","msg":"trace[2135298095] linearizableReadLoop","detail":"{readStateIndex:401; appliedIndex:400; }","duration":"842.355933ms","start":"2026-04-02T17:41:25.408621Z","end":"2026-04-02T17:41:26.250977Z","steps":["trace[2135298095] 'read index received' (duration: 842.051865ms)","trace[2135298095] 'applied index is now lower than readState.Index' (duration: 302.508µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:41:26.251101Z","caller":"traceutil/trace.go:171","msg":"trace[585959418] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"980.491952ms","start":"2026-04-02T17:41:25.270537Z","end":"2026-04-02T17:41:26.251029Z","steps":["trace[585959418] 'process raft request' (duration: 980.216254ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:41:26.251291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.416375ms","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-02T17:41:26.251351Z","caller":"traceutil/trace.go:171","msg":"trace[1819992940] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:387; }","duration":"625.549268ms","start":"2026-04-02T17:41:25.625785Z","end":"2026-04-02T17:41:26.251334Z","steps":["trace[1819992940] 'agreement among raft nodes before linearized reading' (duration: 625.410655ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:41:26.251319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"842.661922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-02T17:41:26.251418Z","caller":"traceutil/trace.go:171","msg":"trace[705773022] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:387; }","duration":"842.857918ms","start":"2026-04-02T17:41:25.408537Z","end":"2026-04-02T17:41:26.251395Z","steps":["trace[705773022] 'agreement among raft nodes before linearized reading' (duration: 842.603871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:41:26.251404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:41:25.625762Z","time spent":"625.63187ms","remote":"127.0.0.1:42218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-02T17:41:26.251477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:41:25.408505Z","time spent":"842.963811ms","remote":"127.0.0.1:42218","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-02T17:41:26.252267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:41:25.270511Z","time spent":"980.77996ms","remote":"127.0.0.1:42218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T17:41:43.477152Z","caller":"traceutil/trace.go:171","msg":"trace[1378655297] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"578.398169ms","start":"2026-04-02T17:41:42.898731Z","end":"2026-04-02T17:41:43.477129Z","steps":["trace[1378655297] 'process raft request' (duration: 578.282106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:41:43.477387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:41:42.898714Z","time spent":"578.587904ms","remote":"127.0.0.1:41966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T17:41:43.484793Z","caller":"traceutil/trace.go:171","msg":"trace[1060705080] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"582.599871ms","start":"2026-04-02T17:41:42.902175Z","end":"2026-04-02T17:41:43.484774Z","steps":["trace[1060705080] 'process raft request' (duration: 581.72694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:41:43.4849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:41:42.902154Z","time spent":"582.699133ms","remote":"127.0.0.1:41966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T17:41:43.485003Z","caller":"traceutil/trace.go:171","msg":"trace[121208750] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"582.679983ms","start":"2026-04-02T17:41:42.902317Z","end":"2026-04-02T17:41:43.484997Z","steps":["trace[121208750] 'process raft request' (duration: 582.391956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:41:43.485036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:41:42.902287Z","time spent":"582.735375ms","remote":"127.0.0.1:41966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T17:41:43.485187Z","caller":"traceutil/trace.go:171","msg":"trace[1532361361] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"576.174742ms","start":"2026-04-02T17:41:42.90899Z","end":"2026-04-02T17:41:43.485164Z","steps":["trace[1532361361] 'process raft request' (duration: 575.762192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:41:43.485298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:41:42.90898Z","time spent":"576.266935ms","remote":"127.0.0.1:41966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T17:41:43.486277Z","caller":"traceutil/trace.go:171","msg":"trace[1791048153] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"571.910527ms","start":"2026-04-02T17:41:42.914355Z","end":"2026-04-02T17:41:43.486266Z","steps":["trace[1791048153] 'process raft request' (duration: 571.605059ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:41:43.486323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:41:42.914343Z","time spent":"571.961928ms","remote":"127.0.0.1:41966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T17:41:43.486389Z","caller":"traceutil/trace.go:171","msg":"trace[1212085462] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"575.487945ms","start":"2026-04-02T17:41:42.910896Z","end":"2026-04-02T17:41:43.486384Z","steps":["trace[1212085462] 'process raft request' (duration: 573.958087ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:41:43.486418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:41:42.910887Z","time spent":"575.519926ms","remote":"127.0.0.1:41966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T17:41:43.486483Z","caller":"traceutil/trace.go:171","msg":"trace[89979103] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"572.005349ms","start":"2026-04-02T17:41:42.914471Z","end":"2026-04-02T17:41:43.486476Z","steps":["trace[89979103] 'process raft request' (duration: 571.64961ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:41:43.486509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:41:42.914455Z","time spent":"572.040621ms","remote":"127.0.0.1:41966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T17:41:43.486573Z","caller":"traceutil/trace.go:171","msg":"trace[1183331369] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"572.112272ms","start":"2026-04-02T17:41:42.914454Z","end":"2026-04-02T17:41:43.486566Z","steps":["trace[1183331369] 'process raft request' (duration: 571.62404ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:41:43.486563Z","caller":"traceutil/trace.go:171","msg":"trace[280906552] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"552.171411ms","start":"2026-04-02T17:41:42.934373Z","end":"2026-04-02T17:41:43.486545Z","steps":["trace[280906552] 'process raft request' (duration: 551.786461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:41:43.486599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:41:42.914428Z","time spent":"572.158533ms","remote":"127.0.0.1:41966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T17:41:43.486631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:41:42.934358Z","time spent":"552.249362ms","remote":"127.0.0.1:41966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T17:41:43.486665Z","caller":"traceutil/trace.go:171","msg":"trace[1739556712] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"536.31908ms","start":"2026-04-02T17:41:42.950339Z","end":"2026-04-02T17:41:43.486658Z","steps":["trace[1739556712] 'process raft request' (duration: 535.903929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:41:43.486695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:41:42.950325Z","time spent":"536.35981ms","remote":"127.0.0.1:41966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T17:41:43.486827Z","caller":"traceutil/trace.go:171","msg":"trace[398941289] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"532.802042ms","start":"2026-04-02T17:41:42.954013Z","end":"2026-04-02T17:41:43.486815Z","steps":["trace[398941289] 'process raft request' (duration: 532.29638ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:41:43.486879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:41:42.953974Z","time spent":"532.886614ms","remote":"127.0.0.1:41966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T17:41:43.487303Z","caller":"traceutil/trace.go:171","msg":"trace[1954752666] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"430.629534ms","start":"2026-04-02T17:41:43.056658Z","end":"2026-04-02T17:41:43.487287Z","steps":["trace[1954752666] 'process raft request' (duration: 429.716672ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:41:43.48741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:41:43.056633Z","time spent":"430.716396ms","remote":"127.0.0.1:41966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T17:41:47.283132Z","caller":"traceutil/trace.go:171","msg":"trace[378459421] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"139.086239ms","start":"2026-04-02T17:41:47.144013Z","end":"2026-04-02T17:41:47.283099Z","steps":["trace[378459421] 'process raft request' (duration: 138.437614ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:41:50.109465Z","caller":"traceutil/trace.go:171","msg":"trace[2041133106] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"440.69512ms","start":"2026-04-02T17:41:49.668746Z","end":"2026-04-02T17:41:50.109441Z","steps":["trace[2041133106] 'process raft request' (duration: 440.206158ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:41:50.109795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:41:49.668724Z","time spent":"440.991986ms","remote":"127.0.0.1:42006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T17:41:50.684479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.152424ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-02T17:41:50.684617Z","caller":"traceutil/trace.go:171","msg":"trace[1011458434] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:625; }","duration":"522.296915ms","start":"2026-04-02T17:41:50.162282Z","end":"2026-04-02T17:41:50.684578Z","steps":["trace[1011458434] 'read index received' (duration: 29.571µs)","trace[1011458434] 'applied index is now lower than readState.Index' (duration: 522.265054ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:41:50.684881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.577892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-t8wnt\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-02T17:41:50.684974Z","caller":"traceutil/trace.go:171","msg":"trace[298877341] range","detail":"{range_begin:/registry/pods/kube-system/cilium-t8wnt; range_end:; response_count:1; response_revision:601; }","duration":"522.693195ms","start":"2026-04-02T17:41:50.162253Z","end":"2026-04-02T17:41:50.684946Z","steps":["trace[298877341] 'agreement among raft nodes before linearized reading' (duration: 522.449319ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:41:50.685017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:41:50.162236Z","time spent":"522.772546ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-t8wnt\" "} {"level":"warn","ts":"2026-04-02T17:41:51.185525Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313094937332376335,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T17:41:51.247448Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.075060046s","expected-duration":"1s"} {"level":"info","ts":"2026-04-02T17:41:51.248508Z","caller":"traceutil/trace.go:171","msg":"trace[175034289] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"1.076180492s","start":"2026-04-02T17:41:50.172296Z","end":"2026-04-02T17:41:51.248476Z","steps":["trace[175034289] 'process raft request' (duration: 1.076008418s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:41:51.248954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:41:50.172281Z","time spent":"1.076404656s","remote":"127.0.0.1:42172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T17:41:52.219182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.768915ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T17:41:52.219341Z","caller":"traceutil/trace.go:171","msg":"trace[1417104593] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:626; }","duration":"1.534646214s","start":"2026-04-02T17:41:50.68467Z","end":"2026-04-02T17:41:52.219316Z","steps":["trace[1417104593] 'read index received' (duration: 563.288715ms)","trace[1417104593] 'applied index is now lower than readState.Index' (duration: 971.355479ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:41:52.219584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.798242658s","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-02T17:41:52.219647Z","caller":"traceutil/trace.go:171","msg":"trace[1892553812] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:603; }","duration":"1.79834481s","start":"2026-04-02T17:41:50.421289Z","end":"2026-04-02T17:41:52.219633Z","steps":["trace[1892553812] 'agreement among raft nodes before linearized reading' (duration: 1.798135596s)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:41:52.21939Z","caller":"traceutil/trace.go:171","msg":"trace[573265311] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"1.519983014s","start":"2026-04-02T17:41:50.699358Z","end":"2026-04-02T17:41:52.219341Z","steps":["trace[573265311] 'process raft request' (duration: 621.937612ms)","trace[573265311] 'compare' (duration: 896.957987ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:41:52.219636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.499437917s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T17:41:52.219783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"965.668509ms","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-02T17:41:52.219682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.831305145s","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-02T17:41:52.21982Z","caller":"traceutil/trace.go:171","msg":"trace[635033683] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:603; }","duration":"965.740431ms","start":"2026-04-02T17:41:51.254071Z","end":"2026-04-02T17:41:52.219812Z","steps":["trace[635033683] 'agreement among raft nodes before linearized reading' (duration: 965.649758ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:41:52.21985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:41:51.254045Z","time spent":"965.798711ms","remote":"127.0.0.1:42142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-04-02T17:41:52.219819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:41:50.699338Z","time spent":"1.520355262s","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T17:41:52.219856Z","caller":"traceutil/trace.go:171","msg":"trace[152502705] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:603; }","duration":"1.831499419s","start":"2026-04-02T17:41:50.388336Z","end":"2026-04-02T17:41:52.219835Z","steps":["trace[152502705] 'agreement among raft nodes before linearized reading' (duration: 1.831288754s)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:41:52.219918Z","caller":"traceutil/trace.go:171","msg":"trace[540115320] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:603; }","duration":"1.499597781s","start":"2026-04-02T17:41:50.72014Z","end":"2026-04-02T17:41:52.219738Z","steps":["trace[540115320] 'agreement among raft nodes before linearized reading' (duration: 1.499353545s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:41:52.219997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:41:50.388317Z","time spent":"1.831668023s","remote":"127.0.0.1:42218","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-02T17:41:52.220175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:41:50.72013Z","time spent":"1.50002937s","remote":"127.0.0.1:41908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T17:41:52.219557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.65777531s","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-02T17:41:52.220261Z","caller":"traceutil/trace.go:171","msg":"trace[1451707587] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:603; }","duration":"1.658520626s","start":"2026-04-02T17:41:50.561724Z","end":"2026-04-02T17:41:52.220245Z","steps":["trace[1451707587] 'agreement among raft nodes before linearized reading' (duration: 1.657680647s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:41:52.22027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.795146976s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-02T17:41:52.220291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:41:50.56171Z","time spent":"1.658571728s","remote":"127.0.0.1:42218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-02T17:41:52.220325Z","caller":"traceutil/trace.go:171","msg":"trace[65695079] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:603; }","duration":"1.795250538s","start":"2026-04-02T17:41:50.425057Z","end":"2026-04-02T17:41:52.220308Z","steps":["trace[65695079] 'agreement among raft nodes before linearized reading' (duration: 1.794329887s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:41:52.220359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:41:50.425035Z","time spent":"1.79531607s","remote":"127.0.0.1:42218","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-02T17:41:52.219693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:41:50.421268Z","time spent":"1.798415742s","remote":"127.0.0.1:42218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-02T17:41:52.920091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.317218ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T17:41:52.920894Z","caller":"traceutil/trace.go:171","msg":"trace[650472576] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"695.711312ms","start":"2026-04-02T17:41:52.225154Z","end":"2026-04-02T17:41:52.920866Z","steps":["trace[650472576] 'process raft request' (duration: 113.539525ms)","trace[650472576] 'compare' (duration: 581.223706ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:41:52.920943Z","caller":"traceutil/trace.go:171","msg":"trace[517117073] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:628; }","duration":"695.057047ms","start":"2026-04-02T17:41:52.225862Z","end":"2026-04-02T17:41:52.920919Z","steps":["trace[517117073] 'read index received' (duration: 112.847379ms)","trace[517117073] 'applied index is now lower than readState.Index' (duration: 582.208378ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:41:52.921011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:41:52.225128Z","time spent":"695.827795ms","remote":"127.0.0.1:42218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T17:41:52.921182Z","caller":"traceutil/trace.go:171","msg":"trace[516396958] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"696.000749ms","start":"2026-04-02T17:41:52.225169Z","end":"2026-04-02T17:41:52.92117Z","steps":["trace[516396958] 'process raft request' (duration: 695.568069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:41:52.921286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:41:52.22515Z","time spent":"696.066291ms","remote":"127.0.0.1:42218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T17:41:52.921413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.548869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-02T17:41:52.921452Z","caller":"traceutil/trace.go:171","msg":"trace[1340891213] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:607; }","duration":"695.61013ms","start":"2026-04-02T17:41:52.225832Z","end":"2026-04-02T17:41:52.921442Z","steps":["trace[1340891213] 'agreement among raft nodes before linearized reading' (duration: 695.540969ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:41:52.921477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:41:52.22582Z","time spent":"695.650851ms","remote":"127.0.0.1:42142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-04-02T17:41:52.921481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.046169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T17:41:52.921513Z","caller":"traceutil/trace.go:171","msg":"trace[33433217] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:607; }","duration":"692.09843ms","start":"2026-04-02T17:41:52.229404Z","end":"2026-04-02T17:41:52.921503Z","steps":["trace[33433217] 'agreement among raft nodes before linearized reading' (duration: 692.050019ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:41:52.921548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:41:52.229396Z","time spent":"692.145701ms","remote":"127.0.0.1:41908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T17:41:52.921847Z","caller":"traceutil/trace.go:171","msg":"trace[767656369] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"696.016039ms","start":"2026-04-02T17:41:52.22582Z","end":"2026-04-02T17:41:52.921836Z","steps":["trace[767656369] 'process raft request' (duration: 695.032807ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:41:52.921914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:41:52.225803Z","time spent":"696.077641ms","remote":"127.0.0.1:42218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T17:41:52.922132Z","caller":"traceutil/trace.go:171","msg":"trace[1478440177] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"695.330933ms","start":"2026-04-02T17:41:52.226772Z","end":"2026-04-02T17:41:52.922103Z","steps":["trace[1478440177] 'process raft request' (duration: 694.112006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:41:52.922278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:41:52.226763Z","time spent":"695.440557ms","remote":"127.0.0.1:42218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T17:41:53.361293Z","caller":"traceutil/trace.go:171","msg":"trace[68136199] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"109.917054ms","start":"2026-04-02T17:41:53.251346Z","end":"2026-04-02T17:41:53.361263Z","steps":["trace[68136199] 'process raft request' (duration: 109.74282ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:41:54.701137Z","caller":"traceutil/trace.go:171","msg":"trace[535490133] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"413.701251ms","start":"2026-04-02T17:41:54.28741Z","end":"2026-04-02T17:41:54.701112Z","steps":["trace[535490133] 'process raft request' (duration: 413.63911ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:41:54.701243Z","caller":"traceutil/trace.go:171","msg":"trace[1906476904] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"415.069612ms","start":"2026-04-02T17:41:54.286097Z","end":"2026-04-02T17:41:54.701167Z","steps":["trace[1906476904] 'process raft request' (duration: 414.806047ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:41:54.701339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:41:54.287401Z","time spent":"413.824024ms","remote":"127.0.0.1:41966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5562,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T17:41:54.701448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:41:54.286087Z","time spent":"415.282497ms","remote":"127.0.0.1:42502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":932,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T17:41:54.707724Z","caller":"traceutil/trace.go:171","msg":"trace[487676610] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"377.591627ms","start":"2026-04-02T17:41:54.330097Z","end":"2026-04-02T17:41:54.707689Z","steps":["trace[487676610] 'process raft request' (duration: 377.047955ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:41:54.707987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:41:54.330076Z","time spent":"377.787792ms","remote":"127.0.0.1:42502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":950,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T17:41:54.70866Z","caller":"traceutil/trace.go:171","msg":"trace[2031956865] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:657; }","duration":"118.367247ms","start":"2026-04-02T17:41:54.59027Z","end":"2026-04-02T17:41:54.708637Z","steps":["trace[2031956865] 'read index received' (duration: 110.735135ms)","trace[2031956865] 'applied index is now lower than readState.Index' (duration: 7.630622ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:41:54.708912Z","caller":"traceutil/trace.go:171","msg":"trace[80529726] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"230.046404ms","start":"2026-04-02T17:41:54.478852Z","end":"2026-04-02T17:41:54.708898Z","steps":["trace[80529726] 'process raft request' (duration: 228.576531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:41:54.70925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.9811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.89\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-02T17:41:54.709299Z","caller":"traceutil/trace.go:171","msg":"trace[715988006] range","detail":"{range_begin:/registry/masterleases/199.204.45.89; range_end:; response_count:1; response_revision:636; }","duration":"119.086833ms","start":"2026-04-02T17:41:54.590196Z","end":"2026-04-02T17:41:54.709283Z","steps":["trace[715988006] 'agreement among raft nodes before linearized reading' (duration: 118.890999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:42:10.527444Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.249229989s","expected-duration":"1s"} {"level":"info","ts":"2026-04-02T17:42:10.527995Z","caller":"traceutil/trace.go:171","msg":"trace[38941883] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"1.249861762s","start":"2026-04-02T17:42:09.278111Z","end":"2026-04-02T17:42:10.527973Z","steps":["trace[38941883] 'process raft request' (duration: 1.249591266s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:42:10.528187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:42:09.278092Z","time spent":"1.249980254s","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4024,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T17:42:10.558295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.214353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-02T17:42:10.558361Z","caller":"traceutil/trace.go:171","msg":"trace[929362754] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:773; }","duration":"186.324974ms","start":"2026-04-02T17:42:10.372019Z","end":"2026-04-02T17:42:10.558344Z","steps":["trace[929362754] 'agreement among raft nodes before linearized reading' (duration: 186.144611ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:42:10.558129Z","caller":"traceutil/trace.go:171","msg":"trace[1663730247] linearizableReadLoop","detail":"{readStateIndex:804; appliedIndex:802; }","duration":"185.939677ms","start":"2026-04-02T17:42:10.372079Z","end":"2026-04-02T17:42:10.558019Z","steps":["trace[1663730247] 'read index received' (duration: 155.694764ms)","trace[1663730247] 'applied index is now lower than readState.Index' (duration: 30.238373ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:43:01.54392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.073973ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T17:43:01.544121Z","caller":"traceutil/trace.go:171","msg":"trace[42486472] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"194.263376ms","start":"2026-04-02T17:43:01.349841Z","end":"2026-04-02T17:43:01.544104Z","steps":["trace[42486472] 'process raft request' (duration: 194.203135ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:43:01.544192Z","caller":"traceutil/trace.go:171","msg":"trace[1562985505] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"194.382377ms","start":"2026-04-02T17:43:01.349773Z","end":"2026-04-02T17:43:01.544155Z","steps":["trace[1562985505] 'process raft request' (duration: 89.965289ms)","trace[1562985505] 'compare' (duration: 103.941341ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:43:49.367456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.888423ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T17:43:49.367587Z","caller":"traceutil/trace.go:171","msg":"trace[335843840] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"403.60344ms","start":"2026-04-02T17:43:48.963964Z","end":"2026-04-02T17:43:49.367567Z","steps":["trace[335843840] 'process raft request' (duration: 29.513624ms)","trace[335843840] 'compare' (duration: 373.745541ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:43:49.367649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:43:48.963934Z","time spent":"403.68669ms","remote":"127.0.0.1:42218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T17:43:49.531974Z","caller":"traceutil/trace.go:171","msg":"trace[563034088] linearizableReadLoop","detail":"{readStateIndex:1296; appliedIndex:1295; }","duration":"406.148274ms","start":"2026-04-02T17:43:49.125794Z","end":"2026-04-02T17:43:49.531943Z","steps":["trace[563034088] 'read index received' (duration: 405.025959ms)","trace[563034088] 'applied index is now lower than readState.Index' (duration: 1.120785ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:43:49.531997Z","caller":"traceutil/trace.go:171","msg":"trace[1901983227] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"505.404894ms","start":"2026-04-02T17:43:49.026562Z","end":"2026-04-02T17:43:49.531967Z","steps":["trace[1901983227] 'process raft request' (duration: 504.261118ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:43:49.532162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:43:49.02654Z","time spent":"505.535715ms","remote":"127.0.0.1:41980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":853,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T17:43:49.532319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.507029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-02T17:43:49.532376Z","caller":"traceutil/trace.go:171","msg":"trace[2069117706] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1245; }","duration":"406.6034ms","start":"2026-04-02T17:43:49.125759Z","end":"2026-04-02T17:43:49.532363Z","steps":["trace[2069117706] 'agreement among raft nodes before linearized reading' (duration: 406.389657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:43:49.532419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:43:49.125743Z","time spent":"406.665942ms","remote":"127.0.0.1:42218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-02T17:43:54.165997Z","caller":"traceutil/trace.go:171","msg":"trace[117345107] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"126.097919ms","start":"2026-04-02T17:43:54.039852Z","end":"2026-04-02T17:43:54.16595Z","steps":["trace[117345107] 'process raft request' (duration: 124.054461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:43:57.12583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.139727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T17:43:57.125939Z","caller":"traceutil/trace.go:171","msg":"trace[1921432614] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1287; }","duration":"405.299889ms","start":"2026-04-02T17:43:56.720615Z","end":"2026-04-02T17:43:57.125915Z","steps":["trace[1921432614] 'range keys from in-memory index tree' (duration: 404.997716ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:43:57.126042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:43:56.72059Z","time spent":"405.434322ms","remote":"127.0.0.1:41908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T17:44:00.884392Z","caller":"traceutil/trace.go:171","msg":"trace[2130110188] linearizableReadLoop","detail":"{readStateIndex:1358; appliedIndex:1357; }","duration":"194.571882ms","start":"2026-04-02T17:44:00.689793Z","end":"2026-04-02T17:44:00.884365Z","steps":["trace[2130110188] 'read index received' (duration: 194.471261ms)","trace[2130110188] 'applied index is now lower than readState.Index' (duration: 99.451µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:44:00.88444Z","caller":"traceutil/trace.go:171","msg":"trace[1749476278] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"232.417241ms","start":"2026-04-02T17:44:00.651999Z","end":"2026-04-02T17:44:00.884416Z","steps":["trace[1749476278] 'process raft request' (duration: 232.221198ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:44:00.884695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.877966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"warn","ts":"2026-04-02T17:44:00.884761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.727888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T17:44:00.884834Z","caller":"traceutil/trace.go:171","msg":"trace[1186199834] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1304; }","duration":"195.043638ms","start":"2026-04-02T17:44:00.689747Z","end":"2026-04-02T17:44:00.88479Z","steps":["trace[1186199834] 'agreement among raft nodes before linearized reading' (duration: 194.755834ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:44:00.884923Z","caller":"traceutil/trace.go:171","msg":"trace[1697664637] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1304; }","duration":"163.848719ms","start":"2026-04-02T17:44:00.720964Z","end":"2026-04-02T17:44:00.884813Z","steps":["trace[1697664637] 'agreement among raft nodes before linearized reading' (duration: 163.739048ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:44:03.411604Z","caller":"traceutil/trace.go:171","msg":"trace[852804079] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"215.348593ms","start":"2026-04-02T17:44:03.196233Z","end":"2026-04-02T17:44:03.411582Z","steps":["trace[852804079] 'process raft request' (duration: 215.202091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:44:03.72903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.942281ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T17:44:03.729223Z","caller":"traceutil/trace.go:171","msg":"trace[1827286081] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"312.155436ms","start":"2026-04-02T17:44:03.417037Z","end":"2026-04-02T17:44:03.729192Z","steps":["trace[1827286081] 'process raft request' (duration: 106.960482ms)","trace[1827286081] 'compare' (duration: 204.837609ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:44:03.72932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:44:03.417018Z","time spent":"312.253637ms","remote":"127.0.0.1:41980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":706,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T17:44:03.92894Z","caller":"traceutil/trace.go:171","msg":"trace[921240018] linearizableReadLoop","detail":"{readStateIndex:1367; appliedIndex:1366; }","duration":"151.240577ms","start":"2026-04-02T17:44:03.777673Z","end":"2026-04-02T17:44:03.928914Z","steps":["trace[921240018] 'read index received' (duration: 107.937635ms)","trace[921240018] 'applied index is now lower than readState.Index' (duration: 43.301972ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:44:03.929078Z","caller":"traceutil/trace.go:171","msg":"trace[681361812] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"195.183578ms","start":"2026-04-02T17:44:03.733867Z","end":"2026-04-02T17:44:03.929051Z","steps":["trace[681361812] 'process raft request' (duration: 151.842745ms)","trace[681361812] 'compare' (duration: 43.093749ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:44:03.929181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.48216ms","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-02T17:44:03.929232Z","caller":"traceutil/trace.go:171","msg":"trace[945603384] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1313; }","duration":"151.571891ms","start":"2026-04-02T17:44:03.777635Z","end":"2026-04-02T17:44:03.929207Z","steps":["trace[945603384] 'agreement among raft nodes before linearized reading' (duration: 151.418169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:44:03.93086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.018437ms","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-02T17:44:03.930909Z","caller":"traceutil/trace.go:171","msg":"trace[305828239] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1313; }","duration":"149.090048ms","start":"2026-04-02T17:44:03.781806Z","end":"2026-04-02T17:44:03.930896Z","steps":["trace[305828239] 'agreement among raft nodes before linearized reading' (duration: 147.541057ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:44:04.209524Z","caller":"traceutil/trace.go:171","msg":"trace[1523276101] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"274.752141ms","start":"2026-04-02T17:44:03.934758Z","end":"2026-04-02T17:44:04.20951Z","steps":["trace[1523276101] 'process raft request' (duration: 269.240535ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:44:04.209484Z","caller":"traceutil/trace.go:171","msg":"trace[1630689486] linearizableReadLoop","detail":"{readStateIndex:1370; appliedIndex:1367; }","duration":"259.853298ms","start":"2026-04-02T17:44:03.949606Z","end":"2026-04-02T17:44:04.209459Z","steps":["trace[1630689486] 'read index received' (duration: 3.880704ms)","trace[1630689486] 'applied index is now lower than readState.Index' (duration: 255.971464ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:44:04.209657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.03376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T17:44:04.209685Z","caller":"traceutil/trace.go:171","msg":"trace[852092967] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"274.801882ms","start":"2026-04-02T17:44:03.934876Z","end":"2026-04-02T17:44:04.209678Z","steps":["trace[852092967] 'process raft request' (duration: 274.522288ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:44:04.209719Z","caller":"traceutil/trace.go:171","msg":"trace[793575343] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1316; }","duration":"260.141071ms","start":"2026-04-02T17:44:03.949563Z","end":"2026-04-02T17:44:04.209704Z","steps":["trace[793575343] 'agreement among raft nodes before linearized reading' (duration: 259.993879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:44:04.21948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.359229ms","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-02T17:44:04.219481Z","caller":"traceutil/trace.go:171","msg":"trace[605826272] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"162.577718ms","start":"2026-04-02T17:44:04.056873Z","end":"2026-04-02T17:44:04.219451Z","steps":["trace[605826272] 'process raft request' (duration: 162.414296ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:44:04.219533Z","caller":"traceutil/trace.go:171","msg":"trace[21671321] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1317; }","duration":"215.45948ms","start":"2026-04-02T17:44:04.004062Z","end":"2026-04-02T17:44:04.219522Z","steps":["trace[21671321] 'agreement among raft nodes before linearized reading' (duration: 215.301958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:44:04.219702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.245702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T17:44:04.219731Z","caller":"traceutil/trace.go:171","msg":"trace[71469461] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1317; }","duration":"101.278152ms","start":"2026-04-02T17:44:04.118445Z","end":"2026-04-02T17:44:04.219723Z","steps":["trace[71469461] 'agreement among raft nodes before linearized reading' (duration: 101.232151ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:44:05.148851Z","caller":"traceutil/trace.go:171","msg":"trace[205923238] linearizableReadLoop","detail":"{readStateIndex:1373; appliedIndex:1372; }","duration":"428.15299ms","start":"2026-04-02T17:44:04.720672Z","end":"2026-04-02T17:44:05.148825Z","steps":["trace[205923238] 'read index received' (duration: 427.937247ms)","trace[205923238] 'applied index is now lower than readState.Index' (duration: 214.252µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:44:05.148873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:44:04.598127Z","time spent":"550.740483ms","remote":"127.0.0.1:41948","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-02T17:44:05.149002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.324853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T17:44:05.149044Z","caller":"traceutil/trace.go:171","msg":"trace[1634193540] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1318; }","duration":"428.409793ms","start":"2026-04-02T17:44:04.720622Z","end":"2026-04-02T17:44:05.149031Z","steps":["trace[1634193540] 'agreement among raft nodes before linearized reading' (duration: 428.301482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:44:05.149113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:44:04.720603Z","time spent":"428.490504ms","remote":"127.0.0.1:41908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T17:44:08.418449Z","caller":"traceutil/trace.go:171","msg":"trace[2134556181] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"194.024832ms","start":"2026-04-02T17:44:08.224394Z","end":"2026-04-02T17:44:08.418418Z","steps":["trace[2134556181] 'process raft request' (duration: 193.889151ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:44:08.438902Z","caller":"traceutil/trace.go:171","msg":"trace[149273844] linearizableReadLoop","detail":"{readStateIndex:1390; appliedIndex:1387; }","duration":"190.326682ms","start":"2026-04-02T17:44:08.248556Z","end":"2026-04-02T17:44:08.438883Z","steps":["trace[149273844] 'read index received' (duration: 169.754203ms)","trace[149273844] 'applied index is now lower than readState.Index' (duration: 20.571189ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:44:08.43897Z","caller":"traceutil/trace.go:171","msg":"trace[1544087655] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"212.25091ms","start":"2026-04-02T17:44:08.226695Z","end":"2026-04-02T17:44:08.438946Z","steps":["trace[1544087655] 'process raft request' (duration: 212.015737ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:44:08.439031Z","caller":"traceutil/trace.go:171","msg":"trace[1610792423] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"204.875739ms","start":"2026-04-02T17:44:08.234138Z","end":"2026-04-02T17:44:08.439014Z","steps":["trace[1610792423] 'process raft request' (duration: 204.672136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:44:08.439078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.486464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-02T17:44:08.439112Z","caller":"traceutil/trace.go:171","msg":"trace[1297053505] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1335; }","duration":"190.577575ms","start":"2026-04-02T17:44:08.248523Z","end":"2026-04-02T17:44:08.439101Z","steps":["trace[1297053505] 'agreement among raft nodes before linearized reading' (duration: 190.430193ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:44:12.623043Z","caller":"traceutil/trace.go:171","msg":"trace[456401597] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"168.236809ms","start":"2026-04-02T17:44:12.454781Z","end":"2026-04-02T17:44:12.623018Z","steps":["trace[456401597] 'process raft request' (duration: 168.089977ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:44:12.658607Z","caller":"traceutil/trace.go:171","msg":"trace[2045324304] linearizableReadLoop","detail":"{readStateIndex:1405; appliedIndex:1403; }","duration":"171.460382ms","start":"2026-04-02T17:44:12.48712Z","end":"2026-04-02T17:44:12.65858Z","steps":["trace[2045324304] 'read index received' (duration: 135.796872ms)","trace[2045324304] 'applied index is now lower than readState.Index' (duration: 35.66245ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:44:12.658627Z","caller":"traceutil/trace.go:171","msg":"trace[44428637] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"203.182169ms","start":"2026-04-02T17:44:12.455426Z","end":"2026-04-02T17:44:12.658608Z","steps":["trace[44428637] 'process raft request' (duration: 203.025657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:44:12.658832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.150922ms","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-02T17:44:12.658846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.344459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-approver\" ","response":"range_response_count:1 size:700"} {"level":"info","ts":"2026-04-02T17:44:12.658877Z","caller":"traceutil/trace.go:171","msg":"trace[1419204537] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1349; }","duration":"158.226163ms","start":"2026-04-02T17:44:12.500638Z","end":"2026-04-02T17:44:12.658865Z","steps":["trace[1419204537] 'agreement among raft nodes before linearized reading' (duration: 158.117351ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:44:12.658932Z","caller":"traceutil/trace.go:171","msg":"trace[771131238] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-approver; range_end:; response_count:1; response_revision:1349; }","duration":"165.47153ms","start":"2026-04-02T17:44:12.493436Z","end":"2026-04-02T17:44:12.658907Z","steps":["trace[771131238] 'agreement among raft nodes before linearized reading' (duration: 165.312638ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:44:12.659337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.596904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:selfnodeclient\" ","response":"range_response_count:1 size:718"} {"level":"info","ts":"2026-04-02T17:44:12.659406Z","caller":"traceutil/trace.go:171","msg":"trace[1411264386] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:selfnodeclient; range_end:; response_count:1; response_revision:1349; }","duration":"172.367554ms","start":"2026-04-02T17:44:12.487017Z","end":"2026-04-02T17:44:12.659385Z","steps":["trace[1411264386] 'agreement among raft nodes before linearized reading' (duration: 171.658574ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:44:30.550331Z","caller":"traceutil/trace.go:171","msg":"trace[1311760560] linearizableReadLoop","detail":"{readStateIndex:1466; appliedIndex:1465; }","duration":"213.127643ms","start":"2026-04-02T17:44:30.33718Z","end":"2026-04-02T17:44:30.550308Z","steps":["trace[1311760560] 'read index received' (duration: 212.93643ms)","trace[1311760560] 'applied index is now lower than readState.Index' (duration: 190.303µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:44:30.550512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.307206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-04-02T17:44:30.550553Z","caller":"traceutil/trace.go:171","msg":"trace[71703468] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:1406; }","duration":"213.403347ms","start":"2026-04-02T17:44:30.33714Z","end":"2026-04-02T17:44:30.550543Z","steps":["trace[71703468] 'agreement among raft nodes before linearized reading' (duration: 213.311166ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:44:30.550412Z","caller":"traceutil/trace.go:171","msg":"trace[1271897589] transaction","detail":"{read_only:false; response_revision:1406; number_of_response:1; }","duration":"230.927849ms","start":"2026-04-02T17:44:30.319447Z","end":"2026-04-02T17:44:30.550375Z","steps":["trace[1271897589] 'process raft request' (duration: 230.727126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:44:30.550814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.341655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-02T17:44:30.550921Z","caller":"traceutil/trace.go:171","msg":"trace[1066121260] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:1406; }","duration":"175.496156ms","start":"2026-04-02T17:44:30.375398Z","end":"2026-04-02T17:44:30.550895Z","steps":["trace[1066121260] 'agreement among raft nodes before linearized reading' (duration: 175.208243ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:44:36.341658Z","caller":"traceutil/trace.go:171","msg":"trace[1529124950] transaction","detail":"{read_only:false; response_revision:1424; number_of_response:1; }","duration":"149.173908ms","start":"2026-04-02T17:44:36.192457Z","end":"2026-04-02T17:44:36.341631Z","steps":["trace[1529124950] 'process raft request' (duration: 149.028686ms)"],"step_count":1}