{"level":"warn","ts":"2026-04-20T05:50:27.973289Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T05:50:27.973441Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.125: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.125:2380","--initial-cluster=instance=https://199.19.213.125:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.125:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.125:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-20T05:50:27.973556Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T05:50:27.97358Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.125:2380"]} {"level":"info","ts":"2026-04-20T05:50:27.973639Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-20T05:50:27.974473Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.125:2379"]} {"level":"info","ts":"2026-04-20T05:50:27.974578Z","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.125:2380"],"listen-peer-urls":["https://199.19.213.125:2380"],"advertise-client-urls":["https://199.19.213.125:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.125:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.125:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-20T05:50:28.044536Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"69.652806ms"} {"level":"info","ts":"2026-04-20T05:50:28.07294Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b448fdce642f2755","cluster-id":"66f7e5dc9a72c5e3"} {"level":"info","ts":"2026-04-20T05:50:28.073128Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b448fdce642f2755 switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T05:50:28.073164Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b448fdce642f2755 became follower at term 0"} {"level":"info","ts":"2026-04-20T05:50:28.073185Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b448fdce642f2755 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T05:50:28.0732Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b448fdce642f2755 became follower at term 1"} {"level":"info","ts":"2026-04-20T05:50:28.073274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b448fdce642f2755 switched to configuration voters=(12990912188036097877)"} {"level":"warn","ts":"2026-04-20T05:50:28.081619Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T05:50:28.083871Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T05:50:28.085272Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-20T05:50:28.087303Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b448fdce642f2755","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T05:50:28.08759Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b448fdce642f2755","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T05:50:28.087783Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-20T05:50:28.087831Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-20T05:50:28.087837Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-20T05:50:28.088222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b448fdce642f2755 switched to configuration voters=(12990912188036097877)"} {"level":"info","ts":"2026-04-20T05:50:28.088365Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"66f7e5dc9a72c5e3","local-member-id":"b448fdce642f2755","added-peer-id":"b448fdce642f2755","added-peer-peer-urls":["https://199.19.213.125:2380"]} {"level":"info","ts":"2026-04-20T05:50:28.094045Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-20T05:50:28.094169Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.125:2380"} {"level":"info","ts":"2026-04-20T05:50:28.094203Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.125:2380"} {"level":"info","ts":"2026-04-20T05:50:28.094407Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b448fdce642f2755","initial-advertise-peer-urls":["https://199.19.213.125:2380"],"listen-peer-urls":["https://199.19.213.125:2380"],"advertise-client-urls":["https://199.19.213.125:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.125:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T05:50:28.094464Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T05:50:28.374144Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b448fdce642f2755 is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T05:50:28.374221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b448fdce642f2755 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T05:50:28.374266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b448fdce642f2755 received MsgPreVoteResp from b448fdce642f2755 at term 1"} {"level":"info","ts":"2026-04-20T05:50:28.374293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b448fdce642f2755 became candidate at term 2"} {"level":"info","ts":"2026-04-20T05:50:28.374309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b448fdce642f2755 received MsgVoteResp from b448fdce642f2755 at term 2"} {"level":"info","ts":"2026-04-20T05:50:28.374325Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b448fdce642f2755 became leader at term 2"} {"level":"info","ts":"2026-04-20T05:50:28.37434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b448fdce642f2755 elected leader b448fdce642f2755 at term 2"} {"level":"info","ts":"2026-04-20T05:50:28.375378Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b448fdce642f2755","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.125:2379]}","request-path":"/0/members/b448fdce642f2755/attributes","cluster-id":"66f7e5dc9a72c5e3","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T05:50:28.375594Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T05:50:28.375427Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T05:50:28.375828Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T05:50:28.375907Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T05:50:28.375848Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T05:50:28.377666Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"66f7e5dc9a72c5e3","local-member-id":"b448fdce642f2755","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T05:50:28.377813Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T05:50:28.377864Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T05:50:28.378105Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.125:2379"} {"level":"info","ts":"2026-04-20T05:50:28.380121Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-20T05:50:33.739771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.636034ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"warn","ts":"2026-04-20T05:50:33.740302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.301732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-20T05:50:33.74035Z","caller":"traceutil/trace.go:171","msg":"trace[1806776157] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:227; }","duration":"386.398364ms","start":"2026-04-20T05:50:33.35394Z","end":"2026-04-20T05:50:33.740339Z","steps":["trace[1806776157] 'agreement among raft nodes before linearized reading' (duration: 386.272862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:50:33.740387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:50:33.353917Z","time spent":"386.462594ms","remote":"127.0.0.1:42716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-04-20T05:50:33.740256Z","caller":"traceutil/trace.go:171","msg":"trace[74119959] transaction","detail":"{read_only:false; response_revision:227; number_of_response:1; }","duration":"418.18766ms","start":"2026-04-20T05:50:33.321963Z","end":"2026-04-20T05:50:33.740151Z","steps":["trace[74119959] 'process raft request' (duration: 201.624283ms)","trace[74119959] 'compare' (duration: 215.490561ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:50:33.740114Z","caller":"traceutil/trace.go:171","msg":"trace[2128890430] linearizableReadLoop","detail":"{readStateIndex:232; appliedIndex:231; }","duration":"386.107439ms","start":"2026-04-20T05:50:33.353988Z","end":"2026-04-20T05:50:33.740095Z","steps":["trace[2128890430] 'read index received' (duration: 169.557662ms)","trace[2128890430] 'applied index is now lower than readState.Index' (duration: 216.548567ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:50:33.740855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:50:33.321934Z","time spent":"418.81608ms","remote":"127.0.0.1:42508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":164,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T05:50:33.783722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:50:33.354033Z","time spent":"429.681762ms","remote":"127.0.0.1:42390","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-20T05:50:33.783807Z","caller":"traceutil/trace.go:171","msg":"trace[1817533429] transaction","detail":"{read_only:false; number_of_response:0; response_revision:227; }","duration":"309.406129ms","start":"2026-04-20T05:50:33.474391Z","end":"2026-04-20T05:50:33.783797Z","steps":["trace[1817533429] 'process raft request' (duration: 309.269917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:50:33.783889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:50:33.474364Z","time spent":"309.48107ms","remote":"127.0.0.1:42486","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T05:50:33.783986Z","caller":"traceutil/trace.go:171","msg":"trace[1943128690] transaction","detail":"{read_only:false; response_revision:228; number_of_response:1; }","duration":"308.8408ms","start":"2026-04-20T05:50:33.475089Z","end":"2026-04-20T05:50:33.78393Z","steps":["trace[1943128690] 'process raft request' (duration: 308.653177ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:50:33.784043Z","caller":"traceutil/trace.go:171","msg":"trace[1394735048] transaction","detail":"{read_only:false; response_revision:229; number_of_response:1; }","duration":"308.448094ms","start":"2026-04-20T05:50:33.475508Z","end":"2026-04-20T05:50:33.783956Z","steps":["trace[1394735048] 'process raft request' (duration: 308.314143ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:50:33.784159Z","caller":"traceutil/trace.go:171","msg":"trace[242281930] transaction","detail":"{read_only:false; response_revision:230; number_of_response:1; }","duration":"307.309857ms","start":"2026-04-20T05:50:33.476839Z","end":"2026-04-20T05:50:33.784149Z","steps":["trace[242281930] 'process raft request' (duration: 307.017153ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:50:33.784153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:50:33.47508Z","time spent":"309.001722ms","remote":"127.0.0.1:42486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T05:50:33.78423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:50:33.476829Z","time spent":"307.381208ms","remote":"127.0.0.1:42486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T05:50:33.783761Z","caller":"traceutil/trace.go:171","msg":"trace[1289979108] transaction","detail":"{read_only:false; number_of_response:0; response_revision:227; }","duration":"333.171685ms","start":"2026-04-20T05:50:33.450567Z","end":"2026-04-20T05:50:33.783738Z","steps":["trace[1289979108] 'process raft request' (duration: 333.027653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:50:33.78436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:50:33.450541Z","time spent":"333.795975ms","remote":"127.0.0.1:42474","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T05:50:33.784383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.789335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T05:50:33.784429Z","caller":"traceutil/trace.go:171","msg":"trace[221642963] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:230; }","duration":"421.874776ms","start":"2026-04-20T05:50:33.362541Z","end":"2026-04-20T05:50:33.784415Z","steps":["trace[221642963] 'agreement among raft nodes before linearized reading' (duration: 421.673612ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:50:33.784353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:50:33.475499Z","time spent":"308.780069ms","remote":"127.0.0.1:42486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T05:50:33.784463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:50:33.362532Z","time spent":"421.924467ms","remote":"127.0.0.1:42582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"info","ts":"2026-04-20T05:50:33.783928Z","caller":"traceutil/trace.go:171","msg":"trace[1501322517] transaction","detail":"{read_only:false; number_of_response:0; response_revision:227; }","duration":"308.926011ms","start":"2026-04-20T05:50:33.474946Z","end":"2026-04-20T05:50:33.783872Z","steps":["trace[1501322517] 'process raft request' (duration: 308.761969ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:50:33.785741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:50:33.474937Z","time spent":"310.757118ms","remote":"127.0.0.1:42486","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T05:50:34.307886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.547851ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T05:50:34.308025Z","caller":"traceutil/trace.go:171","msg":"trace[1939398308] transaction","detail":"{read_only:false; response_revision:231; number_of_response:1; }","duration":"557.372997ms","start":"2026-04-20T05:50:33.750628Z","end":"2026-04-20T05:50:34.308001Z","steps":["trace[1939398308] 'process raft request' (duration: 205.642403ms)","trace[1939398308] 'compare' (duration: 351.357088ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:50:34.308042Z","caller":"traceutil/trace.go:171","msg":"trace[2134029027] transaction","detail":"{read_only:false; response_revision:232; number_of_response:1; }","duration":"522.132879ms","start":"2026-04-20T05:50:33.785897Z","end":"2026-04-20T05:50:34.30803Z","steps":["trace[2134029027] 'process raft request' (duration: 522.082308ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:50:34.3081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:50:33.78588Z","time spent":"522.193669ms","remote":"127.0.0.1:42390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T05:50:34.308102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:50:33.750608Z","time spent":"557.460649ms","remote":"127.0.0.1:42790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3200,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T05:50:34.308281Z","caller":"traceutil/trace.go:171","msg":"trace[1025953835] linearizableReadLoop","detail":"{readStateIndex:240; appliedIndex:239; }","duration":"524.361172ms","start":"2026-04-20T05:50:33.78391Z","end":"2026-04-20T05:50:34.308271Z","steps":["trace[1025953835] 'read index received' (duration: 172.375584ms)","trace[1025953835] 'applied index is now lower than readState.Index' (duration: 351.984628ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:50:34.308444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.088002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"warn","ts":"2026-04-20T05:50:34.308454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.175595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-04-20T05:50:34.308531Z","caller":"traceutil/trace.go:171","msg":"trace[1305086689] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:232; }","duration":"523.281456ms","start":"2026-04-20T05:50:33.785239Z","end":"2026-04-20T05:50:34.308521Z","steps":["trace[1305086689] 'agreement among raft nodes before linearized reading' (duration: 523.127794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:50:34.308582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:50:33.785218Z","time spent":"523.356268ms","remote":"127.0.0.1:42474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4040,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-20T05:50:34.308648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.306385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"info","ts":"2026-04-20T05:50:34.308845Z","caller":"traceutil/trace.go:171","msg":"trace[56935861] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:232; }","duration":"521.559669ms","start":"2026-04-20T05:50:33.787277Z","end":"2026-04-20T05:50:34.308837Z","steps":["trace[56935861] 'agreement among raft nodes before linearized reading' (duration: 521.339686ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:50:34.308874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:50:33.787249Z","time spent":"521.61862ms","remote":"127.0.0.1:42486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":3489,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-04-20T05:50:34.308942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.625431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-04-20T05:50:34.308988Z","caller":"traceutil/trace.go:171","msg":"trace[1232636488] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:232; }","duration":"521.704431ms","start":"2026-04-20T05:50:33.787275Z","end":"2026-04-20T05:50:34.30898Z","steps":["trace[1232636488] 'agreement among raft nodes before linearized reading' (duration: 521.62385ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:50:34.308499Z","caller":"traceutil/trace.go:171","msg":"trace[1166587090] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:232; }","duration":"561.133263ms","start":"2026-04-20T05:50:33.74734Z","end":"2026-04-20T05:50:34.308474Z","steps":["trace[1166587090] 'agreement among raft nodes before linearized reading' (duration: 561.011611ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:50:34.309108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.374628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T05:50:34.309137Z","caller":"traceutil/trace.go:171","msg":"trace[218613251] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:232; }","duration":"413.452698ms","start":"2026-04-20T05:50:33.895677Z","end":"2026-04-20T05:50:34.309129Z","steps":["trace[218613251] 'agreement among raft nodes before linearized reading' (duration: 413.410688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:50:34.309161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:50:33.895642Z","time spent":"413.51394ms","remote":"127.0.0.1:42326","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-04-20T05:50:34.309189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:50:33.747275Z","time spent":"561.830323ms","remote":"127.0.0.1:42716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-04-20T05:50:34.309015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:50:33.787258Z","time spent":"521.751892ms","remote":"127.0.0.1:42474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4040,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-20T05:50:34.309355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.253455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2918"} {"level":"info","ts":"2026-04-20T05:50:34.309402Z","caller":"traceutil/trace.go:171","msg":"trace[39696696] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:232; }","duration":"523.352917ms","start":"2026-04-20T05:50:33.786036Z","end":"2026-04-20T05:50:34.309389Z","steps":["trace[39696696] 'agreement among raft nodes before linearized reading' (duration: 523.273335ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:50:34.30946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:50:33.785167Z","time spent":"524.282651ms","remote":"127.0.0.1:42486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":2941,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-04-20T05:50:57.547766Z","caller":"traceutil/trace.go:171","msg":"trace[1210810198] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"248.425906ms","start":"2026-04-20T05:50:57.2993Z","end":"2026-04-20T05:50:57.547725Z","steps":["trace[1210810198] 'process raft request' (duration: 236.06019ms)","trace[1210810198] 'compare' (duration: 12.182393ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:51:05.567057Z","caller":"traceutil/trace.go:171","msg":"trace[1866517358] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:599; }","duration":"240.605887ms","start":"2026-04-20T05:51:05.326394Z","end":"2026-04-20T05:51:05.567Z","steps":["trace[1866517358] 'read index received' (duration: 238.963532ms)","trace[1866517358] 'applied index is now lower than readState.Index' (duration: 1.641095ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:51:05.567182Z","caller":"traceutil/trace.go:171","msg":"trace[631491195] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"320.501075ms","start":"2026-04-20T05:51:05.246635Z","end":"2026-04-20T05:51:05.567136Z","steps":["trace[631491195] 'process raft request' (duration: 319.318938ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:51:05.567477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.042545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-20T05:51:05.567555Z","caller":"traceutil/trace.go:171","msg":"trace[159243416] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:578; }","duration":"241.206657ms","start":"2026-04-20T05:51:05.326333Z","end":"2026-04-20T05:51:05.56754Z","steps":["trace[159243416] 'agreement among raft nodes before linearized reading' (duration: 240.921943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:51:05.567576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:51:05.246606Z","time spent":"320.663758ms","remote":"127.0.0.1:42418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036249,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T05:51:05.568704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.103476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-20T05:51:05.568778Z","caller":"traceutil/trace.go:171","msg":"trace[1551501851] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:578; }","duration":"241.218688ms","start":"2026-04-20T05:51:05.327541Z","end":"2026-04-20T05:51:05.56876Z","steps":["trace[1551501851] 'agreement among raft nodes before linearized reading' (duration: 240.201342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:51:05.825669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.764765ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T05:51:05.825795Z","caller":"traceutil/trace.go:171","msg":"trace[1320715996] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"242.035369ms","start":"2026-04-20T05:51:05.583737Z","end":"2026-04-20T05:51:05.825773Z","steps":["trace[1320715996] 'process raft request' (duration: 92.0628ms)","trace[1320715996] 'compare' (duration: 149.155777ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:51:05.893276Z","caller":"traceutil/trace.go:171","msg":"trace[1992950321] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"266.878261ms","start":"2026-04-20T05:51:05.626372Z","end":"2026-04-20T05:51:05.89325Z","steps":["trace[1992950321] 'process raft request' (duration: 266.695749ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:51:06.651915Z","caller":"traceutil/trace.go:171","msg":"trace[1573966227] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:604; }","duration":"143.964749ms","start":"2026-04-20T05:51:06.507923Z","end":"2026-04-20T05:51:06.651887Z","steps":["trace[1573966227] 'read index received' (duration: 142.805222ms)","trace[1573966227] 'applied index is now lower than readState.Index' (duration: 1.158277ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:51:06.652018Z","caller":"traceutil/trace.go:171","msg":"trace[2005348131] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"349.427218ms","start":"2026-04-20T05:51:06.302552Z","end":"2026-04-20T05:51:06.651979Z","steps":["trace[2005348131] 'process raft request' (duration: 348.462285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:51:06.652059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.111021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-20T05:51:06.652106Z","caller":"traceutil/trace.go:171","msg":"trace[1838521179] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:583; }","duration":"144.179462ms","start":"2026-04-20T05:51:06.507917Z","end":"2026-04-20T05:51:06.652096Z","steps":["trace[1838521179] 'agreement among raft nodes before linearized reading' (duration: 144.073791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:51:06.652677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:51:06.302522Z","time spent":"349.584971ms","remote":"127.0.0.1:42418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036277,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T05:51:22.428338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.716678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-20T05:51:22.428489Z","caller":"traceutil/trace.go:171","msg":"trace[622426809] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:748; }","duration":"304.913502ms","start":"2026-04-20T05:51:22.123525Z","end":"2026-04-20T05:51:22.428439Z","steps":["trace[622426809] 'range keys from in-memory index tree' (duration: 304.375172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:51:22.428554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:51:22.123502Z","time spent":"305.036564ms","remote":"127.0.0.1:42582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T05:51:56.339031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.994862ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T05:51:56.33916Z","caller":"traceutil/trace.go:171","msg":"trace[500141789] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:991; }","duration":"444.661352ms","start":"2026-04-20T05:51:55.894478Z","end":"2026-04-20T05:51:56.33914Z","steps":["trace[500141789] 'read index received' (duration: 40.287453ms)","trace[500141789] 'applied index is now lower than readState.Index' (duration: 404.372529ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:51:56.33944Z","caller":"traceutil/trace.go:171","msg":"trace[1274762882] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"449.392817ms","start":"2026-04-20T05:51:55.890003Z","end":"2026-04-20T05:51:56.339396Z","steps":["trace[1274762882] 'process raft request' (duration: 227.932708ms)","trace[1274762882] 'compare' (duration: 220.722015ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:51:56.339508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.032108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-20T05:51:56.339549Z","caller":"traceutil/trace.go:171","msg":"trace[1613159324] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:959; }","duration":"445.11996ms","start":"2026-04-20T05:51:55.894418Z","end":"2026-04-20T05:51:56.339538Z","steps":["trace[1613159324] 'agreement among raft nodes before linearized reading' (duration: 444.981757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:51:56.339594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:51:55.894397Z","time spent":"445.18892ms","remote":"127.0.0.1:42582","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-04-20T05:51:56.339886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:51:55.889978Z","time spent":"449.557481ms","remote":"127.0.0.1:42582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T05:51:56.33994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.366215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T05:51:56.340047Z","caller":"traceutil/trace.go:171","msg":"trace[1233128888] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:959; }","duration":"444.506638ms","start":"2026-04-20T05:51:55.895513Z","end":"2026-04-20T05:51:56.34002Z","steps":["trace[1233128888] 'agreement among raft nodes before linearized reading' (duration: 444.305865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:51:56.340096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:51:55.895505Z","time spent":"444.581679ms","remote":"127.0.0.1:42326","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-04-20T05:51:56.483245Z","caller":"traceutil/trace.go:171","msg":"trace[22261970] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"138.598042ms","start":"2026-04-20T05:51:56.344617Z","end":"2026-04-20T05:51:56.483215Z","steps":["trace[22261970] 'process raft request' (duration: 137.502551ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:52:22.039254Z","caller":"traceutil/trace.go:171","msg":"trace[515695633] linearizableReadLoop","detail":"{readStateIndex:1082; appliedIndex:1081; }","duration":"143.590222ms","start":"2026-04-20T05:52:21.895631Z","end":"2026-04-20T05:52:22.039221Z","steps":["trace[515695633] 'read index received' (duration: 58.908882ms)","trace[515695633] 'applied index is now lower than readState.Index' (duration: 84.67994ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:52:22.039392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.738944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T05:52:22.03943Z","caller":"traceutil/trace.go:171","msg":"trace[976538721] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1042; }","duration":"143.838036ms","start":"2026-04-20T05:52:21.89558Z","end":"2026-04-20T05:52:22.039418Z","steps":["trace[976538721] 'agreement among raft nodes before linearized reading' (duration: 143.756095ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:52:22.039386Z","caller":"traceutil/trace.go:171","msg":"trace[1895260750] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"205.734333ms","start":"2026-04-20T05:52:21.833602Z","end":"2026-04-20T05:52:22.039336Z","steps":["trace[1895260750] 'process raft request' (duration: 205.436308ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:52:23.236201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.016028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T05:52:23.236281Z","caller":"traceutil/trace.go:171","msg":"trace[496535659] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1046; }","duration":"340.180831ms","start":"2026-04-20T05:52:22.896082Z","end":"2026-04-20T05:52:23.236263Z","steps":["trace[496535659] 'range keys from in-memory index tree' (duration: 339.867775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:52:23.236322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:52:22.896055Z","time spent":"340.257392ms","remote":"127.0.0.1:42326","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-04-20T05:52:23.23645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.386237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-20T05:52:23.23659Z","caller":"traceutil/trace.go:171","msg":"trace[984294646] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1046; }","duration":"383.57175ms","start":"2026-04-20T05:52:22.852961Z","end":"2026-04-20T05:52:23.236533Z","steps":["trace[984294646] 'range keys from in-memory index tree' (duration: 383.095732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:52:23.236642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:52:22.852931Z","time spent":"383.697613ms","remote":"127.0.0.1:42582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-20T05:52:23.403181Z","caller":"traceutil/trace.go:171","msg":"trace[801270381] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"160.580781ms","start":"2026-04-20T05:52:23.242569Z","end":"2026-04-20T05:52:23.40315Z","steps":["trace[801270381] 'process raft request' (duration: 160.319456ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:52:23.403477Z","caller":"traceutil/trace.go:171","msg":"trace[15132195] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"160.728655ms","start":"2026-04-20T05:52:23.242705Z","end":"2026-04-20T05:52:23.403434Z","steps":["trace[15132195] 'process raft request' (duration: 160.394578ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:52:24.313936Z","caller":"traceutil/trace.go:171","msg":"trace[2131408319] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"127.624902ms","start":"2026-04-20T05:52:24.186248Z","end":"2026-04-20T05:52:24.313873Z","steps":["trace[2131408319] 'process raft request' (duration: 127.248875ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:52:38.540945Z","caller":"traceutil/trace.go:171","msg":"trace[1278995369] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"108.068637ms","start":"2026-04-20T05:52:38.432837Z","end":"2026-04-20T05:52:38.540906Z","steps":["trace[1278995369] 'process raft request' (duration: 107.817462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:52:55.063352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.042069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T05:52:55.063441Z","caller":"traceutil/trace.go:171","msg":"trace[1413605991] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1202; }","duration":"167.202472ms","start":"2026-04-20T05:52:54.89622Z","end":"2026-04-20T05:52:55.063422Z","steps":["trace[1413605991] 'range keys from in-memory index tree' (duration: 166.911507ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:52:55.063855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.2082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-20T05:52:55.063953Z","caller":"traceutil/trace.go:171","msg":"trace[716430887] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1202; }","duration":"123.365022ms","start":"2026-04-20T05:52:54.940568Z","end":"2026-04-20T05:52:55.063933Z","steps":["trace[716430887] 'range keys from in-memory index tree' (duration: 123.007787ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:52:55.815512Z","caller":"traceutil/trace.go:171","msg":"trace[1792014531] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"155.478877ms","start":"2026-04-20T05:52:55.660003Z","end":"2026-04-20T05:52:55.815482Z","steps":["trace[1792014531] 'process raft request' (duration: 155.389015ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:52:55.81655Z","caller":"traceutil/trace.go:171","msg":"trace[373368953] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"162.443188ms","start":"2026-04-20T05:52:55.65407Z","end":"2026-04-20T05:52:55.816513Z","steps":["trace[373368953] 'process raft request' (duration: 126.259363ms)","trace[373368953] 'compare' (duration: 34.602226ms)"],"step_count":2}