{"level":"warn","ts":"2026-03-30T17:57:01.674398Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-30T17:57:01.674532Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.27: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.27:2380","--initial-cluster=instance=https://199.19.213.27:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.27:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.27:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-30T17:57:01.674617Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-30T17:57:01.674625Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.27:2380"]} {"level":"info","ts":"2026-03-30T17:57:01.674652Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-30T17:57:01.675541Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.27:2379"]} {"level":"info","ts":"2026-03-30T17:57:01.676565Z","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":16,"max-cpu-available":16,"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.27:2380"],"listen-peer-urls":["https://199.19.213.27:2380"],"advertise-client-urls":["https://199.19.213.27:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.27:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.27:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-30T17:57:01.689344Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.67549ms"} {"level":"info","ts":"2026-03-30T17:57:01.747715Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d0494904b1be7692","cluster-id":"7c10a1b21fbd6dff"} {"level":"info","ts":"2026-03-30T17:57:01.747978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0494904b1be7692 switched to configuration voters=()"} {"level":"info","ts":"2026-03-30T17:57:01.748018Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0494904b1be7692 became follower at term 0"} {"level":"info","ts":"2026-03-30T17:57:01.748041Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d0494904b1be7692 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-30T17:57:01.748063Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0494904b1be7692 became follower at term 1"} {"level":"info","ts":"2026-03-30T17:57:01.74813Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0494904b1be7692 switched to configuration voters=(15008607517699634834)"} {"level":"warn","ts":"2026-03-30T17:57:01.770587Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-30T17:57:01.775535Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-30T17:57:01.781121Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-30T17:57:01.787392Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d0494904b1be7692","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-30T17:57:01.787701Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d0494904b1be7692","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-30T17:57:01.787842Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-30T17:57:01.78805Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-30T17:57:01.788068Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-30T17:57:01.790031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0494904b1be7692 switched to configuration voters=(15008607517699634834)"} {"level":"info","ts":"2026-03-30T17:57:01.79113Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7c10a1b21fbd6dff","local-member-id":"d0494904b1be7692","added-peer-id":"d0494904b1be7692","added-peer-peer-urls":["https://199.19.213.27:2380"]} {"level":"info","ts":"2026-03-30T17:57:01.796494Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-30T17:57:01.796647Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.27:2380"} {"level":"info","ts":"2026-03-30T17:57:01.796716Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.27:2380"} {"level":"info","ts":"2026-03-30T17:57:01.796893Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d0494904b1be7692","initial-advertise-peer-urls":["https://199.19.213.27:2380"],"listen-peer-urls":["https://199.19.213.27:2380"],"advertise-client-urls":["https://199.19.213.27:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.27:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-30T17:57:01.796933Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-30T17:57:02.648773Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0494904b1be7692 is starting a new election at term 1"} {"level":"info","ts":"2026-03-30T17:57:02.648866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0494904b1be7692 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-30T17:57:02.648903Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0494904b1be7692 received MsgPreVoteResp from d0494904b1be7692 at term 1"} {"level":"info","ts":"2026-03-30T17:57:02.648928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0494904b1be7692 became candidate at term 2"} {"level":"info","ts":"2026-03-30T17:57:02.648972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0494904b1be7692 received MsgVoteResp from d0494904b1be7692 at term 2"} {"level":"info","ts":"2026-03-30T17:57:02.649007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0494904b1be7692 became leader at term 2"} {"level":"info","ts":"2026-03-30T17:57:02.649032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d0494904b1be7692 elected leader d0494904b1be7692 at term 2"} {"level":"info","ts":"2026-03-30T17:57:02.650999Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-30T17:57:02.652074Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-30T17:57:02.652074Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d0494904b1be7692","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.27:2379]}","request-path":"/0/members/d0494904b1be7692/attributes","cluster-id":"7c10a1b21fbd6dff","publish-timeout":"7s"} {"level":"info","ts":"2026-03-30T17:57:02.652279Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-30T17:57:02.652543Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7c10a1b21fbd6dff","local-member-id":"d0494904b1be7692","cluster-version":"3.5"} {"level":"info","ts":"2026-03-30T17:57:02.652566Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-30T17:57:02.652645Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-30T17:57:02.65269Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-30T17:57:02.652815Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-30T17:57:02.6556Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-30T17:57:02.655754Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.27:2379"} {"level":"info","ts":"2026-03-30T17:57:13.967226Z","caller":"traceutil/trace.go:171","msg":"trace[1011958645] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"536.037509ms","start":"2026-03-30T17:57:13.43116Z","end":"2026-03-30T17:57:13.967198Z","steps":["trace[1011958645] 'process raft request' (duration: 535.756993ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:13.968057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:13.431136Z","time spent":"536.218773ms","remote":"127.0.0.1:44236","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-03-30T17:57:14.19444Z","caller":"traceutil/trace.go:171","msg":"trace[1820878331] linearizableReadLoop","detail":"{readStateIndex:393; appliedIndex:392; }","duration":"129.123334ms","start":"2026-03-30T17:57:14.065279Z","end":"2026-03-30T17:57:14.194402Z","steps":["trace[1820878331] 'read index received' (duration: 128.868558ms)","trace[1820878331] 'applied index is now lower than readState.Index' (duration: 253.346µs)"],"step_count":2} {"level":"info","ts":"2026-03-30T17:57:14.194485Z","caller":"traceutil/trace.go:171","msg":"trace[73549903] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"219.040067ms","start":"2026-03-30T17:57:13.975402Z","end":"2026-03-30T17:57:14.194442Z","steps":["trace[73549903] 'process raft request' (duration: 218.777601ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:14.194829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.454172ms","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-03-30T17:57:14.19492Z","caller":"traceutil/trace.go:171","msg":"trace[953396764] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:386; }","duration":"129.683716ms","start":"2026-03-30T17:57:14.065217Z","end":"2026-03-30T17:57:14.194901Z","steps":["trace[953396764] 'agreement among raft nodes before linearized reading' (duration: 129.330609ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:57:17.537419Z","caller":"traceutil/trace.go:171","msg":"trace[711008797] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"130.950554ms","start":"2026-03-30T17:57:17.406434Z","end":"2026-03-30T17:57:17.537385Z","steps":["trace[711008797] 'process raft request' (duration: 130.743709ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:25.111716Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8544064340814510043,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-30T17:57:25.336443Z","caller":"traceutil/trace.go:171","msg":"trace[363922779] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"767.545877ms","start":"2026-03-30T17:57:24.568865Z","end":"2026-03-30T17:57:25.336411Z","steps":["trace[363922779] 'process raft request' (duration: 767.325913ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:25.336655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:24.56884Z","time spent":"767.69719ms","remote":"127.0.0.1:44236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-30T17:57:26.089343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.138235ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-30T17:57:26.089481Z","caller":"traceutil/trace.go:171","msg":"trace[507971903] linearizableReadLoop","detail":"{readStateIndex:465; appliedIndex:463; }","duration":"1.478747721s","start":"2026-03-30T17:57:24.610713Z","end":"2026-03-30T17:57:26.089461Z","steps":["trace[507971903] 'read index received' (duration: 725.574246ms)","trace[507971903] 'applied index is now lower than readState.Index' (duration: 753.172325ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T17:57:26.089561Z","caller":"traceutil/trace.go:171","msg":"trace[47367702] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"1.390216829s","start":"2026-03-30T17:57:24.699285Z","end":"2026-03-30T17:57:26.089502Z","steps":["trace[47367702] 'process raft request' (duration: 1.044623104s)","trace[47367702] 'compare' (duration: 344.976981ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T17:57:26.089622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.478908824s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-30T17:57:26.089695Z","caller":"traceutil/trace.go:171","msg":"trace[790539287] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:454; }","duration":"1.479042067s","start":"2026-03-30T17:57:24.610636Z","end":"2026-03-30T17:57:26.089678Z","steps":["trace[790539287] 'agreement among raft nodes before linearized reading' (duration: 1.478908784s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:26.089625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.509684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-30T17:57:26.089765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:24.610613Z","time spent":"1.47914465s","remote":"127.0.0.1:44236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-30T17:57:26.089762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.781898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.27\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-30T17:57:26.089818Z","caller":"traceutil/trace.go:171","msg":"trace[831345038] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:454; }","duration":"695.725928ms","start":"2026-03-30T17:57:25.394057Z","end":"2026-03-30T17:57:26.089783Z","steps":["trace[831345038] 'agreement among raft nodes before linearized reading' (duration: 695.530344ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:26.089828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:24.699264Z","time spent":"1.390420953s","remote":"127.0.0.1:44236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-30T17:57:26.089835Z","caller":"traceutil/trace.go:171","msg":"trace[1247740580] range","detail":"{range_begin:/registry/masterleases/199.19.213.27; range_end:; response_count:1; response_revision:454; }","duration":"240.909241ms","start":"2026-03-30T17:57:25.848905Z","end":"2026-03-30T17:57:26.089815Z","steps":["trace[1247740580] 'agreement among raft nodes before linearized reading' (duration: 240.728137ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:26.089904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:25.394031Z","time spent":"695.843881ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-30T17:57:26.634404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.72334ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-03-30T17:57:26.634534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:26.091694Z","time spent":"542.835938ms","remote":"127.0.0.1:44028","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-30T17:57:26.706395Z","caller":"traceutil/trace.go:171","msg":"trace[213277901] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"612.040441ms","start":"2026-03-30T17:57:26.094331Z","end":"2026-03-30T17:57:26.706372Z","steps":["trace[213277901] 'process raft request' (duration: 611.786286ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:26.706561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:26.094299Z","time spent":"612.176594ms","remote":"127.0.0.1:44236","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-03-30T17:57:26.706602Z","caller":"traceutil/trace.go:171","msg":"trace[211386062] linearizableReadLoop","detail":"{readStateIndex:468; appliedIndex:465; }","duration":"450.066304ms","start":"2026-03-30T17:57:26.256507Z","end":"2026-03-30T17:57:26.706573Z","steps":["trace[211386062] 'read index received' (duration: 83.194247ms)","trace[211386062] 'applied index is now lower than readState.Index' (duration: 366.870047ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T17:57:26.706705Z","caller":"traceutil/trace.go:171","msg":"trace[1103856207] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"612.335828ms","start":"2026-03-30T17:57:26.094342Z","end":"2026-03-30T17:57:26.706678Z","steps":["trace[1103856207] 'process raft request' (duration: 611.94349ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:26.70687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:26.094313Z","time spent":"612.47233ms","remote":"127.0.0.1:44236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-30T17:57:26.706873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.332139ms","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-03-30T17:57:26.706999Z","caller":"traceutil/trace.go:171","msg":"trace[1040154796] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:456; }","duration":"450.531043ms","start":"2026-03-30T17:57:26.256441Z","end":"2026-03-30T17:57:26.706972Z","steps":["trace[1040154796] 'agreement among raft nodes before linearized reading' (duration: 450.251327ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:26.707068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:26.256415Z","time spent":"450.638456ms","remote":"127.0.0.1:44236","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-03-30T17:57:26.707262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.447654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T17:57:26.707348Z","caller":"traceutil/trace.go:171","msg":"trace[1495367936] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:457; }","duration":"312.594018ms","start":"2026-03-30T17:57:26.394734Z","end":"2026-03-30T17:57:26.707328Z","steps":["trace[1495367936] 'agreement among raft nodes before linearized reading' (duration: 312.394264ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:26.707398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:26.394708Z","time spent":"312.680989ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-30T17:57:31.65905Z","caller":"traceutil/trace.go:171","msg":"trace[941544510] linearizableReadLoop","detail":"{readStateIndex:480; appliedIndex:479; }","duration":"234.628835ms","start":"2026-03-30T17:57:31.424394Z","end":"2026-03-30T17:57:31.659023Z","steps":["trace[941544510] 'read index received' (duration: 231.216241ms)","trace[941544510] 'applied index is now lower than readState.Index' (duration: 3.410694ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T17:57:31.659237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.803068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-30T17:57:31.659285Z","caller":"traceutil/trace.go:171","msg":"trace[1205960418] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:467; }","duration":"234.951781ms","start":"2026-03-30T17:57:31.424322Z","end":"2026-03-30T17:57:31.659274Z","steps":["trace[1205960418] 'agreement among raft nodes before linearized reading' (duration: 234.804408ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:36.895062Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8544064340814510177,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-30T17:57:37.060358Z","caller":"traceutil/trace.go:171","msg":"trace[486965492] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"994.351132ms","start":"2026-03-30T17:57:36.065965Z","end":"2026-03-30T17:57:37.060316Z","steps":["trace[486965492] 'process raft request' (duration: 994.117447ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:37.060758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:36.065933Z","time spent":"994.549606ms","remote":"127.0.0.1:44036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-30T17:57:37.069711Z","caller":"traceutil/trace.go:171","msg":"trace[1743389150] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"998.44329ms","start":"2026-03-30T17:57:36.071227Z","end":"2026-03-30T17:57:37.06967Z","steps":["trace[1743389150] 'process raft request' (duration: 998.192495ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:57:37.069814Z","caller":"traceutil/trace.go:171","msg":"trace[1157169577] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"998.402349ms","start":"2026-03-30T17:57:36.071382Z","end":"2026-03-30T17:57:37.069785Z","steps":["trace[1157169577] 'process raft request' (duration: 998.172264ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:37.069892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:36.071198Z","time spent":"998.602814ms","remote":"127.0.0.1:44036","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-03-30T17:57:37.07Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:36.071356Z","time spent":"998.544332ms","remote":"127.0.0.1:44036","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-03-30T17:57:37.070001Z","caller":"traceutil/trace.go:171","msg":"trace[659481976] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"980.956661ms","start":"2026-03-30T17:57:36.089012Z","end":"2026-03-30T17:57:37.069969Z","steps":["trace[659481976] 'process raft request' (duration: 980.719786ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:57:37.070033Z","caller":"traceutil/trace.go:171","msg":"trace[1846503055] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"972.485877ms","start":"2026-03-30T17:57:36.097511Z","end":"2026-03-30T17:57:37.069997Z","steps":["trace[1846503055] 'process raft request' (duration: 972.269222ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:57:37.070094Z","caller":"traceutil/trace.go:171","msg":"trace[532773815] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"972.517568ms","start":"2026-03-30T17:57:36.097568Z","end":"2026-03-30T17:57:37.070086Z","steps":["trace[532773815] 'process raft request' (duration: 972.265852ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:37.070141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:36.097564Z","time spent":"972.557589ms","remote":"127.0.0.1:44036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-30T17:57:37.070164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:36.089009Z","time spent":"981.079804ms","remote":"127.0.0.1:44036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-30T17:57:37.070205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:36.097495Z","time spent":"972.607319ms","remote":"127.0.0.1:44036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-30T17:57:37.07005Z","caller":"traceutil/trace.go:171","msg":"trace[1292067328] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"981.077324ms","start":"2026-03-30T17:57:36.088961Z","end":"2026-03-30T17:57:37.070039Z","steps":["trace[1292067328] 'process raft request' (duration: 980.718216ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:37.070353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:36.08895Z","time spent":"981.333129ms","remote":"127.0.0.1:44036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-30T17:57:37.070377Z","caller":"traceutil/trace.go:171","msg":"trace[84019576] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"972.63281ms","start":"2026-03-30T17:57:36.097662Z","end":"2026-03-30T17:57:37.070295Z","steps":["trace[84019576] 'process raft request' (duration: 972.231762ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:37.070639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:36.097658Z","time spent":"972.853916ms","remote":"127.0.0.1:44036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-30T17:57:37.070725Z","caller":"traceutil/trace.go:171","msg":"trace[1965736835] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"967.365036ms","start":"2026-03-30T17:57:36.103345Z","end":"2026-03-30T17:57:37.07071Z","steps":["trace[1965736835] 'process raft request' (duration: 967.041419ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:37.070771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:36.103313Z","time spent":"967.436248ms","remote":"127.0.0.1:44036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-30T17:57:37.070802Z","caller":"traceutil/trace.go:171","msg":"trace[678182363] linearizableReadLoop","detail":"{readStateIndex:538; appliedIndex:525; }","duration":"676.416419ms","start":"2026-03-30T17:57:36.39437Z","end":"2026-03-30T17:57:37.070786Z","steps":["trace[678182363] 'read index received' (duration: 665.725387ms)","trace[678182363] 'applied index is now lower than readState.Index' (duration: 10.690132ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T17:57:37.07085Z","caller":"traceutil/trace.go:171","msg":"trace[228840055] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"955.858307ms","start":"2026-03-30T17:57:36.114982Z","end":"2026-03-30T17:57:37.07084Z","steps":["trace[228840055] 'process raft request' (duration: 955.53034ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:37.070921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:36.114943Z","time spent":"955.937909ms","remote":"127.0.0.1:44036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-30T17:57:37.070972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.594832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T17:57:37.071009Z","caller":"traceutil/trace.go:171","msg":"trace[306085731] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:524; }","duration":"676.687904ms","start":"2026-03-30T17:57:36.394313Z","end":"2026-03-30T17:57:37.071Z","steps":["trace[306085731] 'agreement among raft nodes before linearized reading' (duration: 676.567862ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:37.071034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:36.394284Z","time spent":"676.745806ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-30T17:57:37.071044Z","caller":"traceutil/trace.go:171","msg":"trace[784268876] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"941.115566ms","start":"2026-03-30T17:57:36.129914Z","end":"2026-03-30T17:57:37.071029Z","steps":["trace[784268876] 'process raft request' (duration: 940.653976ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:57:37.071102Z","caller":"traceutil/trace.go:171","msg":"trace[52490487] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"675.392165ms","start":"2026-03-30T17:57:36.395695Z","end":"2026-03-30T17:57:37.071088Z","steps":["trace[52490487] 'process raft request' (duration: 675.028007ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:37.071114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:36.129893Z","time spent":"941.187047ms","remote":"127.0.0.1:44036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-30T17:57:37.070804Z","caller":"traceutil/trace.go:171","msg":"trace[1448113298] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"845.667163ms","start":"2026-03-30T17:57:36.22512Z","end":"2026-03-30T17:57:37.070787Z","steps":["trace[1448113298] 'process raft request' (duration: 845.53716ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:37.071205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:36.395658Z","time spent":"675.488357ms","remote":"127.0.0.1:44236","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-03-30T17:57:37.071814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:36.225099Z","time spent":"846.352558ms","remote":"127.0.0.1:44036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-30T17:57:37.074903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.618878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-30T17:57:37.074947Z","caller":"traceutil/trace.go:171","msg":"trace[735652235] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:526; }","duration":"306.69843ms","start":"2026-03-30T17:57:36.768237Z","end":"2026-03-30T17:57:37.074936Z","steps":["trace[735652235] 'agreement among raft nodes before linearized reading' (duration: 306.582297ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:37.074973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:36.768221Z","time spent":"306.74635ms","remote":"127.0.0.1:44236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-30T17:57:37.075004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.860365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-30T17:57:37.075195Z","caller":"traceutil/trace.go:171","msg":"trace[928529220] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:526; }","duration":"308.102751ms","start":"2026-03-30T17:57:36.76707Z","end":"2026-03-30T17:57:37.075173Z","steps":["trace[928529220] 'agreement among raft nodes before linearized reading' (duration: 307.871386ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:37.075232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.53806ms","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-03-30T17:57:37.075242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:36.767044Z","time spent":"308.187742ms","remote":"127.0.0.1:44236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-30T17:57:37.075256Z","caller":"traceutil/trace.go:171","msg":"trace[840069552] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:526; }","duration":"314.617681ms","start":"2026-03-30T17:57:36.760632Z","end":"2026-03-30T17:57:37.07525Z","steps":["trace[840069552] 'agreement among raft nodes before linearized reading' (duration: 314.57125ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:37.075416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:36.760605Z","time spent":"314.805066ms","remote":"127.0.0.1:44236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-30T17:57:42.279349Z","caller":"traceutil/trace.go:171","msg":"trace[1599870279] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"128.509251ms","start":"2026-03-30T17:57:42.150817Z","end":"2026-03-30T17:57:42.279327Z","steps":["trace[1599870279] 'process raft request' (duration: 127.900538ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:57:43.654722Z","caller":"traceutil/trace.go:171","msg":"trace[1125674022] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"358.360752ms","start":"2026-03-30T17:57:43.296328Z","end":"2026-03-30T17:57:43.654689Z","steps":["trace[1125674022] 'process raft request' (duration: 357.685468ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:43.654927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:43.296289Z","time spent":"358.533786ms","remote":"127.0.0.1:44082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-30T17:57:44.288399Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8544064340814510421,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-30T17:57:44.604475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.102155ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-30T17:57:44.604592Z","caller":"traceutil/trace.go:171","msg":"trace[199407872] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:642; }","duration":"816.936611ms","start":"2026-03-30T17:57:43.787635Z","end":"2026-03-30T17:57:44.604572Z","steps":["trace[199407872] 'read index received' (duration: 582.615371ms)","trace[199407872] 'applied index is now lower than readState.Index' (duration: 234.31982ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T17:57:44.605225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.413367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4j5bj\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-03-30T17:57:44.605334Z","caller":"traceutil/trace.go:171","msg":"trace[1329216784] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4j5bj; range_end:; response_count:1; response_revision:622; }","duration":"569.58341ms","start":"2026-03-30T17:57:44.035722Z","end":"2026-03-30T17:57:44.605305Z","steps":["trace[1329216784] 'agreement among raft nodes before linearized reading' (duration: 569.053539ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:44.605382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:44.035683Z","time spent":"569.689992ms","remote":"127.0.0.1:44166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-4j5bj\" "} {"level":"warn","ts":"2026-03-30T17:57:44.605404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.672297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-30T17:57:44.605522Z","caller":"traceutil/trace.go:171","msg":"trace[1744745596] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:622; }","duration":"817.924903ms","start":"2026-03-30T17:57:43.787567Z","end":"2026-03-30T17:57:44.605492Z","steps":["trace[1744745596] 'agreement among raft nodes before linearized reading' (duration: 817.532235ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:44.605572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:43.787539Z","time spent":"818.021685ms","remote":"127.0.0.1:44236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-30T17:57:44.605621Z","caller":"traceutil/trace.go:171","msg":"trace[1779041647] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"887.532613ms","start":"2026-03-30T17:57:43.71805Z","end":"2026-03-30T17:57:44.605583Z","steps":["trace[1779041647] 'process raft request' (duration: 652.232612ms)","trace[1779041647] 'compare' (duration: 233.86969ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T17:57:44.605722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:43.718013Z","time spent":"887.664636ms","remote":"127.0.0.1:44500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-30T17:57:44.605689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.460918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T17:57:44.605787Z","caller":"traceutil/trace.go:171","msg":"trace[774535172] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"208.604901ms","start":"2026-03-30T17:57:44.397165Z","end":"2026-03-30T17:57:44.60577Z","steps":["trace[774535172] 'agreement among raft nodes before linearized reading' (duration: 208.465798ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:57:44.804386Z","caller":"traceutil/trace.go:171","msg":"trace[1901727464] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"182.727481ms","start":"2026-03-30T17:57:44.621631Z","end":"2026-03-30T17:57:44.804358Z","steps":["trace[1901727464] 'process raft request' (duration: 173.9739ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:45.640732Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8544064340814510431,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-30T17:57:46.141479Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8544064340814510431,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-30T17:57:46.641701Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8544064340814510431,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-30T17:57:47.142134Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8544064340814510431,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-30T17:57:47.280397Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.197004959s","expected-duration":"1s"} {"level":"info","ts":"2026-03-30T17:57:47.281765Z","caller":"traceutil/trace.go:171","msg":"trace[1622462230] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:645; }","duration":"2.141985814s","start":"2026-03-30T17:57:45.13976Z","end":"2026-03-30T17:57:47.281746Z","steps":["trace[1622462230] 'read index received' (duration: 2.140842029s)","trace[1622462230] 'applied index is now lower than readState.Index' (duration: 1.143195ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T17:57:47.281842Z","caller":"traceutil/trace.go:171","msg":"trace[1637392357] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"2.199502503s","start":"2026-03-30T17:57:45.082333Z","end":"2026-03-30T17:57:47.281836Z","steps":["trace[1637392357] 'process raft request' (duration: 2.198528991s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:47.282116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:45.082305Z","time spent":"2.199556795s","remote":"127.0.0.1:44082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-30T17:57:47.282404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.142629689s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-30T17:57:47.282407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"888.249991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T17:57:47.282474Z","caller":"traceutil/trace.go:171","msg":"trace[1958146570] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:625; }","duration":"2.142803372s","start":"2026-03-30T17:57:45.139654Z","end":"2026-03-30T17:57:47.282457Z","steps":["trace[1958146570] 'agreement among raft nodes before linearized reading' (duration: 2.142557966s)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:57:47.282494Z","caller":"traceutil/trace.go:171","msg":"trace[733850990] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:625; }","duration":"888.400334ms","start":"2026-03-30T17:57:46.394074Z","end":"2026-03-30T17:57:47.282474Z","steps":["trace[733850990] 'agreement among raft nodes before linearized reading' (duration: 888.21029ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:47.282512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:45.139626Z","time spent":"2.142879254s","remote":"127.0.0.1:44236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-30T17:57:47.282513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.130906372s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-30T17:57:47.282484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.142337092s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-03-30T17:57:47.282543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:46.394049Z","time spent":"888.486805ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-30T17:57:47.282598Z","caller":"traceutil/trace.go:171","msg":"trace[555724370] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:625; }","duration":"2.142501876s","start":"2026-03-30T17:57:45.140064Z","end":"2026-03-30T17:57:47.282565Z","steps":["trace[555724370] 'agreement among raft nodes before linearized reading' (duration: 2.142144868s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:47.28259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.389756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-30T17:57:47.282576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.432107439s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.27\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-03-30T17:57:47.282654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:45.140051Z","time spent":"2.142593778s","remote":"127.0.0.1:44236","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":"info","ts":"2026-03-30T17:57:47.282672Z","caller":"traceutil/trace.go:171","msg":"trace[1570547477] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:625; }","duration":"599.498398ms","start":"2026-03-30T17:57:46.683151Z","end":"2026-03-30T17:57:47.282649Z","steps":["trace[1570547477] 'agreement among raft nodes before linearized reading' (duration: 599.178441ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:57:47.282681Z","caller":"traceutil/trace.go:171","msg":"trace[291700624] range","detail":"{range_begin:/registry/masterleases/199.19.213.27; range_end:; response_count:1; response_revision:625; }","duration":"1.432286653s","start":"2026-03-30T17:57:45.850375Z","end":"2026-03-30T17:57:47.282662Z","steps":["trace[291700624] 'agreement among raft nodes before linearized reading' (duration: 1.432040038s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:47.282722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:46.683077Z","time spent":"599.637661ms","remote":"127.0.0.1:44236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-30T17:57:47.282724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:45.850327Z","time spent":"1.432387585s","remote":"127.0.0.1:44028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.27\" "} {"level":"info","ts":"2026-03-30T17:57:47.28254Z","caller":"traceutil/trace.go:171","msg":"trace[537781623] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:625; }","duration":"2.130978313s","start":"2026-03-30T17:57:45.151555Z","end":"2026-03-30T17:57:47.282533Z","steps":["trace[537781623] 'agreement among raft nodes before linearized reading' (duration: 2.130869701s)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:47.282812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:45.151524Z","time spent":"2.13128078s","remote":"127.0.0.1:44236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-30T17:57:47.863788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:47.446766Z","time spent":"417.007491ms","remote":"127.0.0.1:51018","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-30T17:57:47.864072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.929531ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-03-30T17:57:47.864359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:47.283678Z","time spent":"580.667146ms","remote":"127.0.0.1:44028","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-30T17:57:47.865628Z","caller":"traceutil/trace.go:171","msg":"trace[488761649] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"577.534266ms","start":"2026-03-30T17:57:47.288069Z","end":"2026-03-30T17:57:47.865603Z","steps":["trace[488761649] 'process raft request' (duration: 577.464895ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:47.86578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:47.288021Z","time spent":"577.677249ms","remote":"127.0.0.1:44236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-30T17:57:47.866271Z","caller":"traceutil/trace.go:171","msg":"trace[1766045959] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"579.174852ms","start":"2026-03-30T17:57:47.287006Z","end":"2026-03-30T17:57:47.866181Z","steps":["trace[1766045959] 'process raft request' (duration: 577.25169ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:47.866343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:47.286981Z","time spent":"579.329165ms","remote":"127.0.0.1:44236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-30T17:57:47.866163Z","caller":"traceutil/trace.go:171","msg":"trace[336880893] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"574.291666ms","start":"2026-03-30T17:57:47.29184Z","end":"2026-03-30T17:57:47.866131Z","steps":["trace[336880893] 'process raft request' (duration: 573.723523ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:47.866529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:47.29181Z","time spent":"574.593052ms","remote":"127.0.0.1:44236","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-03-30T17:57:47.866558Z","caller":"traceutil/trace.go:171","msg":"trace[381949747] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"579.527399ms","start":"2026-03-30T17:57:47.287006Z","end":"2026-03-30T17:57:47.866533Z","steps":["trace[381949747] 'process raft request' (duration: 578.479497ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:47.866689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:47.286981Z","time spent":"579.633012ms","remote":"127.0.0.1:44236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-30T17:57:47.866942Z","caller":"traceutil/trace.go:171","msg":"trace[772704984] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"555.401533ms","start":"2026-03-30T17:57:47.311509Z","end":"2026-03-30T17:57:47.86691Z","steps":["trace[772704984] 'process raft request' (duration: 554.333559ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:57:47.866966Z","caller":"traceutil/trace.go:171","msg":"trace[1652332604] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"523.864651ms","start":"2026-03-30T17:57:47.343074Z","end":"2026-03-30T17:57:47.866939Z","steps":["trace[1652332604] 'process raft request' (duration: 523.768769ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:47.867093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:47.31149Z","time spent":"555.513645ms","remote":"127.0.0.1:44236","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-03-30T17:57:47.8671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:47.343053Z","time spent":"523.970014ms","remote":"127.0.0.1:44222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-30T17:57:48.145972Z","caller":"traceutil/trace.go:171","msg":"trace[1405135446] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"206.343349ms","start":"2026-03-30T17:57:47.939588Z","end":"2026-03-30T17:57:48.145931Z","steps":["trace[1405135446] 'process raft request' (duration: 206.058973ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:57:48.228111Z","caller":"traceutil/trace.go:171","msg":"trace[1005802818] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"174.723426ms","start":"2026-03-30T17:57:48.05335Z","end":"2026-03-30T17:57:48.228073Z","steps":["trace[1005802818] 'process raft request' (duration: 127.228737ms)","trace[1005802818] 'compare' (duration: 47.265174ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T17:57:48.25366Z","caller":"traceutil/trace.go:171","msg":"trace[1438453691] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"101.607185ms","start":"2026-03-30T17:57:48.152015Z","end":"2026-03-30T17:57:48.253622Z","steps":["trace[1438453691] 'process raft request' (duration: 101.222706ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:57:48.25373Z","caller":"traceutil/trace.go:171","msg":"trace[134588957] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"100.253595ms","start":"2026-03-30T17:57:48.153449Z","end":"2026-03-30T17:57:48.253703Z","steps":["trace[134588957] 'process raft request' (duration: 100.01706ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:57:48.253865Z","caller":"traceutil/trace.go:171","msg":"trace[533831700] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"100.47227ms","start":"2026-03-30T17:57:48.153312Z","end":"2026-03-30T17:57:48.253784Z","steps":["trace[533831700] 'process raft request' (duration: 100.07946ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:57:48.943804Z","caller":"traceutil/trace.go:171","msg":"trace[998705396] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"133.602266ms","start":"2026-03-30T17:57:48.810168Z","end":"2026-03-30T17:57:48.94377Z","steps":["trace[998705396] 'process raft request' (duration: 133.375871ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:57:50.502853Z","caller":"traceutil/trace.go:171","msg":"trace[1738311383] linearizableReadLoop","detail":"{readStateIndex:693; appliedIndex:692; }","duration":"107.80634ms","start":"2026-03-30T17:57:50.395021Z","end":"2026-03-30T17:57:50.502827Z","steps":["trace[1738311383] 'read index received' (duration: 107.568025ms)","trace[1738311383] 'applied index is now lower than readState.Index' (duration: 237.335µs)"],"step_count":2} {"level":"warn","ts":"2026-03-30T17:57:50.50301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.972835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T17:57:50.50309Z","caller":"traceutil/trace.go:171","msg":"trace[119579272] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:671; }","duration":"108.122828ms","start":"2026-03-30T17:57:50.394954Z","end":"2026-03-30T17:57:50.503077Z","steps":["trace[119579272] 'agreement among raft nodes before linearized reading' (duration: 107.978535ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:57:50.503059Z","caller":"traceutil/trace.go:171","msg":"trace[629140779] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"149.869752ms","start":"2026-03-30T17:57:50.353117Z","end":"2026-03-30T17:57:50.502987Z","steps":["trace[629140779] 'process raft request' (duration: 149.544204ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:52.60793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.780305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-03-30T17:57:52.608076Z","caller":"traceutil/trace.go:171","msg":"trace[1712068677] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:704; }","duration":"257.996599ms","start":"2026-03-30T17:57:52.350047Z","end":"2026-03-30T17:57:52.608043Z","steps":["trace[1712068677] 'range keys from in-memory index tree' (duration: 257.508749ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:58:04.001798Z","caller":"traceutil/trace.go:171","msg":"trace[319586638] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"842.388147ms","start":"2026-03-30T17:58:03.159373Z","end":"2026-03-30T17:58:04.001761Z","steps":["trace[319586638] 'process raft request' (duration: 842.06525ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:04.002142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:58:03.159329Z","time spent":"842.691574ms","remote":"127.0.0.1:44166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4024,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-30T17:58:04.116151Z","caller":"traceutil/trace.go:171","msg":"trace[1334706279] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"108.22671ms","start":"2026-03-30T17:58:04.007896Z","end":"2026-03-30T17:58:04.116123Z","steps":["trace[1334706279] 'process raft request' (duration: 108.038536ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:58:04.116415Z","caller":"traceutil/trace.go:171","msg":"trace[2120042393] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"107.670728ms","start":"2026-03-30T17:58:04.008735Z","end":"2026-03-30T17:58:04.116406Z","steps":["trace[2120042393] 'process raft request' (duration: 107.565075ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:58:04.116455Z","caller":"traceutil/trace.go:171","msg":"trace[50389801] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"108.221351ms","start":"2026-03-30T17:58:04.008193Z","end":"2026-03-30T17:58:04.116414Z","steps":["trace[50389801] 'process raft request' (duration: 107.891214ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:58:07.457517Z","caller":"traceutil/trace.go:171","msg":"trace[927186834] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"128.054645ms","start":"2026-03-30T17:58:07.329415Z","end":"2026-03-30T17:58:07.45747Z","steps":["trace[927186834] 'process raft request' (duration: 59.722658ms)","trace[927186834] 'compare' (duration: 68.096062ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T17:58:25.33689Z","caller":"traceutil/trace.go:171","msg":"trace[830930152] transaction","detail":"{read_only:false; response_revision:1135; number_of_response:1; }","duration":"426.338776ms","start":"2026-03-30T17:58:24.91052Z","end":"2026-03-30T17:58:25.336859Z","steps":["trace[830930152] 'process raft request' (duration: 425.989228ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:25.337204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:58:24.910482Z","time spent":"426.567381ms","remote":"127.0.0.1:44236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-30T17:58:26.746167Z","caller":"traceutil/trace.go:171","msg":"trace[769685378] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"255.78745ms","start":"2026-03-30T17:58:26.49035Z","end":"2026-03-30T17:58:26.746138Z","steps":["trace[769685378] 'process raft request' (duration: 255.615837ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:58:33.530523Z","caller":"traceutil/trace.go:171","msg":"trace[1162258563] linearizableReadLoop","detail":"{readStateIndex:1233; appliedIndex:1232; }","duration":"135.969708ms","start":"2026-03-30T17:58:33.394528Z","end":"2026-03-30T17:58:33.530497Z","steps":["trace[1162258563] 'read index received' (duration: 135.731213ms)","trace[1162258563] 'applied index is now lower than readState.Index' (duration: 237.585µs)"],"step_count":2} {"level":"warn","ts":"2026-03-30T17:58:33.530675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.127541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T17:58:33.530644Z","caller":"traceutil/trace.go:171","msg":"trace[772940550] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"163.980021ms","start":"2026-03-30T17:58:33.366617Z","end":"2026-03-30T17:58:33.530597Z","steps":["trace[772940550] 'process raft request' (duration: 163.706915ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:58:33.53072Z","caller":"traceutil/trace.go:171","msg":"trace[2145051307] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1199; }","duration":"136.199823ms","start":"2026-03-30T17:58:33.394507Z","end":"2026-03-30T17:58:33.530707Z","steps":["trace[2145051307] 'agreement among raft nodes before linearized reading' (duration: 136.100921ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:59:00.665993Z","caller":"traceutil/trace.go:171","msg":"trace[1129521923] linearizableReadLoop","detail":"{readStateIndex:1359; appliedIndex:1358; }","duration":"127.923078ms","start":"2026-03-30T17:59:00.53804Z","end":"2026-03-30T17:59:00.665963Z","steps":["trace[1129521923] 'read index received' (duration: 96.764664ms)","trace[1129521923] 'applied index is now lower than readState.Index' (duration: 31.156924ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T17:59:00.666094Z","caller":"traceutil/trace.go:171","msg":"trace[1180920316] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"130.171819ms","start":"2026-03-30T17:59:00.535882Z","end":"2026-03-30T17:59:00.666054Z","steps":["trace[1180920316] 'process raft request' (duration: 98.902301ms)","trace[1180920316] 'compare' (duration: 31.068592ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T17:59:00.666277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.200685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-30T17:59:00.666343Z","caller":"traceutil/trace.go:171","msg":"trace[913273923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1320; }","duration":"128.329037ms","start":"2026-03-30T17:59:00.537997Z","end":"2026-03-30T17:59:00.666326Z","steps":["trace[913273923] 'agreement among raft nodes before linearized reading' (duration: 128.114853ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:59:00.666353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.93754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-30T17:59:00.666401Z","caller":"traceutil/trace.go:171","msg":"trace[684473937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1320; }","duration":"107.996872ms","start":"2026-03-30T17:59:00.558389Z","end":"2026-03-30T17:59:00.666386Z","steps":["trace[684473937] 'agreement among raft nodes before linearized reading' (duration: 107.890639ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:59:00.666469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.200585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-30T17:59:00.666577Z","caller":"traceutil/trace.go:171","msg":"trace[942344922] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1320; }","duration":"128.338668ms","start":"2026-03-30T17:59:00.538214Z","end":"2026-03-30T17:59:00.666553Z","steps":["trace[942344922] 'agreement among raft nodes before linearized reading' (duration: 128.054612ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:59:00.79979Z","caller":"traceutil/trace.go:171","msg":"trace[2120121839] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"129.399281ms","start":"2026-03-30T17:59:00.670365Z","end":"2026-03-30T17:59:00.799764Z","steps":["trace[2120121839] 'process raft request' (duration: 116.908877ms)","trace[2120121839] 'compare' (duration: 12.345231ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T17:59:00.800164Z","caller":"traceutil/trace.go:171","msg":"trace[74757038] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"128.803638ms","start":"2026-03-30T17:59:00.671173Z","end":"2026-03-30T17:59:00.799977Z","steps":["trace[74757038] 'process raft request' (duration: 128.552523ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:59:09.146408Z","caller":"traceutil/trace.go:171","msg":"trace[1259996593] linearizableReadLoop","detail":"{readStateIndex:1391; appliedIndex:1389; }","duration":"163.552341ms","start":"2026-03-30T17:59:08.982821Z","end":"2026-03-30T17:59:09.146373Z","steps":["trace[1259996593] 'read index received' (duration: 42.999864ms)","trace[1259996593] 'applied index is now lower than readState.Index' (duration: 120.550997ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T17:59:09.146448Z","caller":"traceutil/trace.go:171","msg":"trace[546983993] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"170.436003ms","start":"2026-03-30T17:59:08.97595Z","end":"2026-03-30T17:59:09.146386Z","steps":["trace[546983993] 'process raft request' (duration: 84.197379ms)","trace[546983993] 'compare' (duration: 85.933657ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T17:59:09.146705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.863189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T17:59:09.146774Z","caller":"traceutil/trace.go:171","msg":"trace[1359233592] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1351; }","duration":"163.967551ms","start":"2026-03-30T17:59:08.982789Z","end":"2026-03-30T17:59:09.146757Z","steps":["trace[1359233592] 'agreement among raft nodes before linearized reading' (duration: 163.718485ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:59:23.219227Z","caller":"traceutil/trace.go:171","msg":"trace[1115380294] transaction","detail":"{read_only:false; response_revision:1403; number_of_response:1; }","duration":"101.174521ms","start":"2026-03-30T17:59:23.118019Z","end":"2026-03-30T17:59:23.219193Z","steps":["trace[1115380294] 'process raft request' (duration: 79.601359ms)","trace[1115380294] 'compare' (duration: 21.440699ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T18:06:57.738652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.586432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-03-30T18:06:57.738803Z","caller":"traceutil/trace.go:171","msg":"trace[57836455] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2935; }","duration":"109.775865ms","start":"2026-03-30T18:06:57.629001Z","end":"2026-03-30T18:06:57.738777Z","steps":["trace[57836455] 'range keys from in-memory index tree' (duration: 109.190782ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T18:07:02.700474Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":1945} {"level":"info","ts":"2026-03-30T18:07:02.991623Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":1945,"took":"290.512297ms","hash":1854053711,"current-db-size-bytes":27611136,"current-db-size":"28 MB","current-db-size-in-use-bytes":9113600,"current-db-size-in-use":"9.1 MB"} {"level":"info","ts":"2026-03-30T18:07:02.991727Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1854053711,"revision":1945,"compact-revision":-1}