{"level":"warn","ts":"2026-06-03T07:31:08.580317Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-03T07:31:08.580417Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.228: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.19.213.228:2380","--initial-cluster=instance=https://199.19.213.228:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.228:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.228: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-06-03T07:31:08.580499Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-03T07:31:08.580514Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.228:2380"]} {"level":"info","ts":"2026-06-03T07:31:08.580537Z","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-06-03T07:31:08.581449Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.228:2379"]} {"level":"info","ts":"2026-06-03T07:31:08.581542Z","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.19.213.228:2380"],"listen-peer-urls":["https://199.19.213.228:2380"],"advertise-client-urls":["https://199.19.213.228:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.228:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.228: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-06-03T07:31:08.585581Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.798235ms"} {"level":"info","ts":"2026-06-03T07:31:08.59331Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"db79120667ce701f","cluster-id":"793c581eb68f3646"} {"level":"info","ts":"2026-06-03T07:31:08.593411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f switched to configuration voters=()"} {"level":"info","ts":"2026-06-03T07:31:08.593438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f became follower at term 0"} {"level":"info","ts":"2026-06-03T07:31:08.593459Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft db79120667ce701f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-03T07:31:08.593522Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f became follower at term 1"} {"level":"info","ts":"2026-06-03T07:31:08.593567Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f switched to configuration voters=(15814691385208893471)"} {"level":"warn","ts":"2026-06-03T07:31:08.598864Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-03T07:31:08.600553Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-03T07:31:08.602158Z","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-06-03T07:31:08.607355Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"db79120667ce701f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-03T07:31:08.607746Z","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-06-03T07:31:08.607839Z","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-06-03T07:31:08.60786Z","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-06-03T07:31:08.607994Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"db79120667ce701f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-03T07:31:08.621254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f switched to configuration voters=(15814691385208893471)"} {"level":"info","ts":"2026-06-03T07:31:08.621364Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"793c581eb68f3646","local-member-id":"db79120667ce701f","added-peer-id":"db79120667ce701f","added-peer-peer-urls":["https://199.19.213.228:2380"]} {"level":"info","ts":"2026-06-03T07:31:08.622702Z","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-06-03T07:31:08.622816Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.228:2380"} {"level":"info","ts":"2026-06-03T07:31:08.622844Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.228:2380"} {"level":"info","ts":"2026-06-03T07:31:08.623089Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"db79120667ce701f","initial-advertise-peer-urls":["https://199.19.213.228:2380"],"listen-peer-urls":["https://199.19.213.228:2380"],"advertise-client-urls":["https://199.19.213.228:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.228:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-03T07:31:08.623127Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-03T07:31:09.393951Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f is starting a new election at term 1"} {"level":"info","ts":"2026-06-03T07:31:09.394063Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f became pre-candidate at term 1"} {"level":"info","ts":"2026-06-03T07:31:09.394109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f received MsgPreVoteResp from db79120667ce701f at term 1"} {"level":"info","ts":"2026-06-03T07:31:09.394139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f became candidate at term 2"} {"level":"info","ts":"2026-06-03T07:31:09.394152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f received MsgVoteResp from db79120667ce701f at term 2"} {"level":"info","ts":"2026-06-03T07:31:09.394191Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f became leader at term 2"} {"level":"info","ts":"2026-06-03T07:31:09.394216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: db79120667ce701f elected leader db79120667ce701f at term 2"} {"level":"info","ts":"2026-06-03T07:31:09.395729Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"db79120667ce701f","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.228:2379]}","request-path":"/0/members/db79120667ce701f/attributes","cluster-id":"793c581eb68f3646","publish-timeout":"7s"} {"level":"info","ts":"2026-06-03T07:31:09.395746Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-03T07:31:09.395789Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-03T07:31:09.396098Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-03T07:31:09.396468Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-03T07:31:09.396528Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-03T07:31:09.398836Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"793c581eb68f3646","local-member-id":"db79120667ce701f","cluster-version":"3.5"} {"level":"info","ts":"2026-06-03T07:31:09.398963Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-03T07:31:09.398992Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-03T07:31:09.39936Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.228:2379"} {"level":"info","ts":"2026-06-03T07:31:09.400591Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-03T07:31:11.976188Z","caller":"traceutil/trace.go:171","msg":"trace[1957218876] linearizableReadLoop","detail":"{readStateIndex:75; appliedIndex:74; }","duration":"307.892546ms","start":"2026-06-03T07:31:11.668267Z","end":"2026-06-03T07:31:11.976159Z","steps":["trace[1957218876] 'read index received' (duration: 307.6047ms)","trace[1957218876] 'applied index is now lower than readState.Index' (duration: 286.516µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:31:11.97627Z","caller":"traceutil/trace.go:171","msg":"trace[533547623] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"313.249014ms","start":"2026-06-03T07:31:11.662977Z","end":"2026-06-03T07:31:11.976226Z","steps":["trace[533547623] 'process raft request' (duration: 312.990029ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:31:11.983539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.057377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-03T07:31:11.983642Z","caller":"traceutil/trace.go:171","msg":"trace[1495559943] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:71; }","duration":"299.175515ms","start":"2026-06-03T07:31:11.684449Z","end":"2026-06-03T07:31:11.983625Z","steps":["trace[1495559943] 'agreement among raft nodes before linearized reading' (duration: 291.945555ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:31:11.983655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.378082ms","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-06-03T07:31:11.983683Z","caller":"traceutil/trace.go:171","msg":"trace[2029294653] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:71; }","duration":"315.437223ms","start":"2026-06-03T07:31:11.668237Z","end":"2026-06-03T07:31:11.983674Z","steps":["trace[2029294653] 'agreement among raft nodes before linearized reading' (duration: 308.07304ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:31:11.983711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:31:11.668228Z","time spent":"315.478993ms","remote":"127.0.0.1:48472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/system:aggregate-to-admin\" "} {"level":"warn","ts":"2026-06-03T07:31:11.983938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:31:11.662935Z","time spent":"313.430558ms","remote":"127.0.0.1:48488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-03T07:31:12.490519Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8079350582347608535,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-03T07:31:12.830874Z","caller":"traceutil/trace.go:171","msg":"trace[537558270] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"840.59008ms","start":"2026-06-03T07:31:11.990255Z","end":"2026-06-03T07:31:12.830845Z","steps":["trace[537558270] 'read index received' (duration: 840.293173ms)","trace[537558270] 'applied index is now lower than readState.Index' (duration: 295.967µs)"],"step_count":2} {"level":"warn","ts":"2026-06-03T07:31:12.831308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.698288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-03T07:31:12.830952Z","caller":"traceutil/trace.go:171","msg":"trace[1672329810] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"841.909058ms","start":"2026-06-03T07:31:11.988998Z","end":"2026-06-03T07:31:12.830907Z","steps":["trace[1672329810] 'process raft request' (duration: 841.676873ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:31:12.831342Z","caller":"traceutil/trace.go:171","msg":"trace[2011666096] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:72; }","duration":"146.757049ms","start":"2026-06-03T07:31:12.684573Z","end":"2026-06-03T07:31:12.83133Z","steps":["trace[2011666096] 'agreement among raft nodes before linearized reading' (duration: 146.714708ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:31:12.831395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:31:11.988988Z","time spent":"842.356117ms","remote":"127.0.0.1:48488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-03T07:31:12.831152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"840.870375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-03T07:31:12.831469Z","caller":"traceutil/trace.go:171","msg":"trace[566758164] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:72; }","duration":"841.229663ms","start":"2026-06-03T07:31:11.990211Z","end":"2026-06-03T07:31:12.83144Z","steps":["trace[566758164] 'agreement among raft nodes before linearized reading' (duration: 840.800164ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:31:12.831559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:31:11.99019Z","time spent":"841.330006ms","remote":"127.0.0.1:48472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/edit\" "} {"level":"warn","ts":"2026-06-03T07:31:13.408897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.296965ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-06-03T07:31:13.409095Z","caller":"traceutil/trace.go:171","msg":"trace[1154351004] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"567.004609ms","start":"2026-06-03T07:31:12.84204Z","end":"2026-06-03T07:31:13.409045Z","steps":["trace[1154351004] 'process raft request' (duration: 183.246983ms)","trace[1154351004] 'compare' (duration: 383.159122ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T07:31:13.409161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:31:12.842024Z","time spent":"567.114551ms","remote":"127.0.0.1:48472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-03T07:31:13.409223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.045972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-03T07:31:13.409277Z","caller":"traceutil/trace.go:171","msg":"trace[270474] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:73; }","duration":"324.106563ms","start":"2026-06-03T07:31:13.085158Z","end":"2026-06-03T07:31:13.409264Z","steps":["trace[270474] 'agreement among raft nodes before linearized reading' (duration: 323.96284ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:31:13.409324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:31:13.085103Z","time spent":"324.210245ms","remote":"127.0.0.1:48116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-03T07:31:13.409086Z","caller":"traceutil/trace.go:171","msg":"trace[735459307] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:76; }","duration":"323.900569ms","start":"2026-06-03T07:31:13.085163Z","end":"2026-06-03T07:31:13.409064Z","steps":["trace[735459307] 'read index received' (duration: 49.941µs)","trace[735459307] 'applied index is now lower than readState.Index' (duration: 323.848738ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:31:13.586207Z","caller":"traceutil/trace.go:171","msg":"trace[838237919] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"170.87251ms","start":"2026-06-03T07:31:13.415314Z","end":"2026-06-03T07:31:13.586187Z","steps":["trace[838237919] 'process raft request' (duration: 169.901959ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:31:45.622066Z","caller":"traceutil/trace.go:171","msg":"trace[1629700701] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"282.895158ms","start":"2026-06-03T07:31:45.339147Z","end":"2026-06-03T07:31:45.622042Z","steps":["trace[1629700701] 'process raft request' (duration: 282.299484ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:31:45.784597Z","caller":"traceutil/trace.go:171","msg":"trace[1097638324] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"113.820306ms","start":"2026-06-03T07:31:45.670745Z","end":"2026-06-03T07:31:45.784566Z","steps":["trace[1097638324] 'process raft request' (duration: 113.724164ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:31:45.784607Z","caller":"traceutil/trace.go:171","msg":"trace[974310245] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"123.08645ms","start":"2026-06-03T07:31:45.661475Z","end":"2026-06-03T07:31:45.784561Z","steps":["trace[974310245] 'process raft request' (duration: 122.139438ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:31:47.847211Z","caller":"traceutil/trace.go:171","msg":"trace[1209686690] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"297.746864ms","start":"2026-06-03T07:31:47.549413Z","end":"2026-06-03T07:31:47.84716Z","steps":["trace[1209686690] 'process raft request' (duration: 297.5535ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:31:47.85425Z","caller":"traceutil/trace.go:171","msg":"trace[724032423] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"286.923215ms","start":"2026-06-03T07:31:47.567304Z","end":"2026-06-03T07:31:47.854227Z","steps":["trace[724032423] 'process raft request' (duration: 286.436465ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:31:47.854376Z","caller":"traceutil/trace.go:171","msg":"trace[898918471] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"302.627172ms","start":"2026-06-03T07:31:47.551715Z","end":"2026-06-03T07:31:47.854342Z","steps":["trace[898918471] 'process raft request' (duration: 301.787493ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:31:47.854505Z","caller":"traceutil/trace.go:171","msg":"trace[73121330] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"302.734494ms","start":"2026-06-03T07:31:47.551763Z","end":"2026-06-03T07:31:47.854497Z","steps":["trace[73121330] 'process raft request' (duration: 301.885955ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:31:47.854508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:31:47.551697Z","time spent":"302.745884ms","remote":"127.0.0.1:48174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-03T07:31:47.854581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:31:47.55176Z","time spent":"302.772775ms","remote":"127.0.0.1:48174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-03T07:31:47.854659Z","caller":"traceutil/trace.go:171","msg":"trace[924231745] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"291.928437ms","start":"2026-06-03T07:31:47.562721Z","end":"2026-06-03T07:31:47.85465Z","steps":["trace[924231745] 'process raft request' (duration: 290.979136ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:31:47.85475Z","caller":"traceutil/trace.go:171","msg":"trace[1563111825] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"217.60368ms","start":"2026-06-03T07:31:47.637136Z","end":"2026-06-03T07:31:47.85474Z","steps":["trace[1563111825] 'process raft request' (duration: 217.100009ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:31:47.854752Z","caller":"traceutil/trace.go:171","msg":"trace[549656974] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"286.596609ms","start":"2026-06-03T07:31:47.568123Z","end":"2026-06-03T07:31:47.854719Z","steps":["trace[549656974] 'process raft request' (duration: 285.676218ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:31:47.854889Z","caller":"traceutil/trace.go:171","msg":"trace[2143023923] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"286.216741ms","start":"2026-06-03T07:31:47.568665Z","end":"2026-06-03T07:31:47.854882Z","steps":["trace[2143023923] 'process raft request' (duration: 285.180667ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:31:47.854954Z","caller":"traceutil/trace.go:171","msg":"trace[488074701] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"281.62355ms","start":"2026-06-03T07:31:47.573325Z","end":"2026-06-03T07:31:47.854949Z","steps":["trace[488074701] 'process raft request' (duration: 280.627677ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:31:47.855018Z","caller":"traceutil/trace.go:171","msg":"trace[831138582] linearizableReadLoop","detail":"{readStateIndex:516; appliedIndex:505; }","duration":"212.13515ms","start":"2026-06-03T07:31:47.642871Z","end":"2026-06-03T07:31:47.855006Z","steps":["trace[831138582] 'read index received' (duration: 204.083303ms)","trace[831138582] 'applied index is now lower than readState.Index' (duration: 8.050817ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:31:47.855042Z","caller":"traceutil/trace.go:171","msg":"trace[1986684312] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"261.548458ms","start":"2026-06-03T07:31:47.593483Z","end":"2026-06-03T07:31:47.855032Z","steps":["trace[1986684312] 'process raft request' (duration: 260.565445ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:31:47.855169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.270213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-03T07:31:47.855213Z","caller":"traceutil/trace.go:171","msg":"trace[1543187604] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:502; }","duration":"212.383135ms","start":"2026-06-03T07:31:47.642818Z","end":"2026-06-03T07:31:47.855202Z","steps":["trace[1543187604] 'agreement among raft nodes before linearized reading' (duration: 212.238242ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:31:47.855221Z","caller":"traceutil/trace.go:171","msg":"trace[1963904788] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"230.117506ms","start":"2026-06-03T07:31:47.625091Z","end":"2026-06-03T07:31:47.855208Z","steps":["trace[1963904788] 'process raft request' (duration: 229.025121ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:31:54.631636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.80578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T07:31:54.631735Z","caller":"traceutil/trace.go:171","msg":"trace[444221500] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:598; }","duration":"622.960484ms","start":"2026-06-03T07:31:54.008752Z","end":"2026-06-03T07:31:54.631713Z","steps":["trace[444221500] 'range keys from in-memory index tree' (duration: 622.659596ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:31:54.631782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.640474ms","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-06-03T07:31:54.631839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:31:54.00873Z","time spent":"623.097446ms","remote":"127.0.0.1:48116","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-06-03T07:31:54.631923Z","caller":"traceutil/trace.go:171","msg":"trace[549598936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:598; }","duration":"636.811778ms","start":"2026-06-03T07:31:53.995041Z","end":"2026-06-03T07:31:54.631853Z","steps":["trace[549598936] 'range keys from in-memory index tree' (duration: 636.43436ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:31:54.63198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:31:53.995009Z","time spent":"636.961101ms","remote":"127.0.0.1:48378","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-06-03T07:31:54.63211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.424039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.228\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-03T07:31:54.632256Z","caller":"traceutil/trace.go:171","msg":"trace[2055145759] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:621; }","duration":"239.284416ms","start":"2026-06-03T07:31:54.392953Z","end":"2026-06-03T07:31:54.632237Z","steps":["trace[2055145759] 'read index received' (duration: 209.844799ms)","trace[2055145759] 'applied index is now lower than readState.Index' (duration: 29.438587ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:31:54.632236Z","caller":"traceutil/trace.go:171","msg":"trace[38196821] range","detail":"{range_begin:/registry/masterleases/199.19.213.228; range_end:; response_count:1; response_revision:598; }","duration":"442.89888ms","start":"2026-06-03T07:31:54.189315Z","end":"2026-06-03T07:31:54.632214Z","steps":["trace[38196821] 'range keys from in-memory index tree' (duration: 442.245336ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:31:54.63236Z","caller":"traceutil/trace.go:171","msg":"trace[1368169468] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"328.598383ms","start":"2026-06-03T07:31:54.303748Z","end":"2026-06-03T07:31:54.632347Z","steps":["trace[1368169468] 'process raft request' (duration: 299.328429ms)","trace[1368169468] 'compare' (duration: 28.782083ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T07:31:54.632404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.45305ms","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-06-03T07:31:54.632441Z","caller":"traceutil/trace.go:171","msg":"trace[626080637] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:599; }","duration":"239.526641ms","start":"2026-06-03T07:31:54.392904Z","end":"2026-06-03T07:31:54.632431Z","steps":["trace[626080637] 'agreement among raft nodes before linearized reading' (duration: 239.417529ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:31:54.632581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:31:54.18929Z","time spent":"443.244727ms","remote":"127.0.0.1:48154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.228\" "} {"level":"warn","ts":"2026-06-03T07:31:54.632725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:31:54.303717Z","time spent":"328.710935ms","remote":"127.0.0.1:48232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T07:31:54.802684Z","caller":"traceutil/trace.go:171","msg":"trace[825196398] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"166.099136ms","start":"2026-06-03T07:31:54.636558Z","end":"2026-06-03T07:31:54.802657Z","steps":["trace[825196398] 'process raft request' (duration: 140.573314ms)","trace[825196398] 'compare' (duration: 25.319388ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:31:54.803088Z","caller":"traceutil/trace.go:171","msg":"trace[1950448486] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"166.441914ms","start":"2026-06-03T07:31:54.636619Z","end":"2026-06-03T07:31:54.803061Z","steps":["trace[1950448486] 'process raft request' (duration: 165.971944ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:31:54.803139Z","caller":"traceutil/trace.go:171","msg":"trace[305201381] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"109.02526ms","start":"2026-06-03T07:31:54.694083Z","end":"2026-06-03T07:31:54.803109Z","steps":["trace[305201381] 'process raft request' (duration: 108.835536ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:31:55.304717Z","caller":"traceutil/trace.go:171","msg":"trace[2051719887] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:627; }","duration":"293.592904ms","start":"2026-06-03T07:31:55.011099Z","end":"2026-06-03T07:31:55.304692Z","steps":["trace[2051719887] 'read index received' (duration: 293.316898ms)","trace[2051719887] 'applied index is now lower than readState.Index' (duration: 275.075µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:31:55.304839Z","caller":"traceutil/trace.go:171","msg":"trace[2103921972] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"485.250392ms","start":"2026-06-03T07:31:54.81958Z","end":"2026-06-03T07:31:55.30483Z","steps":["trace[2103921972] 'process raft request' (duration: 484.942805ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:31:55.304967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:31:54.819566Z","time spent":"485.302693ms","remote":"127.0.0.1:48328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-03T07:31:55.305158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.042173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T07:31:55.305232Z","caller":"traceutil/trace.go:171","msg":"trace[1960092624] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:604; }","duration":"294.171926ms","start":"2026-06-03T07:31:55.01104Z","end":"2026-06-03T07:31:55.305212Z","steps":["trace[1960092624] 'agreement among raft nodes before linearized reading' (duration: 293.941571ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:31:55.305232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.401047ms","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-06-03T07:31:55.305321Z","caller":"traceutil/trace.go:171","msg":"trace[464479542] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:604; }","duration":"223.522441ms","start":"2026-06-03T07:31:55.081773Z","end":"2026-06-03T07:31:55.305296Z","steps":["trace[464479542] 'agreement among raft nodes before linearized reading' (duration: 223.080861ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:31:56.538964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.395085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-03T07:31:56.539045Z","caller":"traceutil/trace.go:171","msg":"trace[2076118559] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:609; }","duration":"628.525327ms","start":"2026-06-03T07:31:55.910499Z","end":"2026-06-03T07:31:56.539025Z","steps":["trace[2076118559] 'range keys from in-memory index tree' (duration: 628.132459ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:31:56.539087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:31:55.910447Z","time spent":"628.63167ms","remote":"127.0.0.1:48378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-03T07:31:57.005332Z","caller":"traceutil/trace.go:171","msg":"trace[388280969] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"101.800821ms","start":"2026-06-03T07:31:56.903506Z","end":"2026-06-03T07:31:57.005307Z","steps":["trace[388280969] 'process raft request' (duration: 101.550126ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:31:58.35516Z","caller":"traceutil/trace.go:171","msg":"trace[2070539525] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"339.702258ms","start":"2026-06-03T07:31:58.015424Z","end":"2026-06-03T07:31:58.355127Z","steps":["trace[2070539525] 'process raft request' (duration: 339.485473ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:31:58.35538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:31:58.015383Z","time spent":"339.883582ms","remote":"127.0.0.1:48174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9296,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T07:31:58.357782Z","caller":"traceutil/trace.go:171","msg":"trace[2045819558] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"154.46725ms","start":"2026-06-03T07:31:58.203271Z","end":"2026-06-03T07:31:58.357738Z","steps":["trace[2045819558] 'process raft request' (duration: 154.237425ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:11.509548Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8079350582347609987,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-03T07:32:11.947128Z","caller":"traceutil/trace.go:171","msg":"trace[1309774859] linearizableReadLoop","detail":"{readStateIndex:786; appliedIndex:785; }","duration":"938.409106ms","start":"2026-06-03T07:32:11.008699Z","end":"2026-06-03T07:32:11.947108Z","steps":["trace[1309774859] 'read index received' (duration: 938.16766ms)","trace[1309774859] 'applied index is now lower than readState.Index' (duration: 240.496µs)"],"step_count":2} {"level":"warn","ts":"2026-06-03T07:32:11.947275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"938.568919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T07:32:11.947317Z","caller":"traceutil/trace.go:171","msg":"trace[577874719] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:758; }","duration":"938.661811ms","start":"2026-06-03T07:32:11.008641Z","end":"2026-06-03T07:32:11.947303Z","steps":["trace[577874719] 'agreement among raft nodes before linearized reading' (duration: 938.591729ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:11.947348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:11.008621Z","time spent":"938.718943ms","remote":"127.0.0.1:48108","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-06-03T07:32:11.947422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.732454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-03T07:32:11.947482Z","caller":"traceutil/trace.go:171","msg":"trace[126355593] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:758; }","duration":"546.840075ms","start":"2026-06-03T07:32:11.400629Z","end":"2026-06-03T07:32:11.947469Z","steps":["trace[126355593] 'agreement among raft nodes before linearized reading' (duration: 546.666482ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:11.947513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:11.400606Z","time spent":"546.899017ms","remote":"127.0.0.1:48378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-03T07:32:11.947273Z","caller":"traceutil/trace.go:171","msg":"trace[2036557197] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"945.918051ms","start":"2026-06-03T07:32:11.001321Z","end":"2026-06-03T07:32:11.947239Z","steps":["trace[2036557197] 'process raft request' (duration: 945.626555ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:11.948242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:11.001307Z","time spent":"946.832451ms","remote":"127.0.0.1:48328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T07:32:12.260699Z","caller":"traceutil/trace.go:171","msg":"trace[348314983] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"308.406629ms","start":"2026-06-03T07:32:11.952266Z","end":"2026-06-03T07:32:12.260673Z","steps":["trace[348314983] 'process raft request' (duration: 277.051199ms)","trace[348314983] 'compare' (duration: 31.127915ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T07:32:12.260839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:11.95225Z","time spent":"308.514241ms","remote":"127.0.0.1:48378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T07:32:12.263678Z","caller":"traceutil/trace.go:171","msg":"trace[1097238267] linearizableReadLoop","detail":"{readStateIndex:788; appliedIndex:786; }","duration":"253.719826ms","start":"2026-06-03T07:32:12.009937Z","end":"2026-06-03T07:32:12.263656Z","steps":["trace[1097238267] 'read index received' (duration: 219.472852ms)","trace[1097238267] 'applied index is now lower than readState.Index' (duration: 34.246014ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:32:12.263837Z","caller":"traceutil/trace.go:171","msg":"trace[1813332088] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"309.992914ms","start":"2026-06-03T07:32:11.953829Z","end":"2026-06-03T07:32:12.263822Z","steps":["trace[1813332088] 'process raft request' (duration: 309.722449ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:12.264312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:11.953818Z","time spent":"310.399523ms","remote":"127.0.0.1:48378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T07:32:12.263895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.966391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T07:32:12.264866Z","caller":"traceutil/trace.go:171","msg":"trace[594219650] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:760; }","duration":"254.961673ms","start":"2026-06-03T07:32:12.00989Z","end":"2026-06-03T07:32:12.264852Z","steps":["trace[594219650] 'agreement among raft nodes before linearized reading' (duration: 253.976981ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:41.223344Z","caller":"traceutil/trace.go:171","msg":"trace[305030218] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"138.340386ms","start":"2026-06-03T07:32:41.084967Z","end":"2026-06-03T07:32:41.223308Z","steps":["trace[305030218] 'process raft request' (duration: 135.76032ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:47.066258Z","caller":"traceutil/trace.go:171","msg":"trace[474590065] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"162.491656ms","start":"2026-06-03T07:32:46.903742Z","end":"2026-06-03T07:32:47.066234Z","steps":["trace[474590065] 'process raft request' (duration: 162.357163ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:48.155469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.698763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-03T07:32:48.155588Z","caller":"traceutil/trace.go:171","msg":"trace[1436025754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:979; }","duration":"907.895337ms","start":"2026-06-03T07:32:47.247668Z","end":"2026-06-03T07:32:48.155563Z","steps":["trace[1436025754] 'range keys from in-memory index tree' (duration: 907.478518ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:48.155635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:47.247646Z","time spent":"907.980629ms","remote":"127.0.0.1:48378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-03T07:32:48.155818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.025719312s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-03T07:32:48.155902Z","caller":"traceutil/trace.go:171","msg":"trace[1723510628] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:979; }","duration":"1.025863155s","start":"2026-06-03T07:32:47.13002Z","end":"2026-06-03T07:32:48.155883Z","steps":["trace[1723510628] 'range keys from in-memory index tree' (duration: 1.025528017s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:48.155936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:47.129997Z","time spent":"1.025931897s","remote":"127.0.0.1:48378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-03T07:32:48.492972Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.243566126s","expected-duration":"1s"} {"level":"info","ts":"2026-06-03T07:32:48.493553Z","caller":"traceutil/trace.go:171","msg":"trace[382269830] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"1.244251731s","start":"2026-06-03T07:32:47.249281Z","end":"2026-06-03T07:32:48.493533Z","steps":["trace[382269830] 'process raft request' (duration: 1.244084997s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:48.493663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:47.249261Z","time spent":"1.244325563s","remote":"127.0.0.1:48378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T07:32:48.537452Z","caller":"traceutil/trace.go:171","msg":"trace[1850274313] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"377.130328ms","start":"2026-06-03T07:32:48.160306Z","end":"2026-06-03T07:32:48.537436Z","steps":["trace[1850274313] 'process raft request' (duration: 377.058837ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:48.537524Z","caller":"traceutil/trace.go:171","msg":"trace[2139155494] linearizableReadLoop","detail":"{readStateIndex:1018; appliedIndex:1016; }","duration":"528.051837ms","start":"2026-06-03T07:32:48.009465Z","end":"2026-06-03T07:32:48.537517Z","steps":["trace[2139155494] 'read index received' (duration: 483.876448ms)","trace[2139155494] 'applied index is now lower than readState.Index' (duration: 44.174509ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T07:32:48.537556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:48.16029Z","time spent":"377.21268ms","remote":"127.0.0.1:48378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T07:32:48.537591Z","caller":"traceutil/trace.go:171","msg":"trace[1383146002] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"377.028397ms","start":"2026-06-03T07:32:48.160542Z","end":"2026-06-03T07:32:48.537571Z","steps":["trace[1383146002] 'process raft request' (duration: 376.859263ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:48.537624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.15384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T07:32:48.537648Z","caller":"traceutil/trace.go:171","msg":"trace[1359889097] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:983; }","duration":"528.221492ms","start":"2026-06-03T07:32:48.009418Z","end":"2026-06-03T07:32:48.537639Z","steps":["trace[1359889097] 'agreement among raft nodes before linearized reading' (duration: 528.15506ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:48.537666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:48.009399Z","time spent":"528.263513ms","remote":"127.0.0.1:48108","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-06-03T07:32:48.537448Z","caller":"traceutil/trace.go:171","msg":"trace[1977477444] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"971.326734ms","start":"2026-06-03T07:32:47.566097Z","end":"2026-06-03T07:32:48.537424Z","steps":["trace[1977477444] 'process raft request' (duration: 971.176431ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:48.5377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:48.160516Z","time spent":"377.118299ms","remote":"127.0.0.1:48378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T07:32:48.537762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:47.566076Z","time spent":"971.657212ms","remote":"127.0.0.1:48378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T07:32:48.537844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.23692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-03T07:32:48.537864Z","caller":"traceutil/trace.go:171","msg":"trace[2097821795] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:983; }","duration":"317.287892ms","start":"2026-06-03T07:32:48.220571Z","end":"2026-06-03T07:32:48.537859Z","steps":["trace[2097821795] 'agreement among raft nodes before linearized reading' (duration: 317.247651ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:48.537884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:48.220557Z","time spent":"317.319842ms","remote":"127.0.0.1:48232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":9,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-06-03T07:33:13.157187Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8079350582347610678,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-03T07:33:13.183235Z","caller":"traceutil/trace.go:171","msg":"trace[1557548485] linearizableReadLoop","detail":"{readStateIndex:1101; appliedIndex:1100; }","duration":"526.722698ms","start":"2026-06-03T07:33:12.656483Z","end":"2026-06-03T07:33:13.183206Z","steps":["trace[1557548485] 'read index received' (duration: 526.579045ms)","trace[1557548485] 'applied index is now lower than readState.Index' (duration: 142.453µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:33:13.183294Z","caller":"traceutil/trace.go:171","msg":"trace[1423561760] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"570.185583ms","start":"2026-06-03T07:33:12.613084Z","end":"2026-06-03T07:33:13.183269Z","steps":["trace[1423561760] 'process raft request' (duration: 569.925527ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:13.183496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.988775ms","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-06-03T07:33:13.183511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.953897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T07:33:13.183543Z","caller":"traceutil/trace.go:171","msg":"trace[924515613] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1059; }","duration":"173.024739ms","start":"2026-06-03T07:33:13.01051Z","end":"2026-06-03T07:33:13.183534Z","steps":["trace[924515613] 'agreement among raft nodes before linearized reading' (duration: 172.959977ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:13.183536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.310646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T07:33:13.183552Z","caller":"traceutil/trace.go:171","msg":"trace[758238449] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1059; }","duration":"527.094436ms","start":"2026-06-03T07:33:12.656435Z","end":"2026-06-03T07:33:13.18353Z","steps":["trace[758238449] 'agreement among raft nodes before linearized reading' (duration: 526.905112ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:33:13.18359Z","caller":"traceutil/trace.go:171","msg":"trace[249942153] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1059; }","duration":"292.375247ms","start":"2026-06-03T07:33:12.891198Z","end":"2026-06-03T07:33:13.183573Z","steps":["trace[249942153] 'agreement among raft nodes before linearized reading' (duration: 292.257815ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:13.183607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:33:12.656416Z","time spent":"527.182629ms","remote":"127.0.0.1:48378","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-06-03T07:33:13.183636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.690084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-03T07:33:13.183689Z","caller":"traceutil/trace.go:171","msg":"trace[465479210] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1059; }","duration":"438.776857ms","start":"2026-06-03T07:33:12.744898Z","end":"2026-06-03T07:33:13.183675Z","steps":["trace[465479210] 'agreement among raft nodes before linearized reading' (duration: 438.605103ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:13.183444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:33:12.613059Z","time spent":"570.281844ms","remote":"127.0.0.1:48378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T07:33:13.183723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:33:12.744855Z","time spent":"438.860629ms","remote":"127.0.0.1:48378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-03T07:33:13.183948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.609482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-03T07:33:13.183912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.631818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-03T07:33:13.184068Z","caller":"traceutil/trace.go:171","msg":"trace[201773649] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1059; }","duration":"463.857264ms","start":"2026-06-03T07:33:12.720164Z","end":"2026-06-03T07:33:13.184021Z","steps":["trace[201773649] 'agreement among raft nodes before linearized reading' (duration: 463.404803ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:33:13.183981Z","caller":"traceutil/trace.go:171","msg":"trace[160969285] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1059; }","duration":"520.687374ms","start":"2026-06-03T07:33:12.663283Z","end":"2026-06-03T07:33:13.183971Z","steps":["trace[160969285] 'agreement among raft nodes before linearized reading' (duration: 520.165873ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:13.184136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:33:12.720116Z","time spent":"464.008988ms","remote":"127.0.0.1:48378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-03T07:33:13.184141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:33:12.66326Z","time spent":"520.863298ms","remote":"127.0.0.1:48378","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-06-03T07:33:13.384639Z","caller":"traceutil/trace.go:171","msg":"trace[234729074] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"195.799025ms","start":"2026-06-03T07:33:13.188812Z","end":"2026-06-03T07:33:13.384611Z","steps":["trace[234729074] 'process raft request' (duration: 117.430266ms)","trace[234729074] 'compare' (duration: 78.203485ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:33:13.432935Z","caller":"traceutil/trace.go:171","msg":"trace[2979205] linearizableReadLoop","detail":"{readStateIndex:1105; appliedIndex:1101; }","duration":"241.279074ms","start":"2026-06-03T07:33:13.19164Z","end":"2026-06-03T07:33:13.432919Z","steps":["trace[2979205] 'read index received' (duration: 114.715165ms)","trace[2979205] 'applied index is now lower than readState.Index' (duration: 126.562809ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:33:13.433084Z","caller":"traceutil/trace.go:171","msg":"trace[1784969387] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"241.99686ms","start":"2026-06-03T07:33:13.191077Z","end":"2026-06-03T07:33:13.433074Z","steps":["trace[1784969387] 'process raft request' (duration: 241.808016ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:33:13.433201Z","caller":"traceutil/trace.go:171","msg":"trace[430941143] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"243.712679ms","start":"2026-06-03T07:33:13.189387Z","end":"2026-06-03T07:33:13.433099Z","steps":["trace[430941143] 'process raft request' (duration: 243.449993ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:13.43328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.340569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-03T07:33:13.433381Z","caller":"traceutil/trace.go:171","msg":"trace[104262840] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1063; }","duration":"174.481552ms","start":"2026-06-03T07:33:13.258882Z","end":"2026-06-03T07:33:13.433363Z","steps":["trace[104262840] 'agreement among raft nodes before linearized reading' (duration: 174.275937ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:33:13.433462Z","caller":"traceutil/trace.go:171","msg":"trace[1944511960] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"244.150529ms","start":"2026-06-03T07:33:13.189299Z","end":"2026-06-03T07:33:13.433449Z","steps":["trace[1944511960] 'process raft request' (duration: 243.438003ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:13.433685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.044682ms","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-06-03T07:33:13.433723Z","caller":"traceutil/trace.go:171","msg":"trace[266157384] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1063; }","duration":"242.104613ms","start":"2026-06-03T07:33:13.19161Z","end":"2026-06-03T07:33:13.433714Z","steps":["trace[266157384] 'agreement among raft nodes before linearized reading' (duration: 241.9844ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:13.657388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.237293ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-03T07:33:13.657556Z","caller":"traceutil/trace.go:171","msg":"trace[1256054306] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"218.219313ms","start":"2026-06-03T07:33:13.439315Z","end":"2026-06-03T07:33:13.657535Z","steps":["trace[1256054306] 'process raft request' (duration: 58.726163ms)","trace[1256054306] 'compare' (duration: 158.830475ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T07:33:15.242774Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.049429157s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-03T07:33:15.243148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:33:14.193167Z","time spent":"1.04997684s","remote":"127.0.0.1:48154","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-06-03T07:33:15.50275Z","caller":"traceutil/trace.go:171","msg":"trace[1856813103] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"312.616416ms","start":"2026-06-03T07:33:15.190106Z","end":"2026-06-03T07:33:15.502723Z","steps":["trace[1856813103] 'process raft request' (duration: 312.413222ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:15.503049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:33:15.190078Z","time spent":"312.76962ms","remote":"127.0.0.1:48378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T07:33:15.51415Z","caller":"traceutil/trace.go:171","msg":"trace[1476217141] linearizableReadLoop","detail":"{readStateIndex:1110; appliedIndex:1108; }","duration":"124.86886ms","start":"2026-06-03T07:33:15.389258Z","end":"2026-06-03T07:33:15.514126Z","steps":["trace[1476217141] 'read index received' (duration: 113.275743ms)","trace[1476217141] 'applied index is now lower than readState.Index' (duration: 11.591897ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:33:15.514273Z","caller":"traceutil/trace.go:171","msg":"trace[1653004412] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"269.634113ms","start":"2026-06-03T07:33:15.244519Z","end":"2026-06-03T07:33:15.514153Z","steps":["trace[1653004412] 'process raft request' (duration: 269.432778ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:15.514674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.374881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-03T07:33:15.51476Z","caller":"traceutil/trace.go:171","msg":"trace[729833288] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1067; }","duration":"125.545926ms","start":"2026-06-03T07:33:15.389199Z","end":"2026-06-03T07:33:15.514745Z","steps":["trace[729833288] 'agreement among raft nodes before linearized reading' (duration: 125.164506ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:33:17.802893Z","caller":"traceutil/trace.go:171","msg":"trace[1808220260] linearizableReadLoop","detail":"{readStateIndex:1116; appliedIndex:1115; }","duration":"264.857777ms","start":"2026-06-03T07:33:17.538008Z","end":"2026-06-03T07:33:17.802866Z","steps":["trace[1808220260] 'read index received' (duration: 264.644442ms)","trace[1808220260] 'applied index is now lower than readState.Index' (duration: 211.495µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:33:17.803106Z","caller":"traceutil/trace.go:171","msg":"trace[207956293] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"293.520493ms","start":"2026-06-03T07:33:17.509573Z","end":"2026-06-03T07:33:17.803093Z","steps":["trace[207956293] 'process raft request' (duration: 293.101644ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:17.803391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.362718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-03T07:33:17.803446Z","caller":"traceutil/trace.go:171","msg":"trace[1576957211] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1073; }","duration":"265.47362ms","start":"2026-06-03T07:33:17.53796Z","end":"2026-06-03T07:33:17.803434Z","steps":["trace[1576957211] 'agreement among raft nodes before linearized reading' (duration: 265.288476ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:17.803915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.040473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-03T07:33:17.804018Z","caller":"traceutil/trace.go:171","msg":"trace[662791153] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1073; }","duration":"229.209947ms","start":"2026-06-03T07:33:17.57479Z","end":"2026-06-03T07:33:17.804Z","steps":["trace[662791153] 'agreement among raft nodes before linearized reading' (duration: 228.92116ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:17.804256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.555952ms","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-06-03T07:33:17.80438Z","caller":"traceutil/trace.go:171","msg":"trace[671575617] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1073; }","duration":"228.767007ms","start":"2026-06-03T07:33:17.575589Z","end":"2026-06-03T07:33:17.804356Z","steps":["trace[671575617] 'agreement among raft nodes before linearized reading' (duration: 228.396558ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:17.804548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.893789ms","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-06-03T07:33:17.804643Z","caller":"traceutil/trace.go:171","msg":"trace[1394938807] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1073; }","duration":"229.041203ms","start":"2026-06-03T07:33:17.575581Z","end":"2026-06-03T07:33:17.804623Z","steps":["trace[1394938807] 'agreement among raft nodes before linearized reading' (duration: 228.756447ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:17.80455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.402693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-03T07:33:17.804915Z","caller":"traceutil/trace.go:171","msg":"trace[74013597] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1073; }","duration":"121.801393ms","start":"2026-06-03T07:33:17.68307Z","end":"2026-06-03T07:33:17.804872Z","steps":["trace[74013597] 'agreement among raft nodes before linearized reading' (duration: 121.22033ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:33:36.262108Z","caller":"traceutil/trace.go:171","msg":"trace[1985381940] linearizableReadLoop","detail":"{readStateIndex:1233; appliedIndex:1232; }","duration":"343.515443ms","start":"2026-06-03T07:33:35.918568Z","end":"2026-06-03T07:33:36.262084Z","steps":["trace[1985381940] 'read index received' (duration: 343.322129ms)","trace[1985381940] 'applied index is now lower than readState.Index' (duration: 192.404µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:33:36.262224Z","caller":"traceutil/trace.go:171","msg":"trace[184014783] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"347.885351ms","start":"2026-06-03T07:33:35.914276Z","end":"2026-06-03T07:33:36.262162Z","steps":["trace[184014783] 'process raft request' (duration: 347.639965ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:36.262396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:33:35.914247Z","time spent":"348.056265ms","remote":"127.0.0.1:48378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T07:33:36.262564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.912422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-03T07:33:36.262535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.903892ms","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-06-03T07:33:36.262622Z","caller":"traceutil/trace.go:171","msg":"trace[770074617] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1184; }","duration":"298.010503ms","start":"2026-06-03T07:33:35.964596Z","end":"2026-06-03T07:33:36.262607Z","steps":["trace[770074617] 'agreement among raft nodes before linearized reading' (duration: 297.815469ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:36.262611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.475372ms","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-06-03T07:33:36.26266Z","caller":"traceutil/trace.go:171","msg":"trace[2082884858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1184; }","duration":"344.102756ms","start":"2026-06-03T07:33:35.91853Z","end":"2026-06-03T07:33:36.262632Z","steps":["trace[2082884858] 'agreement among raft nodes before linearized reading' (duration: 343.686767ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:36.262683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.482502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-03T07:33:36.262711Z","caller":"traceutil/trace.go:171","msg":"trace[1404138972] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1184; }","duration":"297.686766ms","start":"2026-06-03T07:33:35.965Z","end":"2026-06-03T07:33:36.262687Z","steps":["trace[1404138972] 'agreement among raft nodes before linearized reading' (duration: 297.39331ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:33:36.262729Z","caller":"traceutil/trace.go:171","msg":"trace[2115784088] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1184; }","duration":"288.549104ms","start":"2026-06-03T07:33:35.974169Z","end":"2026-06-03T07:33:36.262718Z","steps":["trace[2115784088] 'agreement among raft nodes before linearized reading' (duration: 288.36755ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:36.262721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:33:35.918513Z","time spent":"344.198009ms","remote":"127.0.0.1:48378","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-06-03T07:33:36.262814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.048299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-03T07:33:36.262883Z","caller":"traceutil/trace.go:171","msg":"trace[1944597497] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1184; }","duration":"300.174481ms","start":"2026-06-03T07:33:35.962699Z","end":"2026-06-03T07:33:36.262873Z","steps":["trace[1944597497] 'agreement among raft nodes before linearized reading' (duration: 299.66978ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:36.262916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:33:35.962686Z","time spent":"300.221942ms","remote":"127.0.0.1:48378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-03T07:33:36.58035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.613977ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-03T07:33:36.580584Z","caller":"traceutil/trace.go:171","msg":"trace[1589538241] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"305.072811ms","start":"2026-06-03T07:33:36.275484Z","end":"2026-06-03T07:33:36.580556Z","steps":["trace[1589538241] 'process raft request' (duration: 179.144816ms)","trace[1589538241] 'compare' (duration: 125.24781ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T07:33:36.58075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:33:36.275467Z","time spent":"305.231894ms","remote":"127.0.0.1:48378","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-06-03T07:33:36.581925Z","caller":"traceutil/trace.go:171","msg":"trace[770071768] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"306.260277ms","start":"2026-06-03T07:33:36.27564Z","end":"2026-06-03T07:33:36.581901Z","steps":["trace[770071768] 'process raft request' (duration: 306.031602ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:33:36.582006Z","caller":"traceutil/trace.go:171","msg":"trace[1357138655] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"306.323369ms","start":"2026-06-03T07:33:36.275627Z","end":"2026-06-03T07:33:36.58195Z","steps":["trace[1357138655] 'process raft request' (duration: 306.157855ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:36.582077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:33:36.275626Z","time spent":"306.37086ms","remote":"127.0.0.1:48378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T07:33:36.582091Z","caller":"traceutil/trace.go:171","msg":"trace[1124133313] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"305.98868ms","start":"2026-06-03T07:33:36.276063Z","end":"2026-06-03T07:33:36.582052Z","steps":["trace[1124133313] 'process raft request' (duration: 305.766575ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:36.582129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:33:36.275584Z","time spent":"306.494022ms","remote":"127.0.0.1:48378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T07:33:36.582452Z","caller":"traceutil/trace.go:171","msg":"trace[401006295] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"306.319658ms","start":"2026-06-03T07:33:36.276117Z","end":"2026-06-03T07:33:36.582437Z","steps":["trace[401006295] 'process raft request' (duration: 305.741475ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:36.582516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:33:36.276098Z","time spent":"306.385249ms","remote":"127.0.0.1:48378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T07:33:36.582363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:33:36.276051Z","time spent":"306.131443ms","remote":"127.0.0.1:48378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T07:33:50.977278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.530077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-03T07:33:50.977401Z","caller":"traceutil/trace.go:171","msg":"trace[1275815625] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1230; }","duration":"322.712912ms","start":"2026-06-03T07:33:50.654664Z","end":"2026-06-03T07:33:50.977377Z","steps":["trace[1275815625] 'range keys from in-memory index tree' (duration: 322.338603ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:50.977442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:33:50.65462Z","time spent":"322.812494ms","remote":"127.0.0.1:48378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-03T07:33:50.977404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.841816ms","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-06-03T07:33:50.977567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.587878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-03T07:33:50.977595Z","caller":"traceutil/trace.go:171","msg":"trace[1717264385] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1230; }","duration":"328.121752ms","start":"2026-06-03T07:33:50.649441Z","end":"2026-06-03T07:33:50.977563Z","steps":["trace[1717264385] 'range keys from in-memory index tree' (duration: 327.420184ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:50.977565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.50455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-03T07:33:50.977629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.322873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-03T07:33:50.977664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:33:50.649403Z","time spent":"328.247565ms","remote":"127.0.0.1:48378","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-06-03T07:33:50.977706Z","caller":"traceutil/trace.go:171","msg":"trace[19676000] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1230; }","duration":"319.670844ms","start":"2026-06-03T07:33:50.658013Z","end":"2026-06-03T07:33:50.977684Z","steps":["trace[19676000] 'range keys from in-memory index tree' (duration: 318.966718ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:33:50.977733Z","caller":"traceutil/trace.go:171","msg":"trace[1374330054] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1230; }","duration":"322.456665ms","start":"2026-06-03T07:33:50.655249Z","end":"2026-06-03T07:33:50.977706Z","steps":["trace[1374330054] 'range keys from in-memory index tree' (duration: 322.17262ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:50.977768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:33:50.658005Z","time spent":"319.752545ms","remote":"127.0.0.1:48378","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-06-03T07:33:50.977786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:33:50.65524Z","time spent":"322.534087ms","remote":"127.0.0.1:48378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-03T07:33:50.977638Z","caller":"traceutil/trace.go:171","msg":"trace[1953627985] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1230; }","duration":"312.72396ms","start":"2026-06-03T07:33:50.664896Z","end":"2026-06-03T07:33:50.97762Z","steps":["trace[1953627985] 'range keys from in-memory index tree' (duration: 312.118347ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:50.977832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:33:50.664865Z","time spent":"312.955876ms","remote":"127.0.0.1:48378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-03T07:33:51.51111Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8079350582347611091,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-03T07:33:51.561194Z","caller":"traceutil/trace.go:171","msg":"trace[1838198664] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"579.222141ms","start":"2026-06-03T07:33:50.981942Z","end":"2026-06-03T07:33:51.561164Z","steps":["trace[1838198664] 'process raft request' (duration: 578.805622ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:51.562181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:33:50.981918Z","time spent":"579.358764ms","remote":"127.0.0.1:48378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T07:33:51.622961Z","caller":"traceutil/trace.go:171","msg":"trace[938970159] linearizableReadLoop","detail":"{readStateIndex:1287; appliedIndex:1282; }","duration":"612.473906ms","start":"2026-06-03T07:33:51.010457Z","end":"2026-06-03T07:33:51.622931Z","steps":["trace[938970159] 'read index received' (duration: 550.650968ms)","trace[938970159] 'applied index is now lower than readState.Index' (duration: 61.821408ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:33:51.623378Z","caller":"traceutil/trace.go:171","msg":"trace[554798693] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"639.464033ms","start":"2026-06-03T07:33:50.983865Z","end":"2026-06-03T07:33:51.623329Z","steps":["trace[554798693] 'process raft request' (duration: 639.008353ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:51.62394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.459867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T07:33:51.624004Z","caller":"traceutil/trace.go:171","msg":"trace[1974481811] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1235; }","duration":"613.571409ms","start":"2026-06-03T07:33:51.010419Z","end":"2026-06-03T07:33:51.62399Z","steps":["trace[1974481811] 'agreement among raft nodes before linearized reading' (duration: 613.429376ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:51.624009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:33:50.98386Z","time spent":"639.988805ms","remote":"127.0.0.1:48378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T07:33:51.624059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:33:51.010409Z","time spent":"613.62584ms","remote":"127.0.0.1:48108","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-06-03T07:33:51.624348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.569174ms","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-06-03T07:33:51.624383Z","caller":"traceutil/trace.go:171","msg":"trace[406424713] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1235; }","duration":"296.711027ms","start":"2026-06-03T07:33:51.327661Z","end":"2026-06-03T07:33:51.624372Z","steps":["trace[406424713] 'agreement among raft nodes before linearized reading' (duration: 296.651335ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:33:51.624412Z","caller":"traceutil/trace.go:171","msg":"trace[53448452] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"641.787915ms","start":"2026-06-03T07:33:50.982585Z","end":"2026-06-03T07:33:51.624373Z","steps":["trace[53448452] 'process raft request' (duration: 639.922754ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:51.624523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:33:50.982558Z","time spent":"641.909289ms","remote":"127.0.0.1:48378","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-06-03T07:33:51.624704Z","caller":"traceutil/trace.go:171","msg":"trace[2135954089] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"641.496469ms","start":"2026-06-03T07:33:50.983196Z","end":"2026-06-03T07:33:51.624692Z","steps":["trace[2135954089] 'process raft request' (duration: 639.580636ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:51.624775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:33:50.983188Z","time spent":"641.55195ms","remote":"127.0.0.1:48378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T07:33:51.624973Z","caller":"traceutil/trace.go:171","msg":"trace[1104252032] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"641.226362ms","start":"2026-06-03T07:33:50.983736Z","end":"2026-06-03T07:33:51.624962Z","steps":["trace[1104252032] 'process raft request' (duration: 639.096315ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:51.625116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:33:50.983729Z","time spent":"641.334695ms","remote":"127.0.0.1:48378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T07:33:52.692414Z","caller":"traceutil/trace.go:171","msg":"trace[802251988] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"263.540418ms","start":"2026-06-03T07:33:52.428851Z","end":"2026-06-03T07:33:52.692391Z","steps":["trace[802251988] 'process raft request' (duration: 263.404966ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:53.168455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.40497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T07:33:53.168562Z","caller":"traceutil/trace.go:171","msg":"trace[1887968611] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1237; }","duration":"158.559933ms","start":"2026-06-03T07:33:53.009977Z","end":"2026-06-03T07:33:53.168537Z","steps":["trace[1887968611] 'range keys from in-memory index tree' (duration: 158.332468ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:33:53.809492Z","caller":"traceutil/trace.go:171","msg":"trace[472680875] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"169.981593ms","start":"2026-06-03T07:33:53.639486Z","end":"2026-06-03T07:33:53.809468Z","steps":["trace[472680875] 'process raft request' (duration: 169.931552ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:33:53.809596Z","caller":"traceutil/trace.go:171","msg":"trace[576866827] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"174.158976ms","start":"2026-06-03T07:33:53.635384Z","end":"2026-06-03T07:33:53.809543Z","steps":["trace[576866827] 'process raft request' (duration: 81.262384ms)","trace[576866827] 'compare' (duration: 92.544553ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:33:53.809679Z","caller":"traceutil/trace.go:171","msg":"trace[793592167] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"171.348665ms","start":"2026-06-03T07:33:53.638233Z","end":"2026-06-03T07:33:53.809582Z","steps":["trace[793592167] 'process raft request' (duration: 171.093888ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:33:53.809762Z","caller":"traceutil/trace.go:171","msg":"trace[204182953] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"168.3745ms","start":"2026-06-03T07:33:53.641381Z","end":"2026-06-03T07:33:53.809755Z","steps":["trace[204182953] 'process raft request' (duration: 168.060542ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:55.107894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.374264ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-03T07:33:55.10839Z","caller":"traceutil/trace.go:171","msg":"trace[1100858625] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"824.561922ms","start":"2026-06-03T07:33:54.283757Z","end":"2026-06-03T07:33:55.108319Z","steps":["trace[1100858625] 'process raft request' (duration: 403.698898ms)","trace[1100858625] 'compare' (duration: 418.417281ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:33:55.108768Z","caller":"traceutil/trace.go:171","msg":"trace[1833243876] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"449.127186ms","start":"2026-06-03T07:33:54.659628Z","end":"2026-06-03T07:33:55.108755Z","steps":["trace[1833243876] 'process raft request' (duration: 449.026014ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:55.108819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:33:54.6596Z","time spent":"449.197857ms","remote":"127.0.0.1:48378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T07:33:55.10963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:33:54.283717Z","time spent":"825.006222ms","remote":"127.0.0.1:48154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T07:33:55.23539Z","caller":"traceutil/trace.go:171","msg":"trace[884556022] linearizableReadLoop","detail":"{readStateIndex:1298; appliedIndex:1297; }","duration":"347.916783ms","start":"2026-06-03T07:33:54.887442Z","end":"2026-06-03T07:33:55.235358Z","steps":["trace[884556022] 'read index received' (duration: 347.619056ms)","trace[884556022] 'applied index is now lower than readState.Index' (duration: 296.767µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:33:55.235542Z","caller":"traceutil/trace.go:171","msg":"trace[2102997253] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"537.157269ms","start":"2026-06-03T07:33:54.698368Z","end":"2026-06-03T07:33:55.235525Z","steps":["trace[2102997253] 'process raft request' (duration: 536.7897ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:55.235709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:33:54.698352Z","time spent":"537.225121ms","remote":"127.0.0.1:48378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T07:33:55.235745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.277851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T07:33:55.235809Z","caller":"traceutil/trace.go:171","msg":"trace[77141882] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1245; }","duration":"348.393694ms","start":"2026-06-03T07:33:54.887399Z","end":"2026-06-03T07:33:55.235792Z","steps":["trace[77141882] 'agreement among raft nodes before linearized reading' (duration: 348.276732ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:55.235843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:33:54.887375Z","time spent":"348.461975ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-03T07:33:55.237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.910845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-06-03T07:33:55.237535Z","caller":"traceutil/trace.go:171","msg":"trace[45477791] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1245; }","duration":"126.028089ms","start":"2026-06-03T07:33:55.111455Z","end":"2026-06-03T07:33:55.237483Z","steps":["trace[45477791] 'agreement among raft nodes before linearized reading' (duration: 124.192499ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:55.590183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.90603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5331"} {"level":"info","ts":"2026-06-03T07:33:55.590267Z","caller":"traceutil/trace.go:171","msg":"trace[236907105] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1246; }","duration":"174.057403ms","start":"2026-06-03T07:33:55.416182Z","end":"2026-06-03T07:33:55.590239Z","steps":["trace[236907105] 'range keys from in-memory index tree' (duration: 173.665764ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:34:05.424391Z","caller":"traceutil/trace.go:171","msg":"trace[2001254319] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"157.783167ms","start":"2026-06-03T07:34:05.266584Z","end":"2026-06-03T07:34:05.424367Z","steps":["trace[2001254319] 'process raft request' (duration: 157.615103ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:34:05.42541Z","caller":"traceutil/trace.go:171","msg":"trace[610025962] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"122.463821ms","start":"2026-06-03T07:34:05.302922Z","end":"2026-06-03T07:34:05.425386Z","steps":["trace[610025962] 'process raft request' (duration: 122.326248ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:34:06.48231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.734151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-06-03T07:34:06.482506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.083669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-03T07:34:06.482508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.308619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-06-03T07:34:06.482538Z","caller":"traceutil/trace.go:171","msg":"trace[797854338] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1340; }","duration":"569.139151ms","start":"2026-06-03T07:34:05.913387Z","end":"2026-06-03T07:34:06.482526Z","steps":["trace[797854338] 'range keys from in-memory index tree' (duration: 569.000137ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:34:06.482539Z","caller":"traceutil/trace.go:171","msg":"trace[603984684] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1340; }","duration":"568.985368ms","start":"2026-06-03T07:34:05.913528Z","end":"2026-06-03T07:34:06.482514Z","steps":["trace[603984684] 'range keys from in-memory index tree' (duration: 568.593269ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:34:06.482567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:34:05.913372Z","time spent":"569.188442ms","remote":"127.0.0.1:48378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-03T07:34:06.482563Z","caller":"traceutil/trace.go:171","msg":"trace[1372790785] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1340; }","duration":"953.405911ms","start":"2026-06-03T07:34:05.529141Z","end":"2026-06-03T07:34:06.482547Z","steps":["trace[1372790785] 'range keys from in-memory index tree' (duration: 953.168496ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:34:06.482599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:34:05.913523Z","time spent":"569.059779ms","remote":"127.0.0.1:48378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-03T07:34:06.482615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.527009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T07:34:06.482646Z","caller":"traceutil/trace.go:171","msg":"trace[1176034986] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1340; }","duration":"472.572269ms","start":"2026-06-03T07:34:06.010062Z","end":"2026-06-03T07:34:06.482634Z","steps":["trace[1176034986] 'range keys from in-memory index tree' (duration: 472.449067ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:34:06.482668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:34:06.010051Z","time spent":"472.611081ms","remote":"127.0.0.1:48108","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-06-03T07:34:06.482404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.534348ms","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-06-03T07:34:06.482899Z","caller":"traceutil/trace.go:171","msg":"trace[839861949] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1340; }","duration":"569.06702ms","start":"2026-06-03T07:34:05.913824Z","end":"2026-06-03T07:34:06.482891Z","steps":["trace[839861949] 'range keys from in-memory index tree' (duration: 568.422375ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:34:06.482938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:34:05.913811Z","time spent":"569.10624ms","remote":"127.0.0.1:48378","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-06-03T07:34:06.482606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:34:05.52912Z","time spent":"953.473763ms","remote":"127.0.0.1:48378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-03T07:34:06.482966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.969067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-03T07:34:06.482467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.86721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-06-03T07:34:06.483015Z","caller":"traceutil/trace.go:171","msg":"trace[1392856441] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1340; }","duration":"536.437753ms","start":"2026-06-03T07:34:05.946571Z","end":"2026-06-03T07:34:06.483009Z","steps":["trace[1392856441] 'range keys from in-memory index tree' (duration: 535.798239ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:34:06.483016Z","caller":"traceutil/trace.go:171","msg":"trace[1495611145] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1340; }","duration":"823.073689ms","start":"2026-06-03T07:34:05.659928Z","end":"2026-06-03T07:34:06.483002Z","steps":["trace[1495611145] 'range keys from in-memory index tree' (duration: 822.800914ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:34:06.483034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:34:05.946558Z","time spent":"536.470134ms","remote":"127.0.0.1:48378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-06-03T07:34:06.483056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:34:05.659909Z","time spent":"823.13541ms","remote":"127.0.0.1:48378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-03T07:34:06.483697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.183264ms","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-06-03T07:34:06.483754Z","caller":"traceutil/trace.go:171","msg":"trace[866600276] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1340; }","duration":"570.247155ms","start":"2026-06-03T07:34:05.913488Z","end":"2026-06-03T07:34:06.483735Z","steps":["trace[866600276] 'range keys from in-memory index tree' (duration: 569.192152ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:34:06.483797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:34:05.913485Z","time spent":"570.300157ms","remote":"127.0.0.1:48378","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-06-03T07:34:06.708968Z","caller":"traceutil/trace.go:171","msg":"trace[1033937555] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"222.386845ms","start":"2026-06-03T07:34:06.486554Z","end":"2026-06-03T07:34:06.708941Z","steps":["trace[1033937555] 'process raft request' (duration: 222.231941ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:34:06.716994Z","caller":"traceutil/trace.go:171","msg":"trace[1834606242] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"229.285197ms","start":"2026-06-03T07:34:06.487686Z","end":"2026-06-03T07:34:06.716971Z","steps":["trace[1834606242] 'process raft request' (duration: 229.102643ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:34:06.717123Z","caller":"traceutil/trace.go:171","msg":"trace[782824891] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"229.343528ms","start":"2026-06-03T07:34:06.487768Z","end":"2026-06-03T07:34:06.717112Z","steps":["trace[782824891] 'process raft request' (duration: 229.153224ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:34:06.71707Z","caller":"traceutil/trace.go:171","msg":"trace[92807493] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"227.781313ms","start":"2026-06-03T07:34:06.48927Z","end":"2026-06-03T07:34:06.717052Z","steps":["trace[92807493] 'process raft request' (duration: 227.713822ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:34:06.717133Z","caller":"traceutil/trace.go:171","msg":"trace[1754104215] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"229.211655ms","start":"2026-06-03T07:34:06.48789Z","end":"2026-06-03T07:34:06.717101Z","steps":["trace[1754104215] 'process raft request' (duration: 229.052522ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:34:06.717049Z","caller":"traceutil/trace.go:171","msg":"trace[862764875] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"229.262756ms","start":"2026-06-03T07:34:06.487736Z","end":"2026-06-03T07:34:06.716999Z","steps":["trace[862764875] 'process raft request' (duration: 229.157054ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:34:06.717396Z","caller":"traceutil/trace.go:171","msg":"trace[806955251] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"229.206175ms","start":"2026-06-03T07:34:06.488181Z","end":"2026-06-03T07:34:06.717387Z","steps":["trace[806955251] 'process raft request' (duration: 228.783606ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:36:37.85416Z","caller":"traceutil/trace.go:171","msg":"trace[428852936] linearizableReadLoop","detail":"{readStateIndex:2124; appliedIndex:2121; }","duration":"152.267739ms","start":"2026-06-03T07:36:37.701868Z","end":"2026-06-03T07:36:37.854136Z","steps":["trace[428852936] 'read index received' (duration: 151.668856ms)","trace[428852936] 'applied index is now lower than readState.Index' (duration: 597.853µs)"],"step_count":2} {"level":"warn","ts":"2026-06-03T07:36:37.854337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.439993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T07:36:37.854386Z","caller":"traceutil/trace.go:171","msg":"trace[1709659869] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:2039; }","duration":"152.540335ms","start":"2026-06-03T07:36:37.701829Z","end":"2026-06-03T07:36:37.854369Z","steps":["trace[1709659869] 'agreement among raft nodes before linearized reading' (duration: 152.413542ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:36:37.854559Z","caller":"traceutil/trace.go:171","msg":"trace[1223428557] transaction","detail":"{read_only:false; response_revision:2038; number_of_response:1; }","duration":"277.814457ms","start":"2026-06-03T07:36:37.576721Z","end":"2026-06-03T07:36:37.854536Z","steps":["trace[1223428557] 'process raft request' (duration: 277.302215ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:36:37.854563Z","caller":"traceutil/trace.go:171","msg":"trace[384451082] transaction","detail":"{read_only:false; response_revision:2037; number_of_response:1; }","duration":"277.872188ms","start":"2026-06-03T07:36:37.576661Z","end":"2026-06-03T07:36:37.854533Z","steps":["trace[384451082] 'process raft request' (duration: 276.959368ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:36:37.85463Z","caller":"traceutil/trace.go:171","msg":"trace[1928321840] transaction","detail":"{read_only:false; response_revision:2039; number_of_response:1; }","duration":"277.470249ms","start":"2026-06-03T07:36:37.577147Z","end":"2026-06-03T07:36:37.854617Z","steps":["trace[1928321840] 'process raft request' (duration: 276.941478ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:37:38.226045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.484523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:363678"} {"level":"info","ts":"2026-06-03T07:37:38.22614Z","caller":"traceutil/trace.go:171","msg":"trace[218325102] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:2312; }","duration":"179.590245ms","start":"2026-06-03T07:37:38.046529Z","end":"2026-06-03T07:37:38.226119Z","steps":["trace[218325102] 'range keys from in-memory index tree' (duration: 179.187987ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:37:56.106928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.737188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-03T07:37:56.10707Z","caller":"traceutil/trace.go:171","msg":"trace[340044528] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2414; }","duration":"161.966004ms","start":"2026-06-03T07:37:55.945077Z","end":"2026-06-03T07:37:56.107043Z","steps":["trace[340044528] 'range keys from in-memory index tree' (duration: 161.413481ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:37:56.107077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.300023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-03T07:37:56.107164Z","caller":"traceutil/trace.go:171","msg":"trace[1337450985] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2414; }","duration":"167.435805ms","start":"2026-06-03T07:37:55.939708Z","end":"2026-06-03T07:37:56.107144Z","steps":["trace[1337450985] 'range keys from in-memory index tree' (duration: 166.805702ms)"],"step_count":1}