{"level":"warn","ts":"2026-03-25T21:04:09.581667Z","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-25T21:04:09.581811Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.210: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.210:2380","--initial-cluster=instance=https://199.204.45.210:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.210:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.210: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-25T21:04:09.581874Z","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-25T21:04:09.581889Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-03-25T21:04:09.581936Z","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-25T21:04:09.582605Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"]} {"level":"info","ts":"2026-03-25T21:04:09.582675Z","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.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.210: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-25T21:04:09.771779Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"188.825203ms"} {"level":"info","ts":"2026-03-25T21:04:09.832185Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1cac722af8b2b8a6","cluster-id":"ba0a336ca61ee41d"} {"level":"info","ts":"2026-03-25T21:04:09.832314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=()"} {"level":"info","ts":"2026-03-25T21:04:09.832356Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 0"} {"level":"info","ts":"2026-03-25T21:04:09.832388Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1cac722af8b2b8a6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-25T21:04:09.832415Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 1"} {"level":"info","ts":"2026-03-25T21:04:09.832485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"warn","ts":"2026-03-25T21:04:09.975298Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-25T21:04:09.982953Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-25T21:04:09.984871Z","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-25T21:04:09.989245Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1cac722af8b2b8a6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-25T21:04:09.989572Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1cac722af8b2b8a6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-25T21:04:09.990174Z","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-25T21:04:09.99045Z","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-25T21:04:09.990494Z","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-25T21:04:09.993402Z","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-25T21:04:09.993671Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1cac722af8b2b8a6","initial-advertise-peer-urls":["https://199.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-25T21:04:09.993704Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-25T21:04:09.993874Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-03-25T21:04:09.993928Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-03-25T21:04:10.018038Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"info","ts":"2026-03-25T21:04:10.018192Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","added-peer-id":"1cac722af8b2b8a6","added-peer-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-03-25T21:04:10.033145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 is starting a new election at term 1"} {"level":"info","ts":"2026-03-25T21:04:10.033194Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-25T21:04:10.033215Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgPreVoteResp from 1cac722af8b2b8a6 at term 1"} {"level":"info","ts":"2026-03-25T21:04:10.033296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became candidate at term 2"} {"level":"info","ts":"2026-03-25T21:04:10.033317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgVoteResp from 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-03-25T21:04:10.03338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became leader at term 2"} {"level":"info","ts":"2026-03-25T21:04:10.033402Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1cac722af8b2b8a6 elected leader 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-03-25T21:04:10.128179Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T21:04:10.168624Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1cac722af8b2b8a6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.210:2379]}","request-path":"/0/members/1cac722af8b2b8a6/attributes","cluster-id":"ba0a336ca61ee41d","publish-timeout":"7s"} {"level":"info","ts":"2026-03-25T21:04:10.168636Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-25T21:04:10.168715Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-25T21:04:10.169016Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-25T21:04:10.16906Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-25T21:04:10.170754Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T21:04:10.171027Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T21:04:10.171051Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T21:04:10.172595Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-25T21:04:10.173857Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.210:2379"} {"level":"info","ts":"2026-03-25T21:04:11.995451Z","caller":"traceutil/trace.go:171","msg":"trace[747603003] linearizableReadLoop","detail":"{readStateIndex:32; appliedIndex:31; }","duration":"125.584816ms","start":"2026-03-25T21:04:11.86983Z","end":"2026-03-25T21:04:11.995415Z","steps":["trace[747603003] 'read index received' (duration: 40.693019ms)","trace[747603003] 'applied index is now lower than readState.Index' (duration: 84.890467ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:04:11.995608Z","caller":"traceutil/trace.go:171","msg":"trace[2116787423] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"136.249482ms","start":"2026-03-25T21:04:11.859312Z","end":"2026-03-25T21:04:11.995562Z","steps":["trace[2116787423] 'process raft request' (duration: 51.278661ms)","trace[2116787423] 'compare' (duration: 84.608876ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:04:11.995737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.853899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-node-lease/\" range_end:\"/registry/resourcequotas/kube-node-lease0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-25T21:04:11.99581Z","caller":"traceutil/trace.go:171","msg":"trace[1445354075] range","detail":"{range_begin:/registry/resourcequotas/kube-node-lease/; range_end:/registry/resourcequotas/kube-node-lease0; response_count:0; response_revision:30; }","duration":"125.998024ms","start":"2026-03-25T21:04:11.869798Z","end":"2026-03-25T21:04:11.995796Z","steps":["trace[1445354075] 'agreement among raft nodes before linearized reading' (duration: 125.761324ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:04:11.995813Z","caller":"traceutil/trace.go:171","msg":"trace[323413172] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"125.585697ms","start":"2026-03-25T21:04:11.8702Z","end":"2026-03-25T21:04:11.995786Z","steps":["trace[323413172] 'process raft request' (duration: 125.293555ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:04:11.995948Z","caller":"traceutil/trace.go:171","msg":"trace[170129206] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"125.623079ms","start":"2026-03-25T21:04:11.870272Z","end":"2026-03-25T21:04:11.995895Z","steps":["trace[170129206] 'process raft request' (duration: 125.339397ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:04:11.996002Z","caller":"traceutil/trace.go:171","msg":"trace[958372653] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"124.51007ms","start":"2026-03-25T21:04:11.871469Z","end":"2026-03-25T21:04:11.995979Z","steps":["trace[958372653] 'process raft request' (duration: 124.458038ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:04:11.996141Z","caller":"traceutil/trace.go:171","msg":"trace[556925597] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"125.687721ms","start":"2026-03-25T21:04:11.870408Z","end":"2026-03-25T21:04:11.996096Z","steps":["trace[556925597] 'process raft request' (duration: 125.488493ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:04:11.996038Z","caller":"traceutil/trace.go:171","msg":"trace[2083285412] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"125.641729ms","start":"2026-03-25T21:04:11.870386Z","end":"2026-03-25T21:04:11.996027Z","steps":["trace[2083285412] 'process raft request' (duration: 125.42391ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:04:12.332115Z","caller":"traceutil/trace.go:171","msg":"trace[626394461] transaction","detail":"{read_only:false; response_revision:45; number_of_response:1; }","duration":"116.287648ms","start":"2026-03-25T21:04:12.215793Z","end":"2026-03-25T21:04:12.332081Z","steps":["trace[626394461] 'process raft request' (duration: 46.970261ms)","trace[626394461] 'compare' (duration: 69.075177ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:04:12.332856Z","caller":"traceutil/trace.go:171","msg":"trace[1603897974] linearizableReadLoop","detail":"{readStateIndex:49; appliedIndex:47; }","duration":"102.068266ms","start":"2026-03-25T21:04:12.230762Z","end":"2026-03-25T21:04:12.33283Z","steps":["trace[1603897974] 'read index received' (duration: 31.996326ms)","trace[1603897974] 'applied index is now lower than readState.Index' (duration: 70.07107ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:04:12.333066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.276385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-25T21:04:12.333105Z","caller":"traceutil/trace.go:171","msg":"trace[2010903653] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:46; }","duration":"102.359328ms","start":"2026-03-25T21:04:12.230736Z","end":"2026-03-25T21:04:12.333096Z","steps":["trace[2010903653] 'agreement among raft nodes before linearized reading' (duration: 102.198882ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:04:12.333259Z","caller":"traceutil/trace.go:171","msg":"trace[546856004] transaction","detail":"{read_only:false; response_revision:46; number_of_response:1; }","duration":"109.68591ms","start":"2026-03-25T21:04:12.223554Z","end":"2026-03-25T21:04:12.333239Z","steps":["trace[546856004] 'process raft request' (duration: 109.171628ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:12.592287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.341277ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-03-25T21:04:12.592508Z","caller":"traceutil/trace.go:171","msg":"trace[802223071] transaction","detail":"{read_only:false; response_revision:50; number_of_response:1; }","duration":"210.103375ms","start":"2026-03-25T21:04:12.382379Z","end":"2026-03-25T21:04:12.592482Z","steps":["trace[802223071] 'process raft request' (duration: 52.689044ms)","trace[802223071] 'compare' (duration: 156.217201ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:04:12.592549Z","caller":"traceutil/trace.go:171","msg":"trace[507173365] linearizableReadLoop","detail":"{readStateIndex:54; appliedIndex:52; }","duration":"128.917343ms","start":"2026-03-25T21:04:12.463615Z","end":"2026-03-25T21:04:12.592532Z","steps":["trace[507173365] 'read index received' (duration: 48.260665ms)","trace[507173365] 'applied index is now lower than readState.Index' (duration: 80.648458ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:04:12.592821Z","caller":"traceutil/trace.go:171","msg":"trace[1048817087] transaction","detail":"{read_only:false; response_revision:51; number_of_response:1; }","duration":"183.710026ms","start":"2026-03-25T21:04:12.409097Z","end":"2026-03-25T21:04:12.592807Z","steps":["trace[1048817087] 'process raft request' (duration: 183.32947ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:12.593061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.438966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-25T21:04:12.593218Z","caller":"traceutil/trace.go:171","msg":"trace[1945723350] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:51; }","duration":"129.635243ms","start":"2026-03-25T21:04:12.463559Z","end":"2026-03-25T21:04:12.593195Z","steps":["trace[1945723350] 'agreement among raft nodes before linearized reading' (duration: 129.465146ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:04:12.871306Z","caller":"traceutil/trace.go:171","msg":"trace[792818737] transaction","detail":"{read_only:false; response_revision:56; number_of_response:1; }","duration":"249.552767ms","start":"2026-03-25T21:04:12.621341Z","end":"2026-03-25T21:04:12.870894Z","steps":["trace[792818737] 'process raft request' (duration: 166.776659ms)","trace[792818737] 'compare' (duration: 82.619252ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:04:12.87167Z","caller":"traceutil/trace.go:171","msg":"trace[1504503999] linearizableReadLoop","detail":"{readStateIndex:59; appliedIndex:58; }","duration":"192.586103ms","start":"2026-03-25T21:04:12.679031Z","end":"2026-03-25T21:04:12.871617Z","steps":["trace[1504503999] 'read index received' (duration: 109.217329ms)","trace[1504503999] 'applied index is now lower than readState.Index' (duration: 83.115023ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:04:12.875662Z","caller":"traceutil/trace.go:171","msg":"trace[672632481] transaction","detail":"{read_only:false; response_revision:57; number_of_response:1; }","duration":"192.368134ms","start":"2026-03-25T21:04:12.68326Z","end":"2026-03-25T21:04:12.875628Z","steps":["trace[672632481] 'process raft request' (duration: 187.604852ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:12.87575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.642626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-cluster-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-25T21:04:12.875806Z","caller":"traceutil/trace.go:171","msg":"trace[389589276] range","detail":"{range_begin:/registry/priorityclasses/system-cluster-critical; range_end:; response_count:0; response_revision:57; }","duration":"255.74235ms","start":"2026-03-25T21:04:12.620051Z","end":"2026-03-25T21:04:12.875793Z","steps":["trace[389589276] 'agreement among raft nodes before linearized reading' (duration: 251.847285ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:12.876045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.56487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-25T21:04:12.876076Z","caller":"traceutil/trace.go:171","msg":"trace[250881705] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:59; }","duration":"195.613362ms","start":"2026-03-25T21:04:12.680456Z","end":"2026-03-25T21:04:12.876069Z","steps":["trace[250881705] 'agreement among raft nodes before linearized reading' (duration: 195.538179ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:04:12.87611Z","caller":"traceutil/trace.go:171","msg":"trace[295796305] transaction","detail":"{read_only:false; response_revision:59; number_of_response:1; }","duration":"136.565589ms","start":"2026-03-25T21:04:12.739532Z","end":"2026-03-25T21:04:12.876097Z","steps":["trace[295796305] 'process raft request' (duration: 136.422433ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:12.876201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.372443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-25T21:04:12.876239Z","caller":"traceutil/trace.go:171","msg":"trace[351321167] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:59; }","duration":"183.448666ms","start":"2026-03-25T21:04:12.69278Z","end":"2026-03-25T21:04:12.876228Z","steps":["trace[351321167] 'agreement among raft nodes before linearized reading' (duration: 183.399994ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:04:12.876263Z","caller":"traceutil/trace.go:171","msg":"trace[45490288] transaction","detail":"{read_only:false; response_revision:58; number_of_response:1; }","duration":"192.464529ms","start":"2026-03-25T21:04:12.683788Z","end":"2026-03-25T21:04:12.876252Z","steps":["trace[45490288] 'process raft request' (duration: 191.827002ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:12.876381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.831399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-25T21:04:12.876416Z","caller":"traceutil/trace.go:171","msg":"trace[2106037885] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:59; }","duration":"194.87593ms","start":"2026-03-25T21:04:12.681532Z","end":"2026-03-25T21:04:12.876408Z","steps":["trace[2106037885] 'agreement among raft nodes before linearized reading' (duration: 194.826258ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:12.876539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.167983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-25T21:04:12.876662Z","caller":"traceutil/trace.go:171","msg":"trace[1707450068] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:59; }","duration":"195.3433ms","start":"2026-03-25T21:04:12.681298Z","end":"2026-03-25T21:04:12.876641Z","steps":["trace[1707450068] 'agreement among raft nodes before linearized reading' (duration: 195.178243ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:04:13.099115Z","caller":"traceutil/trace.go:171","msg":"trace[156881124] transaction","detail":"{read_only:false; response_revision:65; number_of_response:1; }","duration":"183.838211ms","start":"2026-03-25T21:04:12.915236Z","end":"2026-03-25T21:04:13.099075Z","steps":["trace[156881124] 'process raft request' (duration: 153.205523ms)","trace[156881124] 'compare' (duration: 30.354507ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:04:13.099132Z","caller":"traceutil/trace.go:171","msg":"trace[1283455936] transaction","detail":"{read_only:false; response_revision:66; number_of_response:1; }","duration":"139.540303ms","start":"2026-03-25T21:04:12.959558Z","end":"2026-03-25T21:04:13.099098Z","steps":["trace[1283455936] 'process raft request' (duration: 139.412358ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:13.099542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.776872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-25T21:04:13.099626Z","caller":"traceutil/trace.go:171","msg":"trace[393101109] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:66; }","duration":"151.895988ms","start":"2026-03-25T21:04:12.947711Z","end":"2026-03-25T21:04:13.099607Z","steps":["trace[393101109] 'agreement among raft nodes before linearized reading' (duration: 151.696869ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:04:13.099468Z","caller":"traceutil/trace.go:171","msg":"trace[1830510331] linearizableReadLoop","detail":"{readStateIndex:68; appliedIndex:67; }","duration":"143.443839ms","start":"2026-03-25T21:04:12.955857Z","end":"2026-03-25T21:04:13.099301Z","steps":["trace[1830510331] 'read index received' (duration: 112.715657ms)","trace[1830510331] 'applied index is now lower than readState.Index' (duration: 30.726572ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:04:13.100266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.817052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-25T21:04:13.10039Z","caller":"traceutil/trace.go:171","msg":"trace[1259039970] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:66; }","duration":"142.959158ms","start":"2026-03-25T21:04:12.957397Z","end":"2026-03-25T21:04:13.100356Z","steps":["trace[1259039970] 'agreement among raft nodes before linearized reading' (duration: 142.778811ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:13.100282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.426716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-25T21:04:13.1015Z","caller":"traceutil/trace.go:171","msg":"trace[1246926698] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:66; }","duration":"139.648787ms","start":"2026-03-25T21:04:12.961807Z","end":"2026-03-25T21:04:13.101456Z","steps":["trace[1246926698] 'agreement among raft nodes before linearized reading' (duration: 138.403135ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:13.100294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.639997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-03-25T21:04:13.101606Z","caller":"traceutil/trace.go:171","msg":"trace[178087329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:66; }","duration":"140.953803ms","start":"2026-03-25T21:04:12.960633Z","end":"2026-03-25T21:04:13.101587Z","steps":["trace[178087329] 'agreement among raft nodes before linearized reading' (duration: 139.613747ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:04:13.416194Z","caller":"traceutil/trace.go:171","msg":"trace[256864003] transaction","detail":"{read_only:false; response_revision:79; number_of_response:1; }","duration":"103.158537ms","start":"2026-03-25T21:04:13.313004Z","end":"2026-03-25T21:04:13.416163Z","steps":["trace[256864003] 'process raft request' (duration: 28.220086ms)","trace[256864003] 'compare' (duration: 74.257423ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:04:13.602418Z","caller":"traceutil/trace.go:171","msg":"trace[1987228845] transaction","detail":"{read_only:false; response_revision:82; number_of_response:1; }","duration":"141.949009ms","start":"2026-03-25T21:04:13.460436Z","end":"2026-03-25T21:04:13.602385Z","steps":["trace[1987228845] 'process raft request' (duration: 83.343684ms)","trace[1987228845] 'compare' (duration: 58.47775ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:04:13.772282Z","caller":"traceutil/trace.go:171","msg":"trace[300958064] transaction","detail":"{read_only:false; response_revision:84; number_of_response:1; }","duration":"115.035966ms","start":"2026-03-25T21:04:13.657204Z","end":"2026-03-25T21:04:13.77224Z","steps":["trace[300958064] 'process raft request' (duration: 74.487012ms)","trace[300958064] 'compare' (duration: 40.293864ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:04:13.990965Z","caller":"traceutil/trace.go:171","msg":"trace[1003011986] transaction","detail":"{read_only:false; response_revision:87; number_of_response:1; }","duration":"121.118632ms","start":"2026-03-25T21:04:13.869814Z","end":"2026-03-25T21:04:13.990933Z","steps":["trace[1003011986] 'process raft request' (duration: 28.650185ms)","trace[1003011986] 'compare' (duration: 92.306071ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:04:14.186424Z","caller":"traceutil/trace.go:171","msg":"trace[1218054622] transaction","detail":"{read_only:false; response_revision:92; number_of_response:1; }","duration":"103.024157ms","start":"2026-03-25T21:04:14.083381Z","end":"2026-03-25T21:04:14.186406Z","steps":["trace[1218054622] 'process raft request' (duration: 21.301119ms)","trace[1218054622] 'compare' (duration: 81.636935ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:04:29.423993Z","caller":"traceutil/trace.go:171","msg":"trace[947207707] transaction","detail":"{read_only:false; number_of_response:1; response_revision:390; }","duration":"124.579502ms","start":"2026-03-25T21:04:29.299387Z","end":"2026-03-25T21:04:29.423967Z","steps":["trace[947207707] 'process raft request' (duration: 124.396676ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:04:29.424709Z","caller":"traceutil/trace.go:171","msg":"trace[2136142244] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"125.206476ms","start":"2026-03-25T21:04:29.298712Z","end":"2026-03-25T21:04:29.423918Z","steps":["trace[2136142244] 'process raft request' (duration: 125.023119ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:04:29.425158Z","caller":"traceutil/trace.go:171","msg":"trace[1111889572] transaction","detail":"{read_only:false; number_of_response:1; response_revision:389; }","duration":"129.815737ms","start":"2026-03-25T21:04:29.295323Z","end":"2026-03-25T21:04:29.425139Z","steps":["trace[1111889572] 'process raft request' (duration: 83.138148ms)","trace[1111889572] 'compare' (duration: 45.164883ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:04:29.665897Z","caller":"traceutil/trace.go:171","msg":"trace[37256465] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"203.821347ms","start":"2026-03-25T21:04:29.462052Z","end":"2026-03-25T21:04:29.665873Z","steps":["trace[37256465] 'process raft request' (duration: 203.62197ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:04:29.666007Z","caller":"traceutil/trace.go:171","msg":"trace[1446002252] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"204.023705ms","start":"2026-03-25T21:04:29.461944Z","end":"2026-03-25T21:04:29.665968Z","steps":["trace[1446002252] 'process raft request' (duration: 203.685992ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:04:29.666042Z","caller":"traceutil/trace.go:171","msg":"trace[1639558049] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"202.800249ms","start":"2026-03-25T21:04:29.463213Z","end":"2026-03-25T21:04:29.666013Z","steps":["trace[1639558049] 'process raft request' (duration: 202.498198ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:04:29.665924Z","caller":"traceutil/trace.go:171","msg":"trace[1160630641] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"204.203801ms","start":"2026-03-25T21:04:29.461699Z","end":"2026-03-25T21:04:29.665903Z","steps":["trace[1160630641] 'process raft request' (duration: 144.927442ms)","trace[1160630641] 'compare' (duration: 58.877204ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:04:29.666554Z","caller":"traceutil/trace.go:171","msg":"trace[1686948533] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"198.679705ms","start":"2026-03-25T21:04:29.467164Z","end":"2026-03-25T21:04:29.665844Z","steps":["trace[1686948533] 'process raft request' (duration: 198.612203ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:04:29.821474Z","caller":"traceutil/trace.go:171","msg":"trace[525945672] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"149.249793ms","start":"2026-03-25T21:04:29.672203Z","end":"2026-03-25T21:04:29.821453Z","steps":["trace[525945672] 'process raft request' (duration: 110.620713ms)","trace[525945672] 'compare' (duration: 38.546207ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:04:29.824559Z","caller":"traceutil/trace.go:171","msg":"trace[1304101886] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"149.915237ms","start":"2026-03-25T21:04:29.674609Z","end":"2026-03-25T21:04:29.824524Z","steps":["trace[1304101886] 'process raft request' (duration: 149.786533ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:30.133349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.097054ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T21:04:30.133483Z","caller":"traceutil/trace.go:171","msg":"trace[2007942107] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"301.972872ms","start":"2026-03-25T21:04:29.831489Z","end":"2026-03-25T21:04:30.133462Z","steps":["trace[2007942107] 'process raft request' (duration: 123.653499ms)","trace[2007942107] 'compare' (duration: 177.97726ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:04:30.133551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:04:29.831466Z","time spent":"302.053825ms","remote":"127.0.0.1:33190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:04:30.897132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.450418ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T21:04:30.897396Z","caller":"traceutil/trace.go:171","msg":"trace[1157183967] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"1.065595315s","start":"2026-03-25T21:04:29.831779Z","end":"2026-03-25T21:04:30.897375Z","steps":["trace[1157183967] 'process raft request' (duration: 445.841717ms)","trace[1157183967] 'compare' (duration: 619.338913ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:04:30.89744Z","caller":"traceutil/trace.go:171","msg":"trace[1658019934] linearizableReadLoop","detail":"{readStateIndex:419; appliedIndex:417; }","duration":"1.015521259s","start":"2026-03-25T21:04:29.881901Z","end":"2026-03-25T21:04:30.897422Z","steps":["trace[1658019934] 'read index received' (duration: 73.254251ms)","trace[1658019934] 'applied index is now lower than readState.Index' (duration: 942.265538ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:04:30.897458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:04:29.831769Z","time spent":"1.065665477s","remote":"127.0.0.1:32912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6950,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:04:30.898993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.935791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-25T21:04:30.899006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.017098477s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:04:30.89903Z","caller":"traceutil/trace.go:171","msg":"trace[1007386256] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:406; }","duration":"598.013844ms","start":"2026-03-25T21:04:30.301007Z","end":"2026-03-25T21:04:30.899021Z","steps":["trace[1007386256] 'agreement among raft nodes before linearized reading' (duration: 597.953182ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:30.899049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:04:30.300981Z","time spent":"598.062896ms","remote":"127.0.0.1:33004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-25T21:04:30.89905Z","caller":"traceutil/trace.go:171","msg":"trace[379743409] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:406; }","duration":"1.017184931s","start":"2026-03-25T21:04:29.881852Z","end":"2026-03-25T21:04:30.899037Z","steps":["trace[379743409] 'agreement among raft nodes before linearized reading' (duration: 1.015682375s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:30.89908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:04:29.881832Z","time spent":"1.017240782s","remote":"127.0.0.1:60980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-25T21:04:36.947572Z","caller":"traceutil/trace.go:171","msg":"trace[765187596] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"210.366044ms","start":"2026-03-25T21:04:36.737169Z","end":"2026-03-25T21:04:36.947535Z","steps":["trace[765187596] 'process raft request' (duration: 209.645349ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:04:37.571265Z","caller":"traceutil/trace.go:171","msg":"trace[1334391432] linearizableReadLoop","detail":"{readStateIndex:440; appliedIndex:439; }","duration":"108.353375ms","start":"2026-03-25T21:04:37.462844Z","end":"2026-03-25T21:04:37.571197Z","steps":["trace[1334391432] 'read index received' (duration: 107.494815ms)","trace[1334391432] 'applied index is now lower than readState.Index' (duration: 857.33µs)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:04:37.571385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.534361ms","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-25T21:04:37.571434Z","caller":"traceutil/trace.go:171","msg":"trace[2021664971] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:425; }","duration":"108.618234ms","start":"2026-03-25T21:04:37.462788Z","end":"2026-03-25T21:04:37.571406Z","steps":["trace[2021664971] 'agreement among raft nodes before linearized reading' (duration: 108.522681ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:04:37.571303Z","caller":"traceutil/trace.go:171","msg":"trace[1914162764] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"112.062116ms","start":"2026-03-25T21:04:37.459208Z","end":"2026-03-25T21:04:37.57127Z","steps":["trace[1914162764] 'process raft request' (duration: 111.241857ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:04:41.137582Z","caller":"traceutil/trace.go:171","msg":"trace[1223696155] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"151.185334ms","start":"2026-03-25T21:04:40.98635Z","end":"2026-03-25T21:04:41.137536Z","steps":["trace[1223696155] 'process raft request' (duration: 150.971027ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:04:41.808041Z","caller":"traceutil/trace.go:171","msg":"trace[488369865] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"665.891388ms","start":"2026-03-25T21:04:41.142113Z","end":"2026-03-25T21:04:41.808004Z","steps":["trace[488369865] 'process raft request' (duration: 660.318627ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:41.808299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:04:41.142086Z","time spent":"666.070475ms","remote":"127.0.0.1:33004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:04:41.808905Z","caller":"traceutil/trace.go:171","msg":"trace[372896725] linearizableReadLoop","detail":"{readStateIndex:460; appliedIndex:457; }","duration":"327.821308ms","start":"2026-03-25T21:04:41.481053Z","end":"2026-03-25T21:04:41.808875Z","steps":["trace[372896725] 'read index received' (duration: 321.460589ms)","trace[372896725] 'applied index is now lower than readState.Index' (duration: 6.359659ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:04:41.808982Z","caller":"traceutil/trace.go:171","msg":"trace[238778051] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"663.194975ms","start":"2026-03-25T21:04:41.14576Z","end":"2026-03-25T21:04:41.808955Z","steps":["trace[238778051] 'process raft request' (duration: 662.739979ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:04:41.809033Z","caller":"traceutil/trace.go:171","msg":"trace[1242972933] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"662.149349ms","start":"2026-03-25T21:04:41.146871Z","end":"2026-03-25T21:04:41.809021Z","steps":["trace[1242972933] 'process raft request' (duration: 661.959403ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:41.809116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:04:41.145742Z","time spent":"663.31173ms","remote":"127.0.0.1:33190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3827,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T21:04:41.809136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:04:41.146855Z","time spent":"662.216662ms","remote":"127.0.0.1:33240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T21:04:41.809313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.10176ms","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-25T21:04:41.809344Z","caller":"traceutil/trace.go:171","msg":"trace[611350781] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:444; }","duration":"224.175692ms","start":"2026-03-25T21:04:41.585161Z","end":"2026-03-25T21:04:41.809337Z","steps":["trace[611350781] 'agreement among raft nodes before linearized reading' (duration: 224.09956ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:41.809458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.408528ms","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-25T21:04:41.809482Z","caller":"traceutil/trace.go:171","msg":"trace[775684589] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:444; }","duration":"328.46857ms","start":"2026-03-25T21:04:41.481006Z","end":"2026-03-25T21:04:41.809475Z","steps":["trace[775684589] 'agreement among raft nodes before linearized reading' (duration: 328.430079ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:41.809502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:04:41.48098Z","time spent":"328.517552ms","remote":"127.0.0.1:33004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-25T21:04:41.809522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.292368ms","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-25T21:04:41.809562Z","caller":"traceutil/trace.go:171","msg":"trace[1828283233] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:444; }","duration":"188.372621ms","start":"2026-03-25T21:04:41.621177Z","end":"2026-03-25T21:04:41.80955Z","steps":["trace[1828283233] 'agreement among raft nodes before linearized reading' (duration: 188.290068ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:04:44.83249Z","caller":"traceutil/trace.go:171","msg":"trace[263662478] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"230.482433ms","start":"2026-03-25T21:04:44.601923Z","end":"2026-03-25T21:04:44.832406Z","steps":["trace[263662478] 'process raft request' (duration: 230.306767ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:04:44.841693Z","caller":"traceutil/trace.go:171","msg":"trace[1110956328] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"223.87737ms","start":"2026-03-25T21:04:44.617798Z","end":"2026-03-25T21:04:44.841675Z","steps":["trace[1110956328] 'process raft request' (duration: 223.028321ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:04:44.841834Z","caller":"traceutil/trace.go:171","msg":"trace[696255080] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"168.684256ms","start":"2026-03-25T21:04:44.673145Z","end":"2026-03-25T21:04:44.841829Z","steps":["trace[696255080] 'process raft request' (duration: 167.962192ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:45.54087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.471205ms","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-25T21:04:45.541136Z","caller":"traceutil/trace.go:171","msg":"trace[338826053] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:570; }","duration":"247.759424ms","start":"2026-03-25T21:04:45.293358Z","end":"2026-03-25T21:04:45.541117Z","steps":["trace[338826053] 'range keys from in-memory index tree' (duration: 247.432144ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:04:45.547254Z","caller":"traceutil/trace.go:171","msg":"trace[1592427904] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"492.038283ms","start":"2026-03-25T21:04:45.055164Z","end":"2026-03-25T21:04:45.547203Z","steps":["trace[1592427904] 'process raft request' (duration: 489.805308ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:04:45.5471Z","caller":"traceutil/trace.go:171","msg":"trace[441164959] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:591; }","duration":"478.446187ms","start":"2026-03-25T21:04:45.068436Z","end":"2026-03-25T21:04:45.546882Z","steps":["trace[441164959] 'read index received' (duration: 475.949193ms)","trace[441164959] 'applied index is now lower than readState.Index' (duration: 2.495554ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:04:45.547533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:04:45.055139Z","time spent":"492.24701ms","remote":"127.0.0.1:32774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:04:45.548991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.549467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:04:45.549208Z","caller":"traceutil/trace.go:171","msg":"trace[1564335814] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:571; }","duration":"480.678861ms","start":"2026-03-25T21:04:45.068383Z","end":"2026-03-25T21:04:45.549062Z","steps":["trace[1564335814] 'agreement among raft nodes before linearized reading' (duration: 478.586531ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:45.549376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:04:45.068368Z","time spent":"480.906469ms","remote":"127.0.0.1:32774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"info","ts":"2026-03-25T21:04:45.550761Z","caller":"traceutil/trace.go:171","msg":"trace[1423955912] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"446.568887ms","start":"2026-03-25T21:04:45.10406Z","end":"2026-03-25T21:04:45.550629Z","steps":["trace[1423955912] 'process raft request' (duration: 446.184184ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:45.550985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:04:45.104031Z","time spent":"446.765843ms","remote":"127.0.0.1:32774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-25T21:04:45.551835Z","caller":"traceutil/trace.go:171","msg":"trace[1559060713] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"280.515764ms","start":"2026-03-25T21:04:45.270118Z","end":"2026-03-25T21:04:45.550634Z","steps":["trace[1559060713] 'process raft request' (duration: 280.420591ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:52.388911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.16839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-03-25T21:04:52.389031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.560199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-25T21:04:52.389067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.817513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:04:52.389112Z","caller":"traceutil/trace.go:171","msg":"trace[155459797] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"466.881654ms","start":"2026-03-25T21:04:51.922214Z","end":"2026-03-25T21:04:52.389096Z","steps":["trace[155459797] 'range keys from in-memory index tree' (duration: 466.762161ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:04:52.389137Z","caller":"traceutil/trace.go:171","msg":"trace[2084270831] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:638; }","duration":"468.661192ms","start":"2026-03-25T21:04:51.920423Z","end":"2026-03-25T21:04:52.389085Z","steps":["trace[2084270831] 'range keys from in-memory index tree' (duration: 468.436165ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:52.389169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:04:51.922189Z","time spent":"466.968337ms","remote":"127.0.0.1:60976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-25T21:04:52.389181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:04:51.920402Z","time spent":"468.767585ms","remote":"127.0.0.1:33004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-25T21:04:52.389131Z","caller":"traceutil/trace.go:171","msg":"trace[1046214495] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:638; }","duration":"283.386908ms","start":"2026-03-25T21:04:52.105672Z","end":"2026-03-25T21:04:52.389059Z","steps":["trace[1046214495] 'range keys from in-memory index tree' (duration: 282.976875ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:52.389243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.726952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-z8jqc\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-25T21:04:52.389354Z","caller":"traceutil/trace.go:171","msg":"trace[906850326] range","detail":"{range_begin:/registry/pods/kube-system/cilium-z8jqc; range_end:; response_count:1; response_revision:638; }","duration":"752.838946ms","start":"2026-03-25T21:04:51.636492Z","end":"2026-03-25T21:04:52.389331Z","steps":["trace[906850326] 'range keys from in-memory index tree' (duration: 752.547527ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:52.389423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:04:51.636435Z","time spent":"752.96796ms","remote":"127.0.0.1:32912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-z8jqc\" "} {"level":"warn","ts":"2026-03-25T21:04:52.38898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.325984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-25T21:04:52.389243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.848761ms","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-25T21:04:52.389636Z","caller":"traceutil/trace.go:171","msg":"trace[670021373] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:638; }","duration":"466.239023ms","start":"2026-03-25T21:04:51.923354Z","end":"2026-03-25T21:04:52.389593Z","steps":["trace[670021373] 'range keys from in-memory index tree' (duration: 465.703327ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:52.389781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:04:51.923351Z","time spent":"466.388569ms","remote":"127.0.0.1:33004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-25T21:04:52.389539Z","caller":"traceutil/trace.go:171","msg":"trace[1912622231] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:638; }","duration":"465.892253ms","start":"2026-03-25T21:04:51.923624Z","end":"2026-03-25T21:04:52.389516Z","steps":["trace[1912622231] 'range keys from in-memory index tree' (duration: 465.218641ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:52.39004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:04:51.92362Z","time spent":"466.407509ms","remote":"127.0.0.1:33004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-25T21:04:52.390358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.031549ms","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-25T21:04:52.390446Z","caller":"traceutil/trace.go:171","msg":"trace[1180025895] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:638; }","duration":"467.125362ms","start":"2026-03-25T21:04:51.923307Z","end":"2026-03-25T21:04:52.390432Z","steps":["trace[1180025895] 'range keys from in-memory index tree' (duration: 465.19822ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:52.39053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:04:51.923301Z","time spent":"467.216905ms","remote":"127.0.0.1:33004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-25T21:04:52.697591Z","caller":"traceutil/trace.go:171","msg":"trace[91746746] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"302.820694ms","start":"2026-03-25T21:04:52.39475Z","end":"2026-03-25T21:04:52.697571Z","steps":["trace[91746746] 'process raft request' (duration: 302.788043ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:52.697714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:04:52.394722Z","time spent":"302.925247ms","remote":"127.0.0.1:33004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:04:52.697738Z","caller":"traceutil/trace.go:171","msg":"trace[430805002] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"302.938867ms","start":"2026-03-25T21:04:52.394754Z","end":"2026-03-25T21:04:52.697693Z","steps":["trace[430805002] 'process raft request' (duration: 302.661078ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:52.697999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:04:52.394721Z","time spent":"303.130363ms","remote":"127.0.0.1:33004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:04:52.889557Z","caller":"traceutil/trace.go:171","msg":"trace[1402769478] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"493.885768ms","start":"2026-03-25T21:04:52.395644Z","end":"2026-03-25T21:04:52.889529Z","steps":["trace[1402769478] 'process raft request' (duration: 433.833164ms)","trace[1402769478] 'compare' (duration: 59.782285ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:04:52.889858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:04:52.395593Z","time spent":"494.124375ms","remote":"127.0.0.1:33004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:04:52.889948Z","caller":"traceutil/trace.go:171","msg":"trace[1473198234] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"490.368075ms","start":"2026-03-25T21:04:52.399558Z","end":"2026-03-25T21:04:52.889927Z","steps":["trace[1473198234] 'process raft request' (duration: 489.886499ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:52.890079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:04:52.399536Z","time spent":"490.482647ms","remote":"127.0.0.1:33004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:04:52.890482Z","caller":"traceutil/trace.go:171","msg":"trace[1686214624] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"490.753447ms","start":"2026-03-25T21:04:52.399707Z","end":"2026-03-25T21:04:52.890461Z","steps":["trace[1686214624] 'process raft request' (duration: 490.149367ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:52.890588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:04:52.399698Z","time spent":"490.831029ms","remote":"127.0.0.1:33004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:04:52.890472Z","caller":"traceutil/trace.go:171","msg":"trace[232683401] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"482.985166ms","start":"2026-03-25T21:04:52.407466Z","end":"2026-03-25T21:04:52.890451Z","steps":["trace[232683401] 'process raft request' (duration: 482.586484ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:52.890871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:04:52.407445Z","time spent":"483.354529ms","remote":"127.0.0.1:32912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:04:54.991481Z","caller":"traceutil/trace.go:171","msg":"trace[682645558] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"286.753403ms","start":"2026-03-25T21:04:54.704703Z","end":"2026-03-25T21:04:54.991457Z","steps":["trace[682645558] 'process raft request' (duration: 286.565227ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:55.735693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.882138ms","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-25T21:04:55.735843Z","caller":"traceutil/trace.go:171","msg":"trace[569572002] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:646; }","duration":"443.041222ms","start":"2026-03-25T21:04:55.292779Z","end":"2026-03-25T21:04:55.73582Z","steps":["trace[569572002] 'range keys from in-memory index tree' (duration: 442.864337ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:04:55.770844Z","caller":"traceutil/trace.go:171","msg":"trace[1126169717] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:667; }","duration":"967.894041ms","start":"2026-03-25T21:04:54.802925Z","end":"2026-03-25T21:04:55.770819Z","steps":["trace[1126169717] 'read index received' (duration: 188.193883ms)","trace[1126169717] 'applied index is now lower than readState.Index' (duration: 779.698838ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:04:55.770968Z","caller":"traceutil/trace.go:171","msg":"trace[1685450747] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"1.06550236s","start":"2026-03-25T21:04:54.705456Z","end":"2026-03-25T21:04:55.770958Z","steps":["trace[1685450747] 'process raft request' (duration: 1.06516778s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:55.771097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:04:54.705435Z","time spent":"1.065561641s","remote":"127.0.0.1:33004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:04:55.77118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"875.897832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-03-25T21:04:55.771248Z","caller":"traceutil/trace.go:171","msg":"trace[760298992] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:647; }","duration":"876.006815ms","start":"2026-03-25T21:04:54.895221Z","end":"2026-03-25T21:04:55.771228Z","steps":["trace[760298992] 'agreement among raft nodes before linearized reading' (duration: 875.837169ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:55.771304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:04:54.895203Z","time spent":"876.088317ms","remote":"127.0.0.1:33004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-25T21:04:55.771476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"968.556622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.210\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-25T21:04:55.771504Z","caller":"traceutil/trace.go:171","msg":"trace[1477225980] range","detail":"{range_begin:/registry/masterleases/199.204.45.210; range_end:; response_count:1; response_revision:647; }","duration":"968.621414ms","start":"2026-03-25T21:04:54.802874Z","end":"2026-03-25T21:04:55.771496Z","steps":["trace[1477225980] 'agreement among raft nodes before linearized reading' (duration: 968.543112ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:55.771535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:04:54.802854Z","time spent":"968.676266ms","remote":"127.0.0.1:60998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.210\" "} {"level":"warn","ts":"2026-03-25T21:04:55.771784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"876.4904ms","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-25T21:04:55.771847Z","caller":"traceutil/trace.go:171","msg":"trace[209568404] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:647; }","duration":"876.584333ms","start":"2026-03-25T21:04:54.895247Z","end":"2026-03-25T21:04:55.771831Z","steps":["trace[209568404] 'agreement among raft nodes before linearized reading' (duration: 876.414818ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:55.77189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:04:54.895228Z","time spent":"876.651575ms","remote":"127.0.0.1:33004","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-25T21:04:55.771927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"876.413368ms","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-25T21:04:55.771968Z","caller":"traceutil/trace.go:171","msg":"trace[1671398190] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:647; }","duration":"876.471559ms","start":"2026-03-25T21:04:54.895486Z","end":"2026-03-25T21:04:55.771958Z","steps":["trace[1671398190] 'agreement among raft nodes before linearized reading' (duration: 876.344325ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:55.77201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:04:54.895479Z","time spent":"876.523081ms","remote":"127.0.0.1:33004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-25T21:05:01.32527Z","caller":"traceutil/trace.go:171","msg":"trace[1531440181] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"305.724189ms","start":"2026-03-25T21:05:01.019271Z","end":"2026-03-25T21:05:01.324995Z","steps":["trace[1531440181] 'process raft request' (duration: 305.10983ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:01.325411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:01.019247Z","time spent":"306.09216ms","remote":"127.0.0.1:33004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:05:03.099425Z","caller":"traceutil/trace.go:171","msg":"trace[28803301] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"184.30752ms","start":"2026-03-25T21:05:02.91509Z","end":"2026-03-25T21:05:03.099398Z","steps":["trace[28803301] 'process raft request' (duration: 184.094033ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:19.380172Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305494939134357173,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T21:05:19.801631Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.027285406s","expected-duration":"1s"} {"level":"info","ts":"2026-03-25T21:05:19.80202Z","caller":"traceutil/trace.go:171","msg":"trace[1608342073] linearizableReadLoop","detail":"{readStateIndex:812; appliedIndex:811; }","duration":"921.964884ms","start":"2026-03-25T21:05:18.880034Z","end":"2026-03-25T21:05:19.801999Z","steps":["trace[1608342073] 'read index received' (duration: 921.791919ms)","trace[1608342073] 'applied index is now lower than readState.Index' (duration: 172.105µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:05:19.802145Z","caller":"traceutil/trace.go:171","msg":"trace[1315794432] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"1.027899374s","start":"2026-03-25T21:05:18.774234Z","end":"2026-03-25T21:05:19.802133Z","steps":["trace[1315794432] 'process raft request' (duration: 1.027643746s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:19.802254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"889.761752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:05:19.802327Z","caller":"traceutil/trace.go:171","msg":"trace[1978371107] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:783; }","duration":"889.881706ms","start":"2026-03-25T21:05:18.912428Z","end":"2026-03-25T21:05:19.80231Z","steps":["trace[1978371107] 'agreement among raft nodes before linearized reading' (duration: 889.735161ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:19.802384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:18.912412Z","time spent":"889.954988ms","remote":"127.0.0.1:33204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-03-25T21:05:19.802424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.267938ms","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-25T21:05:19.802492Z","caller":"traceutil/trace.go:171","msg":"trace[1747506927] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:783; }","duration":"260.37038ms","start":"2026-03-25T21:05:19.542105Z","end":"2026-03-25T21:05:19.802475Z","steps":["trace[1747506927] 'agreement among raft nodes before linearized reading' (duration: 260.206606ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:19.802571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:18.774212Z","time spent":"1.028046827s","remote":"127.0.0.1:32912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:05:19.80227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"922.221961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:05:19.80284Z","caller":"traceutil/trace.go:171","msg":"trace[179265679] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:783; }","duration":"922.827518ms","start":"2026-03-25T21:05:18.879996Z","end":"2026-03-25T21:05:19.802823Z","steps":["trace[179265679] 'agreement among raft nodes before linearized reading' (duration: 922.221591ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:19.80289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:18.879979Z","time spent":"922.897381ms","remote":"127.0.0.1:60980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-25T21:05:34.106857Z","caller":"traceutil/trace.go:171","msg":"trace[1628497491] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"198.732175ms","start":"2026-03-25T21:05:33.908102Z","end":"2026-03-25T21:05:34.106834Z","steps":["trace[1628497491] 'process raft request' (duration: 198.572741ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:17.247039Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305494939134358497,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-25T21:07:17.340744Z","caller":"traceutil/trace.go:171","msg":"trace[60668806] linearizableReadLoop","detail":"{readStateIndex:1392; appliedIndex:1391; }","duration":"594.525839ms","start":"2026-03-25T21:07:16.746197Z","end":"2026-03-25T21:07:17.340722Z","steps":["trace[60668806] 'read index received' (duration: 594.282114ms)","trace[60668806] 'applied index is now lower than readState.Index' (duration: 242.755µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:07:17.34086Z","caller":"traceutil/trace.go:171","msg":"trace[972327467] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"812.034464ms","start":"2026-03-25T21:07:16.528805Z","end":"2026-03-25T21:07:17.340839Z","steps":["trace[972327467] 'process raft request' (duration: 811.768038ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:17.340986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:16.528792Z","time spent":"812.132556ms","remote":"127.0.0.1:33004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:07:17.341037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.813214ms","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-25T21:07:17.341076Z","caller":"traceutil/trace.go:171","msg":"trace[48308552] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1336; }","duration":"361.880606ms","start":"2026-03-25T21:07:16.979186Z","end":"2026-03-25T21:07:17.341066Z","steps":["trace[48308552] 'agreement among raft nodes before linearized reading' (duration: 361.761393ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:17.341111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:16.979173Z","time spent":"361.932157ms","remote":"127.0.0.1:33004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-25T21:07:17.341127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.244397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:07:17.341154Z","caller":"traceutil/trace.go:171","msg":"trace[847093280] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1336; }","duration":"463.280267ms","start":"2026-03-25T21:07:16.877864Z","end":"2026-03-25T21:07:17.341145Z","steps":["trace[847093280] 'agreement among raft nodes before linearized reading' (duration: 463.236246ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:17.341159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.945899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-25T21:07:17.341244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.826714ms","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-25T21:07:17.341249Z","caller":"traceutil/trace.go:171","msg":"trace[99028268] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1336; }","duration":"512.074641ms","start":"2026-03-25T21:07:16.829155Z","end":"2026-03-25T21:07:17.34123Z","steps":["trace[99028268] 'agreement among raft nodes before linearized reading' (duration: 511.806225ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:17.341243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.52719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-25T21:07:17.341277Z","caller":"traceutil/trace.go:171","msg":"trace[1155087896] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1336; }","duration":"361.876956ms","start":"2026-03-25T21:07:16.979391Z","end":"2026-03-25T21:07:17.341268Z","steps":["trace[1155087896] 'agreement among raft nodes before linearized reading' (duration: 361.794144ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:17.341323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:16.979383Z","time spent":"361.934127ms","remote":"127.0.0.1:33004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-25T21:07:17.341335Z","caller":"traceutil/trace.go:171","msg":"trace[877637367] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1336; }","duration":"362.669943ms","start":"2026-03-25T21:07:16.978643Z","end":"2026-03-25T21:07:17.341313Z","steps":["trace[877637367] 'agreement among raft nodes before linearized reading' (duration: 362.419528ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:17.341293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:16.829139Z","time spent":"512.145213ms","remote":"127.0.0.1:33004","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-25T21:07:17.341381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:16.978615Z","time spent":"362.756245ms","remote":"127.0.0.1:33004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-25T21:07:17.340897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.685943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:07:17.341419Z","caller":"traceutil/trace.go:171","msg":"trace[684386481] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1336; }","duration":"595.229365ms","start":"2026-03-25T21:07:16.74618Z","end":"2026-03-25T21:07:17.34141Z","steps":["trace[684386481] 'agreement among raft nodes before linearized reading' (duration: 594.664323ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:17.341496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:16.746122Z","time spent":"595.365528ms","remote":"127.0.0.1:32982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-03-25T21:07:17.341185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:16.877855Z","time spent":"463.325348ms","remote":"127.0.0.1:60976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-25T21:07:17.550636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.344355ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T21:07:17.550867Z","caller":"traceutil/trace.go:171","msg":"trace[726507276] linearizableReadLoop","detail":"{readStateIndex:1396; appliedIndex:1392; }","duration":"203.77736ms","start":"2026-03-25T21:07:17.34707Z","end":"2026-03-25T21:07:17.550848Z","steps":["trace[726507276] 'read index received' (duration: 70.071536ms)","trace[726507276] 'applied index is now lower than readState.Index' (duration: 133.705024ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:07:17.550955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.890303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:07:17.550988Z","caller":"traceutil/trace.go:171","msg":"trace[2126375149] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1340; }","duration":"203.953444ms","start":"2026-03-25T21:07:17.347026Z","end":"2026-03-25T21:07:17.550979Z","steps":["trace[2126375149] 'agreement among raft nodes before linearized reading' (duration: 203.881212ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:07:17.551039Z","caller":"traceutil/trace.go:171","msg":"trace[760615723] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"204.579578ms","start":"2026-03-25T21:07:17.34641Z","end":"2026-03-25T21:07:17.55099Z","steps":["trace[760615723] 'process raft request' (duration: 204.325282ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:07:17.551185Z","caller":"traceutil/trace.go:171","msg":"trace[1814653160] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"204.330592ms","start":"2026-03-25T21:07:17.346822Z","end":"2026-03-25T21:07:17.551152Z","steps":["trace[1814653160] 'process raft request' (duration: 203.992795ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:07:17.551268Z","caller":"traceutil/trace.go:171","msg":"trace[747141999] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"204.666539ms","start":"2026-03-25T21:07:17.346573Z","end":"2026-03-25T21:07:17.55124Z","steps":["trace[747141999] 'process raft request' (duration: 204.208199ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:07:17.55132Z","caller":"traceutil/trace.go:171","msg":"trace[1198866090] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"206.001229ms","start":"2026-03-25T21:07:17.345304Z","end":"2026-03-25T21:07:17.551305Z","steps":["trace[1198866090] 'process raft request' (duration: 71.902187ms)","trace[1198866090] 'compare' (duration: 133.222443ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:07:17.639213Z","caller":"traceutil/trace.go:171","msg":"trace[1927516101] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"116.794505ms","start":"2026-03-25T21:07:17.522392Z","end":"2026-03-25T21:07:17.639187Z","steps":["trace[1927516101] 'process raft request' (duration: 116.625731ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:07:45.582511Z","caller":"traceutil/trace.go:171","msg":"trace[1442692345] linearizableReadLoop","detail":"{readStateIndex:1496; appliedIndex:1495; }","duration":"107.748223ms","start":"2026-03-25T21:07:45.474738Z","end":"2026-03-25T21:07:45.582486Z","steps":["trace[1442692345] 'read index received' (duration: 107.539498ms)","trace[1442692345] 'applied index is now lower than readState.Index' (duration: 207.755µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:07:45.582556Z","caller":"traceutil/trace.go:171","msg":"trace[477207935] transaction","detail":"{read_only:false; response_revision:1433; number_of_response:1; }","duration":"119.391792ms","start":"2026-03-25T21:07:45.463134Z","end":"2026-03-25T21:07:45.582525Z","steps":["trace[477207935] 'process raft request' (duration: 119.140546ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:45.582717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.938527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ephemeral-volume-controller\" ","response":"range_response_count:1 size:220"} {"level":"info","ts":"2026-03-25T21:07:45.582771Z","caller":"traceutil/trace.go:171","msg":"trace[1678555751] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ephemeral-volume-controller; range_end:; response_count:1; response_revision:1433; }","duration":"108.052099ms","start":"2026-03-25T21:07:45.474706Z","end":"2026-03-25T21:07:45.582758Z","steps":["trace[1678555751] 'agreement among raft nodes before linearized reading' (duration: 107.916826ms)"],"step_count":1}