{"level":"warn","ts":"2026-03-31T04:48:42.217895Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-31T04:48:42.217978Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.44: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.44:2380","--initial-cluster=instance=https://199.204.45.44:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.44:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.44: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-03-31T04:48:42.218054Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-31T04:48:42.218068Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.44:2380"]} {"level":"info","ts":"2026-03-31T04:48:42.21809Z","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-03-31T04:48:42.218724Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"]} {"level":"info","ts":"2026-03-31T04:48:42.218812Z","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.44:2380"],"listen-peer-urls":["https://199.204.45.44:2380"],"advertise-client-urls":["https://199.204.45.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.44: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-03-31T04:48:42.222914Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.85899ms"} {"level":"info","ts":"2026-03-31T04:48:42.229434Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a0a01fc7fbf6be45","cluster-id":"92fa3fee501eaaec"} {"level":"info","ts":"2026-03-31T04:48:42.229516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=()"} {"level":"info","ts":"2026-03-31T04:48:42.229536Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 0"} {"level":"info","ts":"2026-03-31T04:48:42.229549Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a0a01fc7fbf6be45 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-31T04:48:42.229585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 1"} {"level":"info","ts":"2026-03-31T04:48:42.229652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"} {"level":"warn","ts":"2026-03-31T04:48:42.284437Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-31T04:48:42.286204Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-31T04:48:42.287382Z","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-03-31T04:48:42.290823Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a0a01fc7fbf6be45","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-31T04:48:42.293123Z","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-03-31T04:48:42.293185Z","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-03-31T04:48:42.293194Z","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-03-31T04:48:42.293121Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a0a01fc7fbf6be45","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-31T04:48:42.295489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"} {"level":"info","ts":"2026-03-31T04:48:42.299525Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"92fa3fee501eaaec","local-member-id":"a0a01fc7fbf6be45","added-peer-id":"a0a01fc7fbf6be45","added-peer-peer-urls":["https://199.204.45.44:2380"]} {"level":"info","ts":"2026-03-31T04:48:42.300532Z","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-03-31T04:48:42.300662Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.44:2380"} {"level":"info","ts":"2026-03-31T04:48:42.300678Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.44:2380"} {"level":"info","ts":"2026-03-31T04:48:42.301402Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a0a01fc7fbf6be45","initial-advertise-peer-urls":["https://199.204.45.44:2380"],"listen-peer-urls":["https://199.204.45.44:2380"],"advertise-client-urls":["https://199.204.45.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-31T04:48:42.301453Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-31T04:48:42.333508Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 is starting a new election at term 1"} {"level":"info","ts":"2026-03-31T04:48:42.333557Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-31T04:48:42.333654Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgPreVoteResp from a0a01fc7fbf6be45 at term 1"} {"level":"info","ts":"2026-03-31T04:48:42.333732Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became candidate at term 2"} {"level":"info","ts":"2026-03-31T04:48:42.333755Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgVoteResp from a0a01fc7fbf6be45 at term 2"} {"level":"info","ts":"2026-03-31T04:48:42.333779Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became leader at term 2"} {"level":"info","ts":"2026-03-31T04:48:42.333899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a0a01fc7fbf6be45 elected leader a0a01fc7fbf6be45 at term 2"} {"level":"info","ts":"2026-03-31T04:48:42.335117Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a0a01fc7fbf6be45","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.44:2379]}","request-path":"/0/members/a0a01fc7fbf6be45/attributes","cluster-id":"92fa3fee501eaaec","publish-timeout":"7s"} {"level":"info","ts":"2026-03-31T04:48:42.335142Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-31T04:48:42.335354Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-31T04:48:42.335962Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T04:48:42.336241Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-31T04:48:42.336302Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-31T04:48:42.337306Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"92fa3fee501eaaec","local-member-id":"a0a01fc7fbf6be45","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T04:48:42.337635Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T04:48:42.337833Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T04:48:42.339619Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.44:2379"} {"level":"info","ts":"2026-03-31T04:48:42.339888Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-31T04:48:44.652451Z","caller":"traceutil/trace.go:171","msg":"trace[1387782232] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"165.620246ms","start":"2026-03-31T04:48:44.48681Z","end":"2026-03-31T04:48:44.65243Z","steps":["trace[1387782232] 'process raft request' (duration: 165.515115ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:48:45.550559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.589305ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-31T04:48:45.550682Z","caller":"traceutil/trace.go:171","msg":"trace[775091535] transaction","detail":"{read_only:false; response_revision:107; number_of_response:1; }","duration":"182.41326ms","start":"2026-03-31T04:48:45.368253Z","end":"2026-03-31T04:48:45.550666Z","steps":["trace[775091535] 'process raft request' (duration: 72.239189ms)","trace[775091535] 'compare' (duration: 109.440144ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T04:48:45.559466Z","caller":"traceutil/trace.go:171","msg":"trace[154059485] transaction","detail":"{read_only:false; response_revision:108; number_of_response:1; }","duration":"170.44869ms","start":"2026-03-31T04:48:45.388986Z","end":"2026-03-31T04:48:45.559435Z","steps":["trace[154059485] 'process raft request' (duration: 170.261758ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:48:45.886596Z","caller":"traceutil/trace.go:171","msg":"trace[1572509417] transaction","detail":"{read_only:false; response_revision:118; number_of_response:1; }","duration":"104.479094ms","start":"2026-03-31T04:48:45.782092Z","end":"2026-03-31T04:48:45.886571Z","steps":["trace[1572509417] 'process raft request' (duration: 90.108809ms)","trace[1572509417] 'compare' (duration: 14.214484ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T04:48:48.482899Z","caller":"traceutil/trace.go:171","msg":"trace[326931420] transaction","detail":"{read_only:false; response_revision:226; number_of_response:1; }","duration":"104.322294ms","start":"2026-03-31T04:48:48.378551Z","end":"2026-03-31T04:48:48.482873Z","steps":["trace[326931420] 'process raft request' (duration: 104.263644ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:48:48.482918Z","caller":"traceutil/trace.go:171","msg":"trace[1548245734] transaction","detail":"{read_only:false; response_revision:225; number_of_response:1; }","duration":"104.393324ms","start":"2026-03-31T04:48:48.378503Z","end":"2026-03-31T04:48:48.482896Z","steps":["trace[1548245734] 'process raft request' (duration: 104.252383ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:48:48.482896Z","caller":"traceutil/trace.go:171","msg":"trace[736638752] transaction","detail":"{read_only:false; response_revision:224; number_of_response:1; }","duration":"106.571906ms","start":"2026-03-31T04:48:48.37629Z","end":"2026-03-31T04:48:48.482862Z","steps":["trace[736638752] 'process raft request' (duration: 20.243379ms)","trace[736638752] 'compare' (duration: 86.028694ms)"],"step_count":2} 2026/03/31 04:48:49 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-03-31T04:48:49.560232Z","caller":"traceutil/trace.go:171","msg":"trace[564430938] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"107.253985ms","start":"2026-03-31T04:48:49.452933Z","end":"2026-03-31T04:48:49.560187Z","steps":["trace[564430938] 'process raft request' (duration: 84.761476ms)","trace[564430938] 'compare' (duration: 22.133465ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T04:48:49.560315Z","caller":"traceutil/trace.go:171","msg":"trace[1437326829] transaction","detail":"{read_only:false; response_revision:276; number_of_response:1; }","duration":"107.227434ms","start":"2026-03-31T04:48:49.453057Z","end":"2026-03-31T04:48:49.560285Z","steps":["trace[1437326829] 'process raft request' (duration: 107.109922ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:48:49.560558Z","caller":"traceutil/trace.go:171","msg":"trace[1162186503] transaction","detail":"{read_only:false; number_of_response:0; response_revision:275; }","duration":"107.536087ms","start":"2026-03-31T04:48:49.453011Z","end":"2026-03-31T04:48:49.560547Z","steps":["trace[1162186503] 'process raft request' (duration: 107.107513ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:48:49.560722Z","caller":"traceutil/trace.go:171","msg":"trace[957834281] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"107.145903ms","start":"2026-03-31T04:48:49.453546Z","end":"2026-03-31T04:48:49.560692Z","steps":["trace[957834281] 'process raft request' (duration: 106.725489ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:48:49.560821Z","caller":"traceutil/trace.go:171","msg":"trace[1948278522] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"107.546207ms","start":"2026-03-31T04:48:49.453243Z","end":"2026-03-31T04:48:49.560789Z","steps":["trace[1948278522] 'process raft request' (duration: 106.989272ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:48:49.560761Z","caller":"traceutil/trace.go:171","msg":"trace[923942916] transaction","detail":"{read_only:false; number_of_response:0; response_revision:276; }","duration":"107.536277ms","start":"2026-03-31T04:48:49.453213Z","end":"2026-03-31T04:48:49.56075Z","steps":["trace[923942916] 'process raft request' (duration: 106.997852ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:48:49.772548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.689818ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-31T04:48:49.772704Z","caller":"traceutil/trace.go:171","msg":"trace[1758272064] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"117.557834ms","start":"2026-03-31T04:48:49.655123Z","end":"2026-03-31T04:48:49.772681Z","steps":["trace[1758272064] 'compare' (duration: 108.489746ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:48:49.811535Z","caller":"traceutil/trace.go:171","msg":"trace[1872895130] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"141.664988ms","start":"2026-03-31T04:48:49.669836Z","end":"2026-03-31T04:48:49.811501Z","steps":["trace[1872895130] 'process raft request' (duration: 141.506736ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:48:53.468832Z","caller":"traceutil/trace.go:171","msg":"trace[955214513] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"233.267827ms","start":"2026-03-31T04:48:53.235543Z","end":"2026-03-31T04:48:53.468811Z","steps":["trace[955214513] 'process raft request' (duration: 233.115836ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:48:53.468865Z","caller":"traceutil/trace.go:171","msg":"trace[1420229103] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"233.278937ms","start":"2026-03-31T04:48:53.235542Z","end":"2026-03-31T04:48:53.46882Z","steps":["trace[1420229103] 'process raft request' (duration: 190.640015ms)","trace[1420229103] 'compare' (duration: 42.027286ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T04:48:53.468877Z","caller":"traceutil/trace.go:171","msg":"trace[255936188] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"230.793254ms","start":"2026-03-31T04:48:53.238048Z","end":"2026-03-31T04:48:53.468842Z","steps":["trace[255936188] 'process raft request' (duration: 230.677063ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:48:53.469189Z","caller":"traceutil/trace.go:171","msg":"trace[27478795] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"230.776453ms","start":"2026-03-31T04:48:53.238397Z","end":"2026-03-31T04:48:53.469174Z","steps":["trace[27478795] 'process raft request' (duration: 230.36594ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:48:53.469254Z","caller":"traceutil/trace.go:171","msg":"trace[1537506119] linearizableReadLoop","detail":"{readStateIndex:354; appliedIndex:349; }","duration":"198.924024ms","start":"2026-03-31T04:48:53.270317Z","end":"2026-03-31T04:48:53.469241Z","steps":["trace[1537506119] 'read index received' (duration: 155.754737ms)","trace[1537506119] 'applied index is now lower than readState.Index' (duration: 43.166857ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T04:48:53.469282Z","caller":"traceutil/trace.go:171","msg":"trace[712565346] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"230.050817ms","start":"2026-03-31T04:48:53.239209Z","end":"2026-03-31T04:48:53.46926Z","steps":["trace[712565346] 'process raft request' (duration: 229.748724ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:48:53.469494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.139556ms","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-03-31T04:48:53.469539Z","caller":"traceutil/trace.go:171","msg":"trace[1195530982] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:342; }","duration":"199.263047ms","start":"2026-03-31T04:48:53.270263Z","end":"2026-03-31T04:48:53.469526Z","steps":["trace[1195530982] 'agreement among raft nodes before linearized reading' (duration: 199.058765ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:48:53.469615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.035272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-03-31T04:48:53.469652Z","caller":"traceutil/trace.go:171","msg":"trace[1123762986] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:342; }","duration":"101.132703ms","start":"2026-03-31T04:48:53.368509Z","end":"2026-03-31T04:48:53.469641Z","steps":["trace[1123762986] 'agreement among raft nodes before linearized reading' (duration: 101.054972ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:48:53.469674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.904343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-03-31T04:48:53.469801Z","caller":"traceutil/trace.go:171","msg":"trace[664083394] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:342; }","duration":"149.084794ms","start":"2026-03-31T04:48:53.320702Z","end":"2026-03-31T04:48:53.469787Z","steps":["trace[664083394] 'agreement among raft nodes before linearized reading' (duration: 148.840282ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:48:53.700245Z","caller":"traceutil/trace.go:171","msg":"trace[1106330760] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"226.614725ms","start":"2026-03-31T04:48:53.473602Z","end":"2026-03-31T04:48:53.700217Z","steps":["trace[1106330760] 'process raft request' (duration: 143.513622ms)","trace[1106330760] 'compare' (duration: 82.954741ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T04:48:53.700987Z","caller":"traceutil/trace.go:171","msg":"trace[507880382] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"225.303223ms","start":"2026-03-31T04:48:53.475659Z","end":"2026-03-31T04:48:53.700962Z","steps":["trace[507880382] 'process raft request' (duration: 224.865989ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:48:53.701186Z","caller":"traceutil/trace.go:171","msg":"trace[1225841155] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"221.86594ms","start":"2026-03-31T04:48:53.479287Z","end":"2026-03-31T04:48:53.701153Z","steps":["trace[1225841155] 'process raft request' (duration: 221.571507ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:48:53.701407Z","caller":"traceutil/trace.go:171","msg":"trace[552134353] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"221.964691ms","start":"2026-03-31T04:48:53.479363Z","end":"2026-03-31T04:48:53.701327Z","steps":["trace[552134353] 'process raft request' (duration: 221.642318ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:48:53.701536Z","caller":"traceutil/trace.go:171","msg":"trace[1269727173] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"217.145876ms","start":"2026-03-31T04:48:53.484356Z","end":"2026-03-31T04:48:53.701502Z","steps":["trace[1269727173] 'process raft request' (duration: 216.791752ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:48:53.701957Z","caller":"traceutil/trace.go:171","msg":"trace[151676774] linearizableReadLoop","detail":"{readStateIndex:356; appliedIndex:354; }","duration":"224.99081ms","start":"2026-03-31T04:48:53.476909Z","end":"2026-03-31T04:48:53.701899Z","steps":["trace[151676774] 'read index received' (duration: 140.386193ms)","trace[151676774] 'applied index is now lower than readState.Index' (duration: 84.602707ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T04:48:53.702152Z","caller":"traceutil/trace.go:171","msg":"trace[1486655414] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"212.575402ms","start":"2026-03-31T04:48:53.489519Z","end":"2026-03-31T04:48:53.702094Z","steps":["trace[1486655414] 'process raft request' (duration: 211.797455ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:48:53.702279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.798805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:1 size:3475"} {"level":"info","ts":"2026-03-31T04:48:53.702426Z","caller":"traceutil/trace.go:171","msg":"trace[895445781] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:1; response_revision:348; }","duration":"212.985986ms","start":"2026-03-31T04:48:53.48942Z","end":"2026-03-31T04:48:53.702406Z","steps":["trace[895445781] 'agreement among raft nodes before linearized reading' (duration: 212.730524ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:48:53.702625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.711326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-31T04:48:53.702664Z","caller":"traceutil/trace.go:171","msg":"trace[573644777] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:348; }","duration":"225.799097ms","start":"2026-03-31T04:48:53.476856Z","end":"2026-03-31T04:48:53.702655Z","steps":["trace[573644777] 'agreement among raft nodes before linearized reading' (duration: 225.730326ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:48:53.968191Z","caller":"traceutil/trace.go:171","msg":"trace[1793882457] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"255.84533ms","start":"2026-03-31T04:48:53.712311Z","end":"2026-03-31T04:48:53.968156Z","steps":["trace[1793882457] 'process raft request' (duration: 187.85239ms)","trace[1793882457] 'compare' (duration: 67.802368ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T04:48:54.269532Z","caller":"traceutil/trace.go:171","msg":"trace[1743462057] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"553.392124ms","start":"2026-03-31T04:48:53.716112Z","end":"2026-03-31T04:48:54.269504Z","steps":["trace[1743462057] 'process raft request' (duration: 553.298233ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:48:54.269924Z","caller":"traceutil/trace.go:171","msg":"trace[162592360] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"554.337052ms","start":"2026-03-31T04:48:53.715561Z","end":"2026-03-31T04:48:54.269898Z","steps":["trace[162592360] 'process raft request' (duration: 539.241031ms)","trace[162592360] 'compare' (duration: 14.188393ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T04:48:54.270245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:48:53.716101Z","time spent":"553.683136ms","remote":"127.0.0.1:52478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4447,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T04:48:54.270429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:48:53.71553Z","time spent":"554.573755ms","remote":"127.0.0.1:52750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4002,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T04:48:54.335771Z","caller":"traceutil/trace.go:171","msg":"trace[1824576443] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"361.394421ms","start":"2026-03-31T04:48:53.974355Z","end":"2026-03-31T04:48:54.335749Z","steps":["trace[1824576443] 'process raft request' (duration: 360.993307ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:48:54.335849Z","caller":"traceutil/trace.go:171","msg":"trace[1682044240] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"357.459864ms","start":"2026-03-31T04:48:53.978376Z","end":"2026-03-31T04:48:54.335836Z","steps":["trace[1682044240] 'process raft request' (duration: 357.296762ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:48:54.335906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:48:53.974325Z","time spent":"361.516062ms","remote":"127.0.0.1:52364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T04:48:54.335755Z","caller":"traceutil/trace.go:171","msg":"trace[1474989577] linearizableReadLoop","detail":"{readStateIndex:367; appliedIndex:361; }","duration":"180.512368ms","start":"2026-03-31T04:48:54.155224Z","end":"2026-03-31T04:48:54.335737Z","steps":["trace[1474989577] 'read index received' (duration: 99.51408ms)","trace[1474989577] 'applied index is now lower than readState.Index' (duration: 80.997068ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T04:48:54.335965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:48:53.978351Z","time spent":"357.540264ms","remote":"127.0.0.1:52478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T04:48:54.335899Z","caller":"traceutil/trace.go:171","msg":"trace[994608728] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"360.462831ms","start":"2026-03-31T04:48:53.975341Z","end":"2026-03-31T04:48:54.335804Z","steps":["trace[994608728] 'process raft request' (duration: 360.27312ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:48:54.335754Z","caller":"traceutil/trace.go:171","msg":"trace[1482993146] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"361.234539ms","start":"2026-03-31T04:48:53.974479Z","end":"2026-03-31T04:48:54.335714Z","steps":["trace[1482993146] 'process raft request' (duration: 361.085667ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:48:54.336484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:48:53.975309Z","time spent":"361.069568ms","remote":"127.0.0.1:52478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T04:48:54.336567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:48:53.974442Z","time spent":"362.062617ms","remote":"127.0.0.1:52364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T04:48:54.336186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.861601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-31T04:48:54.336663Z","caller":"traceutil/trace.go:171","msg":"trace[2120672516] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:355; }","duration":"181.477276ms","start":"2026-03-31T04:48:54.155166Z","end":"2026-03-31T04:48:54.336644Z","steps":["trace[2120672516] 'agreement among raft nodes before linearized reading' (duration: 180.650888ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:48:54.451681Z","caller":"traceutil/trace.go:171","msg":"trace[2019301433] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"110.029148ms","start":"2026-03-31T04:48:54.341622Z","end":"2026-03-31T04:48:54.451651Z","steps":["trace[2019301433] 'process raft request' (duration: 109.979738ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:48:54.451768Z","caller":"traceutil/trace.go:171","msg":"trace[195856224] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"110.299361ms","start":"2026-03-31T04:48:54.341416Z","end":"2026-03-31T04:48:54.451715Z","steps":["trace[195856224] 'process raft request' (duration: 84.771202ms)","trace[195856224] 'compare' (duration: 25.210326ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T04:48:54.516709Z","caller":"traceutil/trace.go:171","msg":"trace[1615525945] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"171.935067ms","start":"2026-03-31T04:48:54.34475Z","end":"2026-03-31T04:48:54.516685Z","steps":["trace[1615525945] 'process raft request' (duration: 171.824656ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:48:54.516743Z","caller":"traceutil/trace.go:171","msg":"trace[1937081313] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"172.452913ms","start":"2026-03-31T04:48:54.344245Z","end":"2026-03-31T04:48:54.516698Z","steps":["trace[1937081313] 'process raft request' (duration: 172.19188ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:48:54.516879Z","caller":"traceutil/trace.go:171","msg":"trace[1644473313] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"171.19337ms","start":"2026-03-31T04:48:54.345654Z","end":"2026-03-31T04:48:54.516848Z","steps":["trace[1644473313] 'process raft request' (duration: 170.983128ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:48:55.269216Z","caller":"traceutil/trace.go:171","msg":"trace[1762528543] linearizableReadLoop","detail":"{readStateIndex:379; appliedIndex:378; }","duration":"155.333011ms","start":"2026-03-31T04:48:55.113862Z","end":"2026-03-31T04:48:55.269195Z","steps":["trace[1762528543] 'read index received' (duration: 155.134279ms)","trace[1762528543] 'applied index is now lower than readState.Index' (duration: 197.612µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T04:48:55.269266Z","caller":"traceutil/trace.go:171","msg":"trace[1743951782] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"221.680567ms","start":"2026-03-31T04:48:55.047548Z","end":"2026-03-31T04:48:55.269228Z","steps":["trace[1743951782] 'process raft request' (duration: 221.479085ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:48:55.269596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.716364ms","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-03-31T04:48:55.269642Z","caller":"traceutil/trace.go:171","msg":"trace[1126282242] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:367; }","duration":"155.801135ms","start":"2026-03-31T04:48:55.113829Z","end":"2026-03-31T04:48:55.26963Z","steps":["trace[1126282242] 'agreement among raft nodes before linearized reading' (duration: 155.495482ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:48:55.270215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.664553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4368"} {"level":"info","ts":"2026-03-31T04:48:55.270271Z","caller":"traceutil/trace.go:171","msg":"trace[70865652] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:367; }","duration":"128.750695ms","start":"2026-03-31T04:48:55.141506Z","end":"2026-03-31T04:48:55.270256Z","steps":["trace[70865652] 'agreement among raft nodes before linearized reading' (duration: 128.330521ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:48:55.913382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.775476ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T04:48:55.91351Z","caller":"traceutil/trace.go:171","msg":"trace[374255435] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:367; }","duration":"292.942708ms","start":"2026-03-31T04:48:55.62055Z","end":"2026-03-31T04:48:55.913493Z","steps":["trace[374255435] 'range keys from in-memory index tree' (duration: 292.748846ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:48:55.913484Z","caller":"traceutil/trace.go:171","msg":"trace[88052312] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"640.414441ms","start":"2026-03-31T04:48:55.273038Z","end":"2026-03-31T04:48:55.913452Z","steps":["trace[88052312] 'process raft request' (duration: 564.186614ms)","trace[88052312] 'compare' (duration: 76.086036ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T04:48:55.913801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:48:55.273012Z","time spent":"640.630744ms","remote":"127.0.0.1:52582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T04:48:56.309166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.119875ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T04:48:56.309464Z","caller":"traceutil/trace.go:171","msg":"trace[921193463] linearizableReadLoop","detail":"{readStateIndex:381; appliedIndex:379; }","duration":"874.163949ms","start":"2026-03-31T04:48:55.435283Z","end":"2026-03-31T04:48:56.309447Z","steps":["trace[921193463] 'read index received' (duration: 401.946399ms)","trace[921193463] 'applied index is now lower than readState.Index' (duration: 472.21663ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T04:48:56.309558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.2746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T04:48:56.309592Z","caller":"traceutil/trace.go:171","msg":"trace[1918650850] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:370; }","duration":"874.333641ms","start":"2026-03-31T04:48:55.435248Z","end":"2026-03-31T04:48:56.309582Z","steps":["trace[1918650850] 'agreement among raft nodes before linearized reading' (duration: 874.254541ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:48:56.309569Z","caller":"traceutil/trace.go:171","msg":"trace[1468916395] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"606.336535ms","start":"2026-03-31T04:48:55.703164Z","end":"2026-03-31T04:48:56.3095Z","steps":["trace[1468916395] 'process raft request' (duration: 606.226104ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:48:56.309535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:48:55.489861Z","time spent":"819.659584ms","remote":"127.0.0.1:52818","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-31T04:48:56.309826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:48:55.70313Z","time spent":"606.594667ms","remote":"127.0.0.1:52364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T04:48:56.309626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:48:55.435233Z","time spent":"874.386162ms","remote":"127.0.0.1:52292","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-03-31T04:48:56.309612Z","caller":"traceutil/trace.go:171","msg":"trace[1855881169] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"1.035679157s","start":"2026-03-31T04:48:55.273898Z","end":"2026-03-31T04:48:56.309577Z","steps":["trace[1855881169] 'process raft request' (duration: 800.019757ms)","trace[1855881169] 'compare' (duration: 234.971944ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T04:48:56.310254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:48:55.273888Z","time spent":"1.036329473s","remote":"127.0.0.1:52582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T04:48:56.724545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.080113ms","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-03-31T04:48:56.724636Z","caller":"traceutil/trace.go:171","msg":"trace[830782199] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:373; }","duration":"123.240494ms","start":"2026-03-31T04:48:56.601377Z","end":"2026-03-31T04:48:56.724618Z","steps":["trace[830782199] 'range keys from in-memory index tree' (duration: 122.950942ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:48:59.691581Z","caller":"traceutil/trace.go:171","msg":"trace[795452118] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"135.363952ms","start":"2026-03-31T04:48:59.556188Z","end":"2026-03-31T04:48:59.691552Z","steps":["trace[795452118] 'process raft request' (duration: 135.18416ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:05.380384Z","caller":"traceutil/trace.go:171","msg":"trace[1283624861] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"525.467334ms","start":"2026-03-31T04:49:04.854885Z","end":"2026-03-31T04:49:05.380352Z","steps":["trace[1283624861] 'process raft request' (duration: 525.257412ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:49:05.380589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:49:04.854866Z","time spent":"525.610705ms","remote":"127.0.0.1:52582","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-03-31T04:49:05.978349Z","caller":"traceutil/trace.go:171","msg":"trace[1391586393] linearizableReadLoop","detail":"{readStateIndex:443; appliedIndex:441; }","duration":"860.116684ms","start":"2026-03-31T04:49:05.118208Z","end":"2026-03-31T04:49:05.978325Z","steps":["trace[1391586393] 'read index received' (duration: 261.898866ms)","trace[1391586393] 'applied index is now lower than readState.Index' (duration: 598.216968ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T04:49:05.978316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.595197ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T04:49:05.978462Z","caller":"traceutil/trace.go:171","msg":"trace[322855913] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:427; }","duration":"357.787009ms","start":"2026-03-31T04:49:05.620654Z","end":"2026-03-31T04:49:05.978441Z","steps":["trace[322855913] 'range keys from in-memory index tree' (duration: 357.572267ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:05.978483Z","caller":"traceutil/trace.go:171","msg":"trace[459619943] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"1.109283186s","start":"2026-03-31T04:49:04.86919Z","end":"2026-03-31T04:49:05.978473Z","steps":["trace[459619943] 'process raft request' (duration: 1.030462961s)","trace[459619943] 'compare' (duration: 78.528573ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T04:49:05.978595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:49:04.869161Z","time spent":"1.109347507s","remote":"127.0.0.1:52582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T04:49:05.978761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.558698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T04:49:05.978798Z","caller":"traceutil/trace.go:171","msg":"trace[1073637802] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:428; }","duration":"860.643878ms","start":"2026-03-31T04:49:05.118144Z","end":"2026-03-31T04:49:05.978787Z","steps":["trace[1073637802] 'agreement among raft nodes before linearized reading' (duration: 860.572497ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:49:05.978839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:49:05.118116Z","time spent":"860.715199ms","remote":"127.0.0.1:52292","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-03-31T04:49:16.877669Z","caller":"traceutil/trace.go:171","msg":"trace[1434219146] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"105.265651ms","start":"2026-03-31T04:49:16.772365Z","end":"2026-03-31T04:49:16.877631Z","steps":["trace[1434219146] 'process raft request' (duration: 52.937497ms)","trace[1434219146] 'compare' (duration: 52.137583ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T04:49:16.877986Z","caller":"traceutil/trace.go:171","msg":"trace[1922450044] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"104.924632ms","start":"2026-03-31T04:49:16.773032Z","end":"2026-03-31T04:49:16.877957Z","steps":["trace[1922450044] 'process raft request' (duration: 104.553451ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:16.878268Z","caller":"traceutil/trace.go:171","msg":"trace[1389617633] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"104.442865ms","start":"2026-03-31T04:49:16.773764Z","end":"2026-03-31T04:49:16.878207Z","steps":["trace[1389617633] 'process raft request' (duration: 104.085556ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:16.878604Z","caller":"traceutil/trace.go:171","msg":"trace[82186819] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"103.173245ms","start":"2026-03-31T04:49:16.775391Z","end":"2026-03-31T04:49:16.878564Z","steps":["trace[82186819] 'process raft request' (duration: 102.661387ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:16.879266Z","caller":"traceutil/trace.go:171","msg":"trace[343244955] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"100.694589ms","start":"2026-03-31T04:49:16.778513Z","end":"2026-03-31T04:49:16.879207Z","steps":["trace[343244955] 'process raft request' (duration: 99.837602ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:18.546476Z","caller":"traceutil/trace.go:171","msg":"trace[193358535] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"140.980266ms","start":"2026-03-31T04:49:18.405469Z","end":"2026-03-31T04:49:18.546449Z","steps":["trace[193358535] 'process raft request' (duration: 140.892131ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:18.546545Z","caller":"traceutil/trace.go:171","msg":"trace[1171551872] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"146.119481ms","start":"2026-03-31T04:49:18.400391Z","end":"2026-03-31T04:49:18.54651Z","steps":["trace[1171551872] 'process raft request' (duration: 145.821055ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:18.546665Z","caller":"traceutil/trace.go:171","msg":"trace[2023093097] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"141.284043ms","start":"2026-03-31T04:49:18.405334Z","end":"2026-03-31T04:49:18.546618Z","steps":["trace[2023093097] 'process raft request' (duration: 140.974356ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:18.54667Z","caller":"traceutil/trace.go:171","msg":"trace[758748770] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"141.380117ms","start":"2026-03-31T04:49:18.405228Z","end":"2026-03-31T04:49:18.546608Z","steps":["trace[758748770] 'process raft request' (duration: 141.038309ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:18.546728Z","caller":"traceutil/trace.go:171","msg":"trace[301831516] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"153.236433ms","start":"2026-03-31T04:49:18.393454Z","end":"2026-03-31T04:49:18.546691Z","steps":["trace[301831516] 'process raft request' (duration: 152.605819ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:18.54723Z","caller":"traceutil/trace.go:171","msg":"trace[899530114] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"121.813169ms","start":"2026-03-31T04:49:18.425407Z","end":"2026-03-31T04:49:18.547221Z","steps":["trace[899530114] 'process raft request' (duration: 121.778977ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:18.547289Z","caller":"traceutil/trace.go:171","msg":"trace[997604092] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"138.464621ms","start":"2026-03-31T04:49:18.408813Z","end":"2026-03-31T04:49:18.547278Z","steps":["trace[997604092] 'process raft request' (duration: 137.592014ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:18.547362Z","caller":"traceutil/trace.go:171","msg":"trace[1383790552] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"132.191025ms","start":"2026-03-31T04:49:18.415165Z","end":"2026-03-31T04:49:18.547356Z","steps":["trace[1383790552] 'process raft request' (duration: 131.960603ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:18.573231Z","caller":"traceutil/trace.go:171","msg":"trace[2010579820] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"126.600655ms","start":"2026-03-31T04:49:18.446584Z","end":"2026-03-31T04:49:18.573185Z","steps":["trace[2010579820] 'process raft request' (duration: 126.284269ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:18.573249Z","caller":"traceutil/trace.go:171","msg":"trace[938850172] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"121.934435ms","start":"2026-03-31T04:49:18.451286Z","end":"2026-03-31T04:49:18.57322Z","steps":["trace[938850172] 'process raft request' (duration: 121.65341ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:18.573376Z","caller":"traceutil/trace.go:171","msg":"trace[2027263325] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"109.019093ms","start":"2026-03-31T04:49:18.464325Z","end":"2026-03-31T04:49:18.573345Z","steps":["trace[2027263325] 'process raft request' (duration: 108.714797ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:18.573678Z","caller":"traceutil/trace.go:171","msg":"trace[987401853] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"132.949545ms","start":"2026-03-31T04:49:18.440714Z","end":"2026-03-31T04:49:18.573664Z","steps":["trace[987401853] 'process raft request' (duration: 131.989434ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:18.748292Z","caller":"traceutil/trace.go:171","msg":"trace[816759781] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"189.411532ms","start":"2026-03-31T04:49:18.558833Z","end":"2026-03-31T04:49:18.748245Z","steps":["trace[816759781] 'process raft request' (duration: 113.535285ms)","trace[816759781] 'compare' (duration: 75.542859ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T04:49:18.748557Z","caller":"traceutil/trace.go:171","msg":"trace[582349083] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"189.648364ms","start":"2026-03-31T04:49:18.558885Z","end":"2026-03-31T04:49:18.748534Z","steps":["trace[582349083] 'process raft request' (duration: 189.235292ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:18.748778Z","caller":"traceutil/trace.go:171","msg":"trace[860808698] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"189.723728ms","start":"2026-03-31T04:49:18.559025Z","end":"2026-03-31T04:49:18.748749Z","steps":["trace[860808698] 'process raft request' (duration: 189.306375ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:18.748781Z","caller":"traceutil/trace.go:171","msg":"trace[775483066] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"189.821863ms","start":"2026-03-31T04:49:18.55893Z","end":"2026-03-31T04:49:18.748752Z","steps":["trace[775483066] 'process raft request' (duration: 189.290724ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:18.748982Z","caller":"traceutil/trace.go:171","msg":"trace[854653770] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"189.959751ms","start":"2026-03-31T04:49:18.559003Z","end":"2026-03-31T04:49:18.748963Z","steps":["trace[854653770] 'process raft request' (duration: 189.270614ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:18.748947Z","caller":"traceutil/trace.go:171","msg":"trace[1861328791] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"189.706617ms","start":"2026-03-31T04:49:18.559174Z","end":"2026-03-31T04:49:18.748881Z","steps":["trace[1861328791] 'process raft request' (duration: 189.295215ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:18.749139Z","caller":"traceutil/trace.go:171","msg":"trace[1196789973] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"181.719169ms","start":"2026-03-31T04:49:18.567279Z","end":"2026-03-31T04:49:18.748999Z","steps":["trace[1196789973] 'process raft request' (duration: 181.469846ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:18.749238Z","caller":"traceutil/trace.go:171","msg":"trace[1581030376] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"185.925214ms","start":"2026-03-31T04:49:18.5633Z","end":"2026-03-31T04:49:18.749226Z","steps":["trace[1581030376] 'process raft request' (duration: 185.227387ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:18.7494Z","caller":"traceutil/trace.go:171","msg":"trace[1361749406] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"186.055961ms","start":"2026-03-31T04:49:18.563324Z","end":"2026-03-31T04:49:18.74938Z","steps":["trace[1361749406] 'process raft request' (duration: 185.263559ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:18.781548Z","caller":"traceutil/trace.go:171","msg":"trace[1571737351] linearizableReadLoop","detail":"{readStateIndex:535; appliedIndex:522; }","duration":"174.795208ms","start":"2026-03-31T04:49:18.606714Z","end":"2026-03-31T04:49:18.781509Z","steps":["trace[1571737351] 'read index received' (duration: 65.618337ms)","trace[1571737351] 'applied index is now lower than readState.Index' (duration: 109.176251ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T04:49:18.781665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.932375ms","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-03-31T04:49:18.781687Z","caller":"traceutil/trace.go:171","msg":"trace[2105241485] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:516; }","duration":"175.020981ms","start":"2026-03-31T04:49:18.606659Z","end":"2026-03-31T04:49:18.78168Z","steps":["trace[2105241485] 'agreement among raft nodes before linearized reading' (duration: 174.951387ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:18.781718Z","caller":"traceutil/trace.go:171","msg":"trace[1478578050] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"205.178536ms","start":"2026-03-31T04:49:18.576514Z","end":"2026-03-31T04:49:18.781692Z","steps":["trace[1478578050] 'process raft request' (duration: 204.8766ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:18.781834Z","caller":"traceutil/trace.go:171","msg":"trace[1633876702] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"206.917009ms","start":"2026-03-31T04:49:18.574901Z","end":"2026-03-31T04:49:18.781818Z","steps":["trace[1633876702] 'process raft request' (duration: 206.285575ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:18.781902Z","caller":"traceutil/trace.go:171","msg":"trace[1384803675] transaction","detail":"{read_only:false; number_of_response:1; response_revision:515; }","duration":"196.700772ms","start":"2026-03-31T04:49:18.585195Z","end":"2026-03-31T04:49:18.781896Z","steps":["trace[1384803675] 'process raft request' (duration: 196.245988ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:18.781958Z","caller":"traceutil/trace.go:171","msg":"trace[776765338] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"196.071028ms","start":"2026-03-31T04:49:18.585882Z","end":"2026-03-31T04:49:18.781953Z","steps":["trace[776765338] 'process raft request' (duration: 195.586852ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:18.900092Z","caller":"traceutil/trace.go:171","msg":"trace[794664578] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"113.627329ms","start":"2026-03-31T04:49:18.786444Z","end":"2026-03-31T04:49:18.900071Z","steps":["trace[794664578] 'process raft request' (duration: 76.971605ms)","trace[794664578] 'compare' (duration: 36.447783ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T04:49:18.900492Z","caller":"traceutil/trace.go:171","msg":"trace[1232695535] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"103.890488ms","start":"2026-03-31T04:49:18.796569Z","end":"2026-03-31T04:49:18.90046Z","steps":["trace[1232695535] 'process raft request' (duration: 103.464605ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:18.900512Z","caller":"traceutil/trace.go:171","msg":"trace[1880755294] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"103.908879ms","start":"2026-03-31T04:49:18.796575Z","end":"2026-03-31T04:49:18.900484Z","steps":["trace[1880755294] 'process raft request' (duration: 103.778462ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:19.313785Z","caller":"traceutil/trace.go:171","msg":"trace[939505439] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"263.394517ms","start":"2026-03-31T04:49:19.050356Z","end":"2026-03-31T04:49:19.313751Z","steps":["trace[939505439] 'process raft request' (duration: 224.440644ms)","trace[939505439] 'compare' (duration: 38.589104ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T04:49:19.349126Z","caller":"traceutil/trace.go:171","msg":"trace[2114110227] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"265.293998ms","start":"2026-03-31T04:49:19.083768Z","end":"2026-03-31T04:49:19.349062Z","steps":["trace[2114110227] 'process raft request' (duration: 265.011283ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:19.349354Z","caller":"traceutil/trace.go:171","msg":"trace[1223452155] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:550; }","duration":"204.077666ms","start":"2026-03-31T04:49:19.145233Z","end":"2026-03-31T04:49:19.349311Z","steps":["trace[1223452155] 'read index received' (duration: 129.576341ms)","trace[1223452155] 'applied index is now lower than readState.Index' (duration: 74.498745ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T04:49:19.349404Z","caller":"traceutil/trace.go:171","msg":"trace[1196440548] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"194.186963ms","start":"2026-03-31T04:49:19.155156Z","end":"2026-03-31T04:49:19.349343Z","steps":["trace[1196440548] 'process raft request' (duration: 193.833334ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:49:19.349533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.268666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T04:49:19.349594Z","caller":"traceutil/trace.go:171","msg":"trace[244566437] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:534; }","duration":"204.403733ms","start":"2026-03-31T04:49:19.145172Z","end":"2026-03-31T04:49:19.349576Z","steps":["trace[244566437] 'agreement among raft nodes before linearized reading' (duration: 204.253785ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:19.507903Z","caller":"traceutil/trace.go:171","msg":"trace[1923373342] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"141.607349ms","start":"2026-03-31T04:49:19.366266Z","end":"2026-03-31T04:49:19.507874Z","steps":["trace[1923373342] 'process raft request' (duration: 92.457726ms)","trace[1923373342] 'compare' (duration: 48.955832ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T04:49:19.508388Z","caller":"traceutil/trace.go:171","msg":"trace[1676601104] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"115.285915ms","start":"2026-03-31T04:49:19.393062Z","end":"2026-03-31T04:49:19.508348Z","steps":["trace[1676601104] 'process raft request' (duration: 114.882053ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:49:19.967782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.625402ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T04:49:19.968023Z","caller":"traceutil/trace.go:171","msg":"trace[417249095] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"430.225992ms","start":"2026-03-31T04:49:19.537763Z","end":"2026-03-31T04:49:19.967989Z","steps":["trace[417249095] 'process raft request' (duration: 117.847061ms)","trace[417249095] 'compare' (duration: 311.442391ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T04:49:19.968307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:49:19.537732Z","time spent":"430.421512ms","remote":"127.0.0.1:52320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T04:49:19.968426Z","caller":"traceutil/trace.go:171","msg":"trace[651185288] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"398.076549ms","start":"2026-03-31T04:49:19.569888Z","end":"2026-03-31T04:49:19.967965Z","steps":["trace[651185288] 'process raft request' (duration: 397.987704ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:49:19.968482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:49:19.569859Z","time spent":"398.600857ms","remote":"127.0.0.1:52320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65199,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T04:49:20.246621Z","caller":"traceutil/trace.go:171","msg":"trace[1748999710] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"445.023442ms","start":"2026-03-31T04:49:19.801572Z","end":"2026-03-31T04:49:20.246596Z","steps":["trace[1748999710] 'process raft request' (duration: 437.593153ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:49:20.246817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:49:19.801533Z","time spent":"445.214762ms","remote":"127.0.0.1:52320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T04:49:20.247069Z","caller":"traceutil/trace.go:171","msg":"trace[55435393] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"243.087809ms","start":"2026-03-31T04:49:20.003952Z","end":"2026-03-31T04:49:20.247039Z","steps":["trace[55435393] 'process raft request' (duration: 242.769653ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:20.247193Z","caller":"traceutil/trace.go:171","msg":"trace[679817186] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"243.195344ms","start":"2026-03-31T04:49:20.003955Z","end":"2026-03-31T04:49:20.247151Z","steps":["trace[679817186] 'process raft request' (duration: 242.996324ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:20.247885Z","caller":"traceutil/trace.go:171","msg":"trace[1970643392] linearizableReadLoop","detail":"{readStateIndex:565; appliedIndex:562; }","duration":"140.528692ms","start":"2026-03-31T04:49:20.107339Z","end":"2026-03-31T04:49:20.247867Z","steps":["trace[1970643392] 'read index received' (duration: 131.789715ms)","trace[1970643392] 'applied index is now lower than readState.Index' (duration: 8.737997ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T04:49:20.24809Z","caller":"traceutil/trace.go:171","msg":"trace[628532607] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"128.83892ms","start":"2026-03-31T04:49:20.119239Z","end":"2026-03-31T04:49:20.248078Z","steps":["trace[628532607] 'process raft request' (duration: 127.851079ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:49:20.248105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.751354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-31T04:49:20.248161Z","caller":"traceutil/trace.go:171","msg":"trace[1638747136] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:547; }","duration":"140.87279ms","start":"2026-03-31T04:49:20.107275Z","end":"2026-03-31T04:49:20.248148Z","steps":["trace[1638747136] 'agreement among raft nodes before linearized reading' (duration: 140.655449ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:20.451982Z","caller":"traceutil/trace.go:171","msg":"trace[438918970] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"134.619453ms","start":"2026-03-31T04:49:20.317316Z","end":"2026-03-31T04:49:20.451936Z","steps":["trace[438918970] 'process raft request' (duration: 81.60885ms)","trace[438918970] 'compare' (duration: 52.847645ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T04:49:20.452503Z","caller":"traceutil/trace.go:171","msg":"trace[1846325900] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"101.808376ms","start":"2026-03-31T04:49:20.350684Z","end":"2026-03-31T04:49:20.452492Z","steps":["trace[1846325900] 'process raft request' (duration: 101.68886ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:20.452596Z","caller":"traceutil/trace.go:171","msg":"trace[181402895] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"113.030343ms","start":"2026-03-31T04:49:20.339535Z","end":"2026-03-31T04:49:20.452565Z","steps":["trace[181402895] 'process raft request' (duration: 112.761919ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:20.670942Z","caller":"traceutil/trace.go:171","msg":"trace[1169224579] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"175.753985ms","start":"2026-03-31T04:49:20.495155Z","end":"2026-03-31T04:49:20.670909Z","steps":["trace[1169224579] 'process raft request' (duration: 130.050814ms)","trace[1169224579] 'compare' (duration: 45.367544ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T04:49:20.67323Z","caller":"traceutil/trace.go:171","msg":"trace[192914760] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"105.526821ms","start":"2026-03-31T04:49:20.567657Z","end":"2026-03-31T04:49:20.673184Z","steps":["trace[192914760] 'process raft request' (duration: 104.887498ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:49:20.928112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.993451ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T04:49:20.928223Z","caller":"traceutil/trace.go:171","msg":"trace[496508015] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"175.527034ms","start":"2026-03-31T04:49:20.752676Z","end":"2026-03-31T04:49:20.928203Z","steps":["trace[496508015] 'process raft request' (duration: 45.352833ms)","trace[496508015] 'compare' (duration: 129.837534ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T04:49:20.928645Z","caller":"traceutil/trace.go:171","msg":"trace[1973230391] linearizableReadLoop","detail":"{readStateIndex:578; appliedIndex:576; }","duration":"157.356594ms","start":"2026-03-31T04:49:20.771275Z","end":"2026-03-31T04:49:20.928631Z","steps":["trace[1973230391] 'read index received' (duration: 26.766431ms)","trace[1973230391] 'applied index is now lower than readState.Index' (duration: 130.589283ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T04:49:20.928737Z","caller":"traceutil/trace.go:171","msg":"trace[256620139] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"174.993156ms","start":"2026-03-31T04:49:20.753708Z","end":"2026-03-31T04:49:20.928701Z","steps":["trace[256620139] 'process raft request' (duration: 174.749573ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:49:20.9289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.616157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:80703"} {"level":"info","ts":"2026-03-31T04:49:20.928944Z","caller":"traceutil/trace.go:171","msg":"trace[722610586] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:559; }","duration":"157.68416ms","start":"2026-03-31T04:49:20.771246Z","end":"2026-03-31T04:49:20.92893Z","steps":["trace[722610586] 'agreement among raft nodes before linearized reading' (duration: 157.525062ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:49:20.929238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.906867ms","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-03-31T04:49:20.929277Z","caller":"traceutil/trace.go:171","msg":"trace[1692572723] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:559; }","duration":"142.947559ms","start":"2026-03-31T04:49:20.78632Z","end":"2026-03-31T04:49:20.929268Z","steps":["trace[1692572723] 'agreement among raft nodes before linearized reading' (duration: 142.842183ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:21.278748Z","caller":"traceutil/trace.go:171","msg":"trace[1446187556] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"122.069301ms","start":"2026-03-31T04:49:21.15665Z","end":"2026-03-31T04:49:21.278719Z","steps":["trace[1446187556] 'process raft request' (duration: 116.854842ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:21.48311Z","caller":"traceutil/trace.go:171","msg":"trace[543603534] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"126.272128ms","start":"2026-03-31T04:49:21.356804Z","end":"2026-03-31T04:49:21.483076Z","steps":["trace[543603534] 'process raft request' (duration: 126.080048ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:49:21.649752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.884365ms","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-03-31T04:49:21.649846Z","caller":"traceutil/trace.go:171","msg":"trace[737036813] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:570; }","duration":"113.076246ms","start":"2026-03-31T04:49:21.536747Z","end":"2026-03-31T04:49:21.649823Z","steps":["trace[737036813] 'range keys from in-memory index tree' (duration: 112.485945ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:49:21.649875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.112326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:65436"} {"level":"info","ts":"2026-03-31T04:49:21.649974Z","caller":"traceutil/trace.go:171","msg":"trace[69591147] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:570; }","duration":"141.274623ms","start":"2026-03-31T04:49:21.508674Z","end":"2026-03-31T04:49:21.649948Z","steps":["trace[69591147] 'range keys from in-memory index tree' (duration: 140.776498ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:49:21.859297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.709236ms","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-03-31T04:49:21.859477Z","caller":"traceutil/trace.go:171","msg":"trace[1030393742] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoypatchpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:572; }","duration":"106.940788ms","start":"2026-03-31T04:49:21.752513Z","end":"2026-03-31T04:49:21.859454Z","steps":["trace[1030393742] 'range keys from in-memory index tree' (duration: 106.530447ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:22.17442Z","caller":"traceutil/trace.go:171","msg":"trace[1055718140] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:592; }","duration":"175.463688ms","start":"2026-03-31T04:49:21.998927Z","end":"2026-03-31T04:49:22.174391Z","steps":["trace[1055718140] 'read index received' (duration: 101.417725ms)","trace[1055718140] 'applied index is now lower than readState.Index' (duration: 74.044922ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T04:49:22.174489Z","caller":"traceutil/trace.go:171","msg":"trace[1097993157] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"234.185874ms","start":"2026-03-31T04:49:21.940286Z","end":"2026-03-31T04:49:22.174472Z","steps":["trace[1097993157] 'process raft request' (duration: 160.12742ms)","trace[1097993157] 'compare' (duration: 73.79432ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T04:49:22.174714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.756063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:15688"} {"level":"info","ts":"2026-03-31T04:49:22.174792Z","caller":"traceutil/trace.go:171","msg":"trace[1380175091] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:574; }","duration":"175.879159ms","start":"2026-03-31T04:49:21.998893Z","end":"2026-03-31T04:49:22.174773Z","steps":["trace[1380175091] 'agreement among raft nodes before linearized reading' (duration: 175.649677ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:24.141416Z","caller":"traceutil/trace.go:171","msg":"trace[1593377651] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"227.323666ms","start":"2026-03-31T04:49:23.914055Z","end":"2026-03-31T04:49:24.141379Z","steps":["trace[1593377651] 'process raft request' (duration: 226.881793ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:25.444497Z","caller":"traceutil/trace.go:171","msg":"trace[700517940] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"142.068585ms","start":"2026-03-31T04:49:25.30239Z","end":"2026-03-31T04:49:25.444459Z","steps":["trace[700517940] 'process raft request' (duration: 139.910358ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:26.134877Z","caller":"traceutil/trace.go:171","msg":"trace[1621497606] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"132.756971ms","start":"2026-03-31T04:49:26.002028Z","end":"2026-03-31T04:49:26.134785Z","steps":["trace[1621497606] 'process raft request' (duration: 106.731063ms)","trace[1621497606] 'compare' (duration: 25.734993ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T04:49:28.964495Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710537548348325201,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T04:49:29.244182Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.018094488s","expected-duration":"1s"} {"level":"info","ts":"2026-03-31T04:49:29.246129Z","caller":"traceutil/trace.go:171","msg":"trace[513275653] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"781.749624ms","start":"2026-03-31T04:49:28.46435Z","end":"2026-03-31T04:49:29.246099Z","steps":["trace[513275653] 'read index received' (duration: 780.022683ms)","trace[513275653] 'applied index is now lower than readState.Index' (duration: 1.724241ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T04:49:29.246167Z","caller":"traceutil/trace.go:171","msg":"trace[1440163091] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"1.021790982s","start":"2026-03-31T04:49:28.224354Z","end":"2026-03-31T04:49:29.246145Z","steps":["trace[1440163091] 'process raft request' (duration: 1.020686551s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:49:29.246287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:49:28.224328Z","time spent":"1.021878596s","remote":"127.0.0.1:52384","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-03-31T04:49:29.246962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.758939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T04:49:29.247152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.770443ms","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-03-31T04:49:29.247286Z","caller":"traceutil/trace.go:171","msg":"trace[1286115184] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:608; }","duration":"782.947961ms","start":"2026-03-31T04:49:28.464295Z","end":"2026-03-31T04:49:29.247243Z","steps":["trace[1286115184] 'agreement among raft nodes before linearized reading' (duration: 781.974865ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:49:29.247151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.207713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-03-31T04:49:29.247382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.914705ms","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-03-31T04:49:29.247441Z","caller":"traceutil/trace.go:171","msg":"trace[1674020713] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:608; }","duration":"179.56471ms","start":"2026-03-31T04:49:29.067776Z","end":"2026-03-31T04:49:29.247341Z","steps":["trace[1674020713] 'agreement among raft nodes before linearized reading' (duration: 178.971092ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:29.247813Z","caller":"traceutil/trace.go:171","msg":"trace[1486837530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:608; }","duration":"184.048791ms","start":"2026-03-31T04:49:29.063396Z","end":"2026-03-31T04:49:29.247445Z","steps":["trace[1486837530] 'agreement among raft nodes before linearized reading' (duration: 183.409681ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:49:29.247825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:49:28.464274Z","time spent":"783.090778ms","remote":"127.0.0.1:52582","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":"info","ts":"2026-03-31T04:49:29.247191Z","caller":"traceutil/trace.go:171","msg":"trace[1092832380] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:608; }","duration":"128.884245ms","start":"2026-03-31T04:49:29.118138Z","end":"2026-03-31T04:49:29.247022Z","steps":["trace[1092832380] 'agreement among raft nodes before linearized reading' (duration: 128.655034ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:49:29.249913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.85275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6wmvr\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-03-31T04:49:29.25017Z","caller":"traceutil/trace.go:171","msg":"trace[1825218709] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6wmvr; range_end:; response_count:1; response_revision:608; }","duration":"463.080551ms","start":"2026-03-31T04:49:28.787017Z","end":"2026-03-31T04:49:29.250097Z","steps":["trace[1825218709] 'agreement among raft nodes before linearized reading' (duration: 459.368337ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:49:29.250244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:49:28.786972Z","time spent":"463.254349ms","remote":"127.0.0.1:52478","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-6wmvr\" "} {"level":"warn","ts":"2026-03-31T04:49:29.486616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.775025ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T04:49:29.486729Z","caller":"traceutil/trace.go:171","msg":"trace[1905546196] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"229.484609ms","start":"2026-03-31T04:49:29.257225Z","end":"2026-03-31T04:49:29.486709Z","steps":["trace[1905546196] 'process raft request' (duration: 54.518465ms)","trace[1905546196] 'compare' (duration: 174.631398ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T04:49:29.931438Z","caller":"traceutil/trace.go:171","msg":"trace[515542128] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"674.019679ms","start":"2026-03-31T04:49:29.257387Z","end":"2026-03-31T04:49:29.931407Z","steps":["trace[515542128] 'process raft request' (duration: 607.260268ms)","trace[515542128] 'compare' (duration: 66.508939ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T04:49:29.931613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:49:29.25736Z","time spent":"674.156245ms","remote":"127.0.0.1:52582","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-03-31T04:49:29.932131Z","caller":"traceutil/trace.go:171","msg":"trace[1655988733] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"674.638718ms","start":"2026-03-31T04:49:29.257466Z","end":"2026-03-31T04:49:29.932105Z","steps":["trace[1655988733] 'process raft request' (duration: 673.889223ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:49:29.937443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:49:29.257461Z","time spent":"679.884524ms","remote":"127.0.0.1:52582","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-03-31T04:49:29.937778Z","caller":"traceutil/trace.go:171","msg":"trace[2035208972] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:630; }","duration":"147.038889ms","start":"2026-03-31T04:49:29.790726Z","end":"2026-03-31T04:49:29.937764Z","steps":["trace[2035208972] 'read index received' (duration: 73.850965ms)","trace[2035208972] 'applied index is now lower than readState.Index' (duration: 73.187074ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T04:49:29.937858Z","caller":"traceutil/trace.go:171","msg":"trace[157999120] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"663.017901ms","start":"2026-03-31T04:49:29.274807Z","end":"2026-03-31T04:49:29.937825Z","steps":["trace[157999120] 'process raft request' (duration: 662.831822ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:29.937904Z","caller":"traceutil/trace.go:171","msg":"trace[47670154] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"635.104407ms","start":"2026-03-31T04:49:29.302775Z","end":"2026-03-31T04:49:29.937879Z","steps":["trace[47670154] 'process raft request' (duration: 634.94835ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:49:29.937972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.245839ms","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-03-31T04:49:29.938007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:49:29.302745Z","time spent":"635.208282ms","remote":"127.0.0.1:52542","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-03-31T04:49:29.938018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:49:29.274774Z","time spent":"663.166457ms","remote":"127.0.0.1:52478","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-03-31T04:49:29.938018Z","caller":"traceutil/trace.go:171","msg":"trace[453558591] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:613; }","duration":"147.331323ms","start":"2026-03-31T04:49:29.790674Z","end":"2026-03-31T04:49:29.938005Z","steps":["trace[453558591] 'agreement among raft nodes before linearized reading' (duration: 147.147845ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:49:31.348765Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.321856966s","expected-duration":"1s"} {"level":"info","ts":"2026-03-31T04:49:31.349169Z","caller":"traceutil/trace.go:171","msg":"trace[453632346] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"1.322367869s","start":"2026-03-31T04:49:30.026776Z","end":"2026-03-31T04:49:31.349144Z","steps":["trace[453632346] 'process raft request' (duration: 1.322243013s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:49:31.349283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:49:30.026745Z","time spent":"1.322482905s","remote":"127.0.0.1:52364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T04:49:31.356186Z","caller":"traceutil/trace.go:171","msg":"trace[1725936068] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"1.328403227s","start":"2026-03-31T04:49:30.027761Z","end":"2026-03-31T04:49:31.356165Z","steps":["trace[1725936068] 'process raft request' (duration: 1.328191077s)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:31.356233Z","caller":"traceutil/trace.go:171","msg":"trace[134009835] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"1.327029352s","start":"2026-03-31T04:49:30.02919Z","end":"2026-03-31T04:49:31.356219Z","steps":["trace[134009835] 'process raft request' (duration: 1.326890916s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:49:31.356307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:49:30.029171Z","time spent":"1.327089815s","remote":"127.0.0.1:52542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T04:49:31.356304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:49:30.027735Z","time spent":"1.328502581s","remote":"127.0.0.1:52364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T04:49:31.356323Z","caller":"traceutil/trace.go:171","msg":"trace[230426296] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:637; }","duration":"238.225256ms","start":"2026-03-31T04:49:31.118024Z","end":"2026-03-31T04:49:31.35625Z","steps":["trace[230426296] 'read index received' (duration: 231.58165ms)","trace[230426296] 'applied index is now lower than readState.Index' (duration: 6.642476ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T04:49:31.356554Z","caller":"traceutil/trace.go:171","msg":"trace[1178509650] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"1.326679976s","start":"2026-03-31T04:49:30.029864Z","end":"2026-03-31T04:49:31.356544Z","steps":["trace[1178509650] 'process raft request' (duration: 1.326303259s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:49:31.356564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.521169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T04:49:31.3566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:49:30.029833Z","time spent":"1.326739358s","remote":"127.0.0.1:52478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T04:49:31.356618Z","caller":"traceutil/trace.go:171","msg":"trace[94415407] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:620; }","duration":"238.622574ms","start":"2026-03-31T04:49:31.11798Z","end":"2026-03-31T04:49:31.356603Z","steps":["trace[94415407] 'agreement among raft nodes before linearized reading' (duration: 238.442176ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:31.356637Z","caller":"traceutil/trace.go:171","msg":"trace[519231504] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"717.650345ms","start":"2026-03-31T04:49:30.638978Z","end":"2026-03-31T04:49:31.356629Z","steps":["trace[519231504] 'process raft request' (duration: 717.225095ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:49:31.356698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:49:30.638949Z","time spent":"717.720058ms","remote":"127.0.0.1:52582","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-03-31T04:49:33.239046Z","caller":"traceutil/trace.go:171","msg":"trace[1966029932] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:669; }","duration":"121.657158ms","start":"2026-03-31T04:49:33.117361Z","end":"2026-03-31T04:49:33.239019Z","steps":["trace[1966029932] 'read index received' (duration: 121.423888ms)","trace[1966029932] 'applied index is now lower than readState.Index' (duration: 231.97µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T04:49:33.239129Z","caller":"traceutil/trace.go:171","msg":"trace[124847634] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"161.823894ms","start":"2026-03-31T04:49:33.077258Z","end":"2026-03-31T04:49:33.239082Z","steps":["trace[124847634] 'process raft request' (duration: 161.601304ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:49:33.239243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.863327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T04:49:33.23928Z","caller":"traceutil/trace.go:171","msg":"trace[1281739851] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"121.941351ms","start":"2026-03-31T04:49:33.117327Z","end":"2026-03-31T04:49:33.239268Z","steps":["trace[1281739851] 'agreement among raft nodes before linearized reading' (duration: 121.840206ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:49:36.144106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.466877ms","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-03-31T04:49:36.144185Z","caller":"traceutil/trace.go:171","msg":"trace[959254790] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:687; }","duration":"118.596373ms","start":"2026-03-31T04:49:36.025571Z","end":"2026-03-31T04:49:36.144167Z","steps":["trace[959254790] 'range keys from in-memory index tree' (duration: 118.318961ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:49:36.144167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.27008ms","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-03-31T04:49:36.144252Z","caller":"traceutil/trace.go:171","msg":"trace[1175726368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:687; }","duration":"121.402425ms","start":"2026-03-31T04:49:36.022827Z","end":"2026-03-31T04:49:36.144229Z","steps":["trace[1175726368] 'range keys from in-memory index tree' (duration: 121.100632ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:49:36.144299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.309721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-31T04:49:36.144344Z","caller":"traceutil/trace.go:171","msg":"trace[1538152209] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:687; }","duration":"121.394465ms","start":"2026-03-31T04:49:36.022936Z","end":"2026-03-31T04:49:36.144331Z","steps":["trace[1538152209] 'range keys from in-memory index tree' (duration: 121.184666ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:49:46.760793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.055723ms","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-03-31T04:49:46.760885Z","caller":"traceutil/trace.go:171","msg":"trace[370161818] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:765; }","duration":"566.202219ms","start":"2026-03-31T04:49:46.194664Z","end":"2026-03-31T04:49:46.760866Z","steps":["trace[370161818] 'range keys from in-memory index tree' (duration: 565.846655ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:49:46.760929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:49:46.194641Z","time spent":"566.278631ms","remote":"127.0.0.1:52582","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-03-31T04:49:46.761142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.586814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-31T04:49:46.76122Z","caller":"traceutil/trace.go:171","msg":"trace[1856335972] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:765; }","duration":"565.73636ms","start":"2026-03-31T04:49:46.195467Z","end":"2026-03-31T04:49:46.761203Z","steps":["trace[1856335972] 'range keys from in-memory index tree' (duration: 565.474061ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:49:46.761256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:49:46.195455Z","time spent":"565.791803ms","remote":"127.0.0.1:52582","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-03-31T04:49:46.761292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.98578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T04:49:46.76135Z","caller":"traceutil/trace.go:171","msg":"trace[837203711] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:765; }","duration":"642.095994ms","start":"2026-03-31T04:49:46.119239Z","end":"2026-03-31T04:49:46.761335Z","steps":["trace[837203711] 'range keys from in-memory index tree' (duration: 633.76834ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:49:46.761366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.156388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T04:49:46.76139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:49:46.119217Z","time spent":"642.161497ms","remote":"127.0.0.1:52292","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-03-31T04:49:46.76142Z","caller":"traceutil/trace.go:171","msg":"trace[1554019716] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:765; }","duration":"234.253422ms","start":"2026-03-31T04:49:46.527151Z","end":"2026-03-31T04:49:46.761404Z","steps":["trace[1554019716] 'count revisions from in-memory index tree' (duration: 234.054065ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:49:46.761903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"875.658857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-x828t\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-03-31T04:49:46.761952Z","caller":"traceutil/trace.go:171","msg":"trace[839608178] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-x828t; range_end:; response_count:1; response_revision:765; }","duration":"875.74067ms","start":"2026-03-31T04:49:45.886196Z","end":"2026-03-31T04:49:46.761937Z","steps":["trace[839608178] 'range keys from in-memory index tree' (duration: 874.927718ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:49:46.761993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:49:45.88618Z","time spent":"875.800803ms","remote":"127.0.0.1:52478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-x828t\" "} {"level":"warn","ts":"2026-03-31T04:49:46.762572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.739686ms","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-03-31T04:49:46.762654Z","caller":"traceutil/trace.go:171","msg":"trace[1448015050] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:765; }","duration":"569.869481ms","start":"2026-03-31T04:49:46.192768Z","end":"2026-03-31T04:49:46.762637Z","steps":["trace[1448015050] 'range keys from in-memory index tree' (duration: 568.590801ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:49:46.762703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:49:46.192745Z","time spent":"569.942404ms","remote":"127.0.0.1:52582","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-03-31T04:49:46.885636Z","caller":"traceutil/trace.go:171","msg":"trace[487602229] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"119.610848ms","start":"2026-03-31T04:49:46.766002Z","end":"2026-03-31T04:49:46.885612Z","steps":["trace[487602229] 'process raft request' (duration: 119.374779ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:47.153791Z","caller":"traceutil/trace.go:171","msg":"trace[920556721] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"385.493499ms","start":"2026-03-31T04:49:46.767858Z","end":"2026-03-31T04:49:47.153351Z","steps":["trace[920556721] 'process raft request' (duration: 385.00191ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:49:47.154011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:49:46.767843Z","time spent":"386.04684ms","remote":"127.0.0.1:52582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T04:49:47.154037Z","caller":"traceutil/trace.go:171","msg":"trace[187192370] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"384.312612ms","start":"2026-03-31T04:49:46.769699Z","end":"2026-03-31T04:49:47.154012Z","steps":["trace[187192370] 'process raft request' (duration: 383.663177ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:49:47.154457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:49:46.769684Z","time spent":"384.658616ms","remote":"127.0.0.1:52582","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-03-31T04:49:47.154086Z","caller":"traceutil/trace.go:171","msg":"trace[1675105425] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"286.736042ms","start":"2026-03-31T04:49:46.867323Z","end":"2026-03-31T04:49:47.154059Z","steps":["trace[1675105425] 'process raft request' (duration: 286.641919ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:49:47.15496Z","caller":"traceutil/trace.go:171","msg":"trace[1687276396] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"385.27497ms","start":"2026-03-31T04:49:46.769655Z","end":"2026-03-31T04:49:47.15493Z","steps":["trace[1687276396] 'process raft request' (duration: 383.637127ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:49:47.155278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:49:46.769645Z","time spent":"385.587832ms","remote":"127.0.0.1:52478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4024,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T04:49:47.153973Z","caller":"traceutil/trace.go:171","msg":"trace[935826535] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"384.396336ms","start":"2026-03-31T04:49:46.769546Z","end":"2026-03-31T04:49:47.153942Z","steps":["trace[935826535] 'process raft request' (duration: 383.663658ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:49:47.160392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:49:46.769532Z","time spent":"390.785353ms","remote":"127.0.0.1:52582","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-03-31T04:49:59.764661Z","caller":"traceutil/trace.go:171","msg":"trace[798916771] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"127.954336ms","start":"2026-03-31T04:49:59.636683Z","end":"2026-03-31T04:49:59.764637Z","steps":["trace[798916771] 'process raft request' (duration: 127.76596ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:51:40.385638Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710537548348326831,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-31T04:51:40.412852Z","caller":"traceutil/trace.go:171","msg":"trace[146468429] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"558.28494ms","start":"2026-03-31T04:51:39.854501Z","end":"2026-03-31T04:51:40.412786Z","steps":["trace[146468429] 'process raft request' (duration: 557.983205ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:51:40.413247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:51:39.854478Z","time spent":"558.444733ms","remote":"127.0.0.1:52582","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-03-31T04:51:40.530545Z","caller":"traceutil/trace.go:171","msg":"trace[1255494130] linearizableReadLoop","detail":"{readStateIndex:1366; appliedIndex:1363; }","duration":"645.581372ms","start":"2026-03-31T04:51:39.88493Z","end":"2026-03-31T04:51:40.530511Z","steps":["trace[1255494130] 'read index received' (duration: 527.485218ms)","trace[1255494130] 'applied index is now lower than readState.Index' (duration: 118.095234ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T04:51:40.530601Z","caller":"traceutil/trace.go:171","msg":"trace[1195287436] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"645.852587ms","start":"2026-03-31T04:51:39.884714Z","end":"2026-03-31T04:51:40.530566Z","steps":["trace[1195287436] 'process raft request' (duration: 645.733055ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:51:40.530827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:51:39.884699Z","time spent":"646.04973ms","remote":"127.0.0.1:52582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T04:51:40.53063Z","caller":"traceutil/trace.go:171","msg":"trace[2126702484] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"675.214476ms","start":"2026-03-31T04:51:39.855371Z","end":"2026-03-31T04:51:40.530586Z","steps":["trace[2126702484] 'process raft request' (duration: 601.864826ms)","trace[2126702484] 'compare' (duration: 73.048424ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T04:51:40.531028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.072448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T04:51:40.531016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.676477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T04:51:40.531074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.723331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T04:51:40.531101Z","caller":"traceutil/trace.go:171","msg":"trace[957522094] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1314; }","duration":"210.19308ms","start":"2026-03-31T04:51:40.320891Z","end":"2026-03-31T04:51:40.531084Z","steps":["trace[957522094] 'agreement among raft nodes before linearized reading' (duration: 210.057718ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T04:51:40.531114Z","caller":"traceutil/trace.go:171","msg":"trace[833276043] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1314; }","duration":"598.921211ms","start":"2026-03-31T04:51:39.932174Z","end":"2026-03-31T04:51:40.531095Z","steps":["trace[833276043] 'agreement among raft nodes before linearized reading' (duration: 598.669527ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:51:40.531048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:51:39.855361Z","time spent":"675.615962ms","remote":"127.0.0.1:52582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T04:51:40.531136Z","caller":"traceutil/trace.go:171","msg":"trace[203850033] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1314; }","duration":"412.814702ms","start":"2026-03-31T04:51:40.118303Z","end":"2026-03-31T04:51:40.531118Z","steps":["trace[203850033] 'agreement among raft nodes before linearized reading' (duration: 412.6972ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:51:40.531186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:51:39.932151Z","time spent":"599.015742ms","remote":"127.0.0.1:52504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-03-31T04:51:40.531202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:51:40.118288Z","time spent":"412.897585ms","remote":"127.0.0.1:52292","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-03-31T04:51:40.531292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.218522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-31T04:51:40.53137Z","caller":"traceutil/trace.go:171","msg":"trace[2074932763] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1314; }","duration":"229.318123ms","start":"2026-03-31T04:51:40.302034Z","end":"2026-03-31T04:51:40.531352Z","steps":["trace[2074932763] 'agreement among raft nodes before linearized reading' (duration: 228.824775ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:51:40.531511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.57825ms","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-03-31T04:51:40.53158Z","caller":"traceutil/trace.go:171","msg":"trace[738872920] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1314; }","duration":"646.668801ms","start":"2026-03-31T04:51:39.88487Z","end":"2026-03-31T04:51:40.531539Z","steps":["trace[738872920] 'agreement among raft nodes before linearized reading' (duration: 645.811286ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:51:40.53162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:51:39.88484Z","time spent":"646.773383ms","remote":"127.0.0.1:52582","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-03-31T04:52:19.683724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.843546ms","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-03-31T04:52:19.683824Z","caller":"traceutil/trace.go:171","msg":"trace[149496625] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1438; }","duration":"462.964227ms","start":"2026-03-31T04:52:19.220839Z","end":"2026-03-31T04:52:19.683804Z","steps":["trace[149496625] 'range keys from in-memory index tree' (duration: 462.740424ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:52:19.683882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:52:19.220836Z","time spent":"463.027848ms","remote":"127.0.0.1:52582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-31T04:52:19.68398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.047069ms","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-03-31T04:52:19.684037Z","caller":"traceutil/trace.go:171","msg":"trace[702630455] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1438; }","duration":"463.116309ms","start":"2026-03-31T04:52:19.220908Z","end":"2026-03-31T04:52:19.684024Z","steps":["trace[702630455] 'range keys from in-memory index tree' (duration: 462.928597ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:52:19.684077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:52:19.220906Z","time spent":"463.16208ms","remote":"127.0.0.1:52582","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-03-31T04:52:19.684319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.400471291s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.44\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-31T04:52:19.68437Z","caller":"traceutil/trace.go:171","msg":"trace[171453893] range","detail":"{range_begin:/registry/masterleases/199.204.45.44; range_end:; response_count:1; response_revision:1438; }","duration":"1.400547501s","start":"2026-03-31T04:52:18.283808Z","end":"2026-03-31T04:52:19.684355Z","steps":["trace[171453893] 'range keys from in-memory index tree' (duration: 1.400366679s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:52:19.684407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:52:18.283793Z","time spent":"1.400604582s","remote":"127.0.0.1:52304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.44\" "} {"level":"warn","ts":"2026-03-31T04:52:19.684575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.398844566s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T04:52:19.684618Z","caller":"traceutil/trace.go:171","msg":"trace[592862558] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1438; }","duration":"1.398896507s","start":"2026-03-31T04:52:18.28571Z","end":"2026-03-31T04:52:19.684607Z","steps":["trace[592862558] 'range keys from in-memory index tree' (duration: 1.398813445s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:52:19.684651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:52:18.285705Z","time spent":"1.398937688s","remote":"127.0.0.1:52292","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-03-31T04:52:19.684915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.022763ms","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-03-31T04:52:19.684979Z","caller":"traceutil/trace.go:171","msg":"trace[599322421] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1438; }","duration":"464.091225ms","start":"2026-03-31T04:52:19.220876Z","end":"2026-03-31T04:52:19.684967Z","steps":["trace[599322421] 'range keys from in-memory index tree' (duration: 463.963002ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:52:19.685142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.167025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-31T04:52:19.685142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:52:19.220873Z","time spent":"464.257737ms","remote":"127.0.0.1:52582","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-03-31T04:52:19.685151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.536891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T04:52:19.685255Z","caller":"traceutil/trace.go:171","msg":"trace[1618957168] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1438; }","duration":"464.681842ms","start":"2026-03-31T04:52:19.220551Z","end":"2026-03-31T04:52:19.685233Z","steps":["trace[1618957168] 'count revisions from in-memory index tree' (duration: 464.448139ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:52:19.685322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:52:19.220539Z","time spent":"464.768625ms","remote":"127.0.0.1:52376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"info","ts":"2026-03-31T04:52:19.6852Z","caller":"traceutil/trace.go:171","msg":"trace[1862823124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1438; }","duration":"464.260917ms","start":"2026-03-31T04:52:19.220928Z","end":"2026-03-31T04:52:19.685189Z","steps":["trace[1862823124] 'range keys from in-memory index tree' (duration: 464.014502ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:52:19.685675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:52:19.220911Z","time spent":"464.751484ms","remote":"127.0.0.1:52582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-31T04:52:19.685271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.304267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-31T04:52:19.685856Z","caller":"traceutil/trace.go:171","msg":"trace[195949835] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1438; }","duration":"464.890436ms","start":"2026-03-31T04:52:19.220953Z","end":"2026-03-31T04:52:19.685844Z","steps":["trace[195949835] 'range keys from in-memory index tree' (duration: 464.206965ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:52:19.685905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:52:19.220949Z","time spent":"464.941686ms","remote":"127.0.0.1:52582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-31T04:52:19.686119Z","caller":"traceutil/trace.go:171","msg":"trace[953052479] transaction","detail":"{read_only:false; response_revision:1439; number_of_response:1; }","duration":"464.640622ms","start":"2026-03-31T04:52:19.221462Z","end":"2026-03-31T04:52:19.686103Z","steps":["trace[953052479] 'process raft request' (duration: 462.210416ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T04:52:19.686253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T04:52:19.221453Z","time spent":"464.707093ms","remote":"127.0.0.1:52582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"}