{"level":"warn","ts":"2026-02-17T16:06:34.213524Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-17T16:06:34.21361Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.20: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.20:2380","--initial-cluster=instance=https://199.19.213.20:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.20:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.20: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-02-17T16:06:34.213725Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-17T16:06:34.213739Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.20:2380"]} {"level":"info","ts":"2026-02-17T16:06:34.213775Z","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-02-17T16:06:34.215635Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.20:2379"]} {"level":"info","ts":"2026-02-17T16:06:34.215725Z","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":2,"max-cpu-available":2,"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.20:2380"],"listen-peer-urls":["https://199.19.213.20:2380"],"advertise-client-urls":["https://199.19.213.20:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.20:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.20: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-02-17T16:06:34.221076Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.013308ms"} {"level":"info","ts":"2026-02-17T16:06:34.235467Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"185293d8d40ffce0","cluster-id":"cc857afa6e1fa648"} {"level":"info","ts":"2026-02-17T16:06:34.235653Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"185293d8d40ffce0 switched to configuration voters=()"} {"level":"info","ts":"2026-02-17T16:06:34.235722Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"185293d8d40ffce0 became follower at term 0"} {"level":"info","ts":"2026-02-17T16:06:34.235811Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 185293d8d40ffce0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-17T16:06:34.23586Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"185293d8d40ffce0 became follower at term 1"} {"level":"info","ts":"2026-02-17T16:06:34.235944Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"185293d8d40ffce0 switched to configuration voters=(1752625764480580832)"} {"level":"warn","ts":"2026-02-17T16:06:34.252811Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-17T16:06:34.261956Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-17T16:06:34.26722Z","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-02-17T16:06:34.277263Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"185293d8d40ffce0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-17T16:06:34.278416Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"185293d8d40ffce0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-17T16:06:34.278569Z","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-02-17T16:06:34.278651Z","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-02-17T16:06:34.278715Z","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-02-17T16:06:34.288267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"185293d8d40ffce0 switched to configuration voters=(1752625764480580832)"} {"level":"info","ts":"2026-02-17T16:06:34.290824Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cc857afa6e1fa648","local-member-id":"185293d8d40ffce0","added-peer-id":"185293d8d40ffce0","added-peer-peer-urls":["https://199.19.213.20:2380"]} {"level":"info","ts":"2026-02-17T16:06:34.291216Z","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-02-17T16:06:34.291469Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"185293d8d40ffce0","initial-advertise-peer-urls":["https://199.19.213.20:2380"],"listen-peer-urls":["https://199.19.213.20:2380"],"advertise-client-urls":["https://199.19.213.20:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.20:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-17T16:06:34.29133Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.20:2380"} {"level":"info","ts":"2026-02-17T16:06:34.294203Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.20:2380"} {"level":"info","ts":"2026-02-17T16:06:34.292325Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-17T16:06:34.637659Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"185293d8d40ffce0 is starting a new election at term 1"} {"level":"info","ts":"2026-02-17T16:06:34.63776Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"185293d8d40ffce0 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-17T16:06:34.637823Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"185293d8d40ffce0 received MsgPreVoteResp from 185293d8d40ffce0 at term 1"} {"level":"info","ts":"2026-02-17T16:06:34.637867Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"185293d8d40ffce0 became candidate at term 2"} {"level":"info","ts":"2026-02-17T16:06:34.6379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"185293d8d40ffce0 received MsgVoteResp from 185293d8d40ffce0 at term 2"} {"level":"info","ts":"2026-02-17T16:06:34.637946Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"185293d8d40ffce0 became leader at term 2"} {"level":"info","ts":"2026-02-17T16:06:34.637987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 185293d8d40ffce0 elected leader 185293d8d40ffce0 at term 2"} {"level":"info","ts":"2026-02-17T16:06:34.64033Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"185293d8d40ffce0","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.20:2379]}","request-path":"/0/members/185293d8d40ffce0/attributes","cluster-id":"cc857afa6e1fa648","publish-timeout":"7s"} {"level":"info","ts":"2026-02-17T16:06:34.640474Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-17T16:06:34.640755Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-17T16:06:34.642035Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-17T16:06:34.654754Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.20:2379"} {"level":"info","ts":"2026-02-17T16:06:34.654844Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T16:06:34.654981Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-17T16:06:34.65502Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-17T16:06:34.659054Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cc857afa6e1fa648","local-member-id":"185293d8d40ffce0","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T16:06:34.659168Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T16:06:34.659204Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T16:06:37.854924Z","caller":"traceutil/trace.go:171","msg":"trace[465396440] transaction","detail":"{read_only:false; response_revision:106; number_of_response:1; }","duration":"217.98625ms","start":"2026-02-17T16:06:37.636918Z","end":"2026-02-17T16:06:37.854905Z","steps":["trace[465396440] 'process raft request' (duration: 149.934499ms)","trace[465396440] 'compare' (duration: 67.661346ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:06:39.669956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.065796684s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-17T16:06:39.670207Z","caller":"traceutil/trace.go:171","msg":"trace[1656801253] transaction","detail":"{read_only:false; response_revision:108; number_of_response:1; }","duration":"1.795951374s","start":"2026-02-17T16:06:37.874233Z","end":"2026-02-17T16:06:39.670184Z","steps":["trace[1656801253] 'process raft request' (duration: 729.354048ms)","trace[1656801253] 'compare' (duration: 1.065647576s)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:06:39.670356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:06:37.874224Z","time spent":"1.796058783s","remote":"127.0.0.1:37668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1368,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T16:06:39.670845Z","caller":"traceutil/trace.go:171","msg":"trace[1919125545] linearizableReadLoop","detail":"{readStateIndex:112; appliedIndex:110; }","duration":"1.074009041s","start":"2026-02-17T16:06:38.596828Z","end":"2026-02-17T16:06:39.670837Z","steps":["trace[1919125545] 'read index received' (duration: 6.771062ms)","trace[1919125545] 'applied index is now lower than readState.Index' (duration: 1.067237069s)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:06:39.670985Z","caller":"traceutil/trace.go:171","msg":"trace[1843554984] transaction","detail":"{read_only:false; response_revision:109; number_of_response:1; }","duration":"1.220901009s","start":"2026-02-17T16:06:38.450078Z","end":"2026-02-17T16:06:39.670979Z","steps":["trace[1843554984] 'process raft request' (duration: 1.219996859s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:06:39.671053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:06:38.450055Z","time spent":"1.220967429s","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3937,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-17T16:06:39.671228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.074402057s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-17T16:06:39.671271Z","caller":"traceutil/trace.go:171","msg":"trace[1617521085] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:109; }","duration":"1.074483556s","start":"2026-02-17T16:06:38.59678Z","end":"2026-02-17T16:06:39.671263Z","steps":["trace[1617521085] 'agreement among raft nodes before linearized reading' (duration: 1.074369747s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:06:39.671317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:06:38.596761Z","time spent":"1.074550626s","remote":"127.0.0.1:37352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T16:06:40.171574Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18221736081529240867,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T16:06:40.67238Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18221736081529240867,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T16:06:40.740294Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.19687359s","expected-duration":"1s"} {"level":"info","ts":"2026-02-17T16:06:40.74634Z","caller":"traceutil/trace.go:171","msg":"trace[712589452] linearizableReadLoop","detail":"{readStateIndex:113; appliedIndex:112; }","duration":"1.075434653s","start":"2026-02-17T16:06:39.670885Z","end":"2026-02-17T16:06:40.74632Z","steps":["trace[712589452] 'read index received' (duration: 1.069528967s)","trace[712589452] 'applied index is now lower than readState.Index' (duration: 5.904906ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:06:40.746657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.072955678s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:attachdetach-controller\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-17T16:06:40.746729Z","caller":"traceutil/trace.go:171","msg":"trace[197857596] transaction","detail":"{read_only:false; response_revision:110; number_of_response:1; }","duration":"1.694472435s","start":"2026-02-17T16:06:39.052241Z","end":"2026-02-17T16:06:40.746713Z","steps":["trace[197857596] 'process raft request' (duration: 1.688226121s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:06:40.74681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:06:39.052217Z","time spent":"1.694551244s","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5855,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T16:06:40.746753Z","caller":"traceutil/trace.go:171","msg":"trace[55582975] range","detail":"{range_begin:/registry/clusterroles/system:controller:attachdetach-controller; range_end:; response_count:0; response_revision:110; }","duration":"1.073069867s","start":"2026-02-17T16:06:39.673669Z","end":"2026-02-17T16:06:40.746738Z","steps":["trace[55582975] 'agreement among raft nodes before linearized reading' (duration: 1.072947629s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:06:40.746996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:06:39.673659Z","time spent":"1.073326795s","remote":"127.0.0.1:37668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/system:controller:attachdetach-controller\" "} {"level":"warn","ts":"2026-02-17T16:06:40.747189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.319324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-17T16:06:40.747252Z","caller":"traceutil/trace.go:171","msg":"trace[1304106326] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:110; }","duration":"791.422174ms","start":"2026-02-17T16:06:39.955823Z","end":"2026-02-17T16:06:40.747245Z","steps":["trace[1304106326] 'agreement among raft nodes before linearized reading' (duration: 791.345884ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:06:40.747306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:06:39.955767Z","time spent":"791.532952ms","remote":"127.0.0.1:37352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T16:06:40.74744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.073739063s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-17T16:06:40.747501Z","caller":"traceutil/trace.go:171","msg":"trace[1174140968] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:110; }","duration":"1.073804612s","start":"2026-02-17T16:06:39.67369Z","end":"2026-02-17T16:06:40.747495Z","steps":["trace[1174140968] 'agreement among raft nodes before linearized reading' (duration: 1.073732703s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:06:40.747598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:06:39.673688Z","time spent":"1.073903332s","remote":"127.0.0.1:37366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T16:06:40.746681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.078220787s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-17T16:06:40.748385Z","caller":"traceutil/trace.go:171","msg":"trace[1486341250] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:110; }","duration":"2.079670864s","start":"2026-02-17T16:06:38.668395Z","end":"2026-02-17T16:06:40.748066Z","steps":["trace[1486341250] 'agreement among raft nodes before linearized reading' (duration: 2.078150097s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:06:40.74852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:06:38.668371Z","time spent":"2.08013787s","remote":"127.0.0.1:37596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-17T16:06:40.748751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.076423452s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-17T16:06:40.748787Z","caller":"traceutil/trace.go:171","msg":"trace[1666803760] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:110; }","duration":"2.076473421s","start":"2026-02-17T16:06:38.672304Z","end":"2026-02-17T16:06:40.748778Z","steps":["trace[1666803760] 'agreement among raft nodes before linearized reading' (duration: 2.076191724s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:06:40.748861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:06:38.672298Z","time spent":"2.076529601s","remote":"127.0.0.1:37596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-17T16:07:06.590997Z","caller":"traceutil/trace.go:171","msg":"trace[1125742444] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"462.049906ms","start":"2026-02-17T16:07:06.128919Z","end":"2026-02-17T16:07:06.590969Z","steps":["trace[1125742444] 'process raft request' (duration: 460.292347ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:06.5915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:06.128895Z","time spent":"462.546194ms","remote":"127.0.0.1:37444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317280,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-17T16:07:11.384292Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18221736081529241711,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-17T16:07:11.506159Z","caller":"traceutil/trace.go:171","msg":"trace[648865625] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:535; }","duration":"622.701725ms","start":"2026-02-17T16:07:10.88343Z","end":"2026-02-17T16:07:11.506132Z","steps":["trace[648865625] 'read index received' (duration: 622.350567ms)","trace[648865625] 'applied index is now lower than readState.Index' (duration: 350.058µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:07:11.507336Z","caller":"traceutil/trace.go:171","msg":"trace[1522685391] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"760.545571ms","start":"2026-02-17T16:07:10.746758Z","end":"2026-02-17T16:07:11.507303Z","steps":["trace[1522685391] 'process raft request' (duration: 759.095012ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:11.507675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:10.746724Z","time spent":"760.659244ms","remote":"127.0.0.1:37596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:07:11.508151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.716786ms","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-02-17T16:07:11.508203Z","caller":"traceutil/trace.go:171","msg":"trace[302288789] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:518; }","duration":"624.789087ms","start":"2026-02-17T16:07:10.883396Z","end":"2026-02-17T16:07:11.508185Z","steps":["trace[302288789] 'agreement among raft nodes before linearized reading' (duration: 624.612793ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:11.508243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:10.883369Z","time spent":"624.864258ms","remote":"127.0.0.1:37596","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-02-17T16:07:11.508565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.970091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:07:11.508607Z","caller":"traceutil/trace.go:171","msg":"trace[902187673] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:518; }","duration":"556.041762ms","start":"2026-02-17T16:07:10.952553Z","end":"2026-02-17T16:07:11.508595Z","steps":["trace[902187673] 'agreement among raft nodes before linearized reading' (duration: 555.97182ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:11.508641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:10.952538Z","time spent":"556.095103ms","remote":"127.0.0.1:37352","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-02-17T16:07:11.509241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.53519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.20\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-17T16:07:11.509285Z","caller":"traceutil/trace.go:171","msg":"trace[625168408] range","detail":"{range_begin:/registry/masterleases/199.19.213.20; range_end:; response_count:1; response_revision:518; }","duration":"309.617281ms","start":"2026-02-17T16:07:11.199655Z","end":"2026-02-17T16:07:11.509273Z","steps":["trace[625168408] 'agreement among raft nodes before linearized reading' (duration: 309.529219ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:11.509322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:11.199636Z","time spent":"309.677783ms","remote":"127.0.0.1:37394","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.20\" "} {"level":"warn","ts":"2026-02-17T16:07:11.509715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.691589ms","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-02-17T16:07:11.509757Z","caller":"traceutil/trace.go:171","msg":"trace[877699590] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:518; }","duration":"511.758341ms","start":"2026-02-17T16:07:10.997987Z","end":"2026-02-17T16:07:11.509745Z","steps":["trace[877699590] 'agreement among raft nodes before linearized reading' (duration: 511.566817ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:11.509871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:10.997977Z","time spent":"511.808302ms","remote":"127.0.0.1:37596","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-02-17T16:07:11.509914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.332156ms","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-02-17T16:07:11.509956Z","caller":"traceutil/trace.go:171","msg":"trace[1240681528] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:518; }","duration":"519.398367ms","start":"2026-02-17T16:07:10.990544Z","end":"2026-02-17T16:07:11.509942Z","steps":["trace[1240681528] 'agreement among raft nodes before linearized reading' (duration: 519.285855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:11.509987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:10.990528Z","time spent":"519.452109ms","remote":"127.0.0.1:37596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-17T16:07:17.969393Z","caller":"traceutil/trace.go:171","msg":"trace[7743276] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:578; }","duration":"370.042405ms","start":"2026-02-17T16:07:17.59929Z","end":"2026-02-17T16:07:17.969332Z","steps":["trace[7743276] 'read index received' (duration: 313.822717ms)","trace[7743276] 'applied index is now lower than readState.Index' (duration: 56.218608ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:07:17.969515Z","caller":"traceutil/trace.go:171","msg":"trace[587256449] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"390.336245ms","start":"2026-02-17T16:07:17.579159Z","end":"2026-02-17T16:07:17.969496Z","steps":["trace[587256449] 'process raft request' (duration: 333.945254ms)","trace[587256449] 'compare' (duration: 56.113505ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:07:17.969604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:17.579144Z","time spent":"390.411307ms","remote":"127.0.0.1:37596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:07:17.96975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.459824ms","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-02-17T16:07:17.969766Z","caller":"traceutil/trace.go:171","msg":"trace[1965381739] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:559; }","duration":"370.497246ms","start":"2026-02-17T16:07:17.599263Z","end":"2026-02-17T16:07:17.969761Z","steps":["trace[1965381739] 'agreement among raft nodes before linearized reading' (duration: 370.422264ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:17.969781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:17.59925Z","time spent":"370.528606ms","remote":"127.0.0.1:37596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-17T16:07:17.977381Z","caller":"traceutil/trace.go:171","msg":"trace[975587491] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"353.177077ms","start":"2026-02-17T16:07:17.624195Z","end":"2026-02-17T16:07:17.977372Z","steps":["trace[975587491] 'process raft request' (duration: 353.094165ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:17.977432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:17.624177Z","time spent":"353.22795ms","remote":"127.0.0.1:37596","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-02-17T16:07:18.721468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.684233ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:07:18.721527Z","caller":"traceutil/trace.go:171","msg":"trace[865894122] linearizableReadLoop","detail":"{readStateIndex:581; appliedIndex:580; }","duration":"483.603843ms","start":"2026-02-17T16:07:18.237914Z","end":"2026-02-17T16:07:18.721518Z","steps":["trace[865894122] 'read index received' (duration: 339.685184ms)","trace[865894122] 'applied index is now lower than readState.Index' (duration: 143.917619ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:07:18.721701Z","caller":"traceutil/trace.go:171","msg":"trace[30895923] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"748.560394ms","start":"2026-02-17T16:07:17.973134Z","end":"2026-02-17T16:07:18.721694Z","steps":["trace[30895923] 'process raft request' (duration: 604.567793ms)","trace[30895923] 'compare' (duration: 143.597111ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:07:18.721736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:17.973118Z","time spent":"748.602264ms","remote":"127.0.0.1:37596","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-02-17T16:07:18.721881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.974832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-g26pt\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-17T16:07:18.721899Z","caller":"traceutil/trace.go:171","msg":"trace[766282710] range","detail":"{range_begin:/registry/pods/kube-system/cilium-g26pt; range_end:; response_count:1; response_revision:561; }","duration":"483.992992ms","start":"2026-02-17T16:07:18.237901Z","end":"2026-02-17T16:07:18.721894Z","steps":["trace[766282710] 'agreement among raft nodes before linearized reading' (duration: 483.917251ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:18.721914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:18.237845Z","time spent":"484.064344ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16675,"request content":"key:\"/registry/pods/kube-system/cilium-g26pt\" "} {"level":"info","ts":"2026-02-17T16:07:19.200999Z","caller":"traceutil/trace.go:171","msg":"trace[452279670] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"468.077205ms","start":"2026-02-17T16:07:18.7329Z","end":"2026-02-17T16:07:19.200977Z","steps":["trace[452279670] 'process raft request' (duration: 451.979366ms)","trace[452279670] 'compare' (duration: 15.73639ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:07:19.201123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:18.732885Z","time spent":"468.164027ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:07:19.819638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.285639ms","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-02-17T16:07:19.819692Z","caller":"traceutil/trace.go:171","msg":"trace[388939910] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:562; }","duration":"263.369772ms","start":"2026-02-17T16:07:19.556309Z","end":"2026-02-17T16:07:19.819679Z","steps":["trace[388939910] 'range keys from in-memory index tree' (duration: 263.164496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:19.819759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.662274ms","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-02-17T16:07:19.819774Z","caller":"traceutil/trace.go:171","msg":"trace[1044153345] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:562; }","duration":"240.712225ms","start":"2026-02-17T16:07:19.579057Z","end":"2026-02-17T16:07:19.819769Z","steps":["trace[1044153345] 'range keys from in-memory index tree' (duration: 240.554681ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:07:20.286471Z","caller":"traceutil/trace.go:171","msg":"trace[2043115722] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"463.573554ms","start":"2026-02-17T16:07:19.822883Z","end":"2026-02-17T16:07:20.286457Z","steps":["trace[2043115722] 'process raft request' (duration: 463.477592ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:20.286569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:19.822868Z","time spent":"463.650366ms","remote":"127.0.0.1:37596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:07:21.947361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00041781s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-17T16:07:21.947428Z","caller":"traceutil/trace.go:171","msg":"trace[683904349] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000500252s","start":"2026-02-17T16:07:19.946914Z","end":"2026-02-17T16:07:21.947414Z","steps":["trace[683904349] 'agreement among raft nodes before linearized reading' (duration: 2.00041312s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:21.947467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:19.946875Z","time spent":"2.000585085s","remote":"127.0.0.1:37352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T16:07:23.948746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00066819s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-17T16:07:23.948802Z","caller":"traceutil/trace.go:171","msg":"trace[468018000] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000758242s","start":"2026-02-17T16:07:21.948031Z","end":"2026-02-17T16:07:23.948789Z","steps":["trace[468018000] 'agreement among raft nodes before linearized reading' (duration: 2.00068393s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:23.948835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:21.948018Z","time spent":"2.000810804s","remote":"127.0.0.1:37366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T16:07:24.032386Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.745510353s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-17T16:07:24.193022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.79335264s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:07:24.19313Z","caller":"traceutil/trace.go:171","msg":"trace[1749848128] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:563; }","duration":"1.793469154s","start":"2026-02-17T16:07:22.399648Z","end":"2026-02-17T16:07:24.193117Z","steps":["trace[1749848128] 'range keys from in-memory index tree' (duration: 1.79333961s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:24.193673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.990832ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:07:24.193824Z","caller":"traceutil/trace.go:171","msg":"trace[855014569] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"239.805688ms","start":"2026-02-17T16:07:23.954008Z","end":"2026-02-17T16:07:24.193813Z","steps":["trace[855014569] 'process raft request' (duration: 239.784088ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:07:24.193939Z","caller":"traceutil/trace.go:171","msg":"trace[1660270767] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"4.369421023s","start":"2026-02-17T16:07:19.824505Z","end":"2026-02-17T16:07:24.193926Z","steps":["trace[1660270767] 'process raft request' (duration: 4.208094622s)","trace[1660270767] 'compare' (duration: 160.814476ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:07:24.193957Z","caller":"traceutil/trace.go:171","msg":"trace[1317432705] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:583; }","duration":"4.247032279s","start":"2026-02-17T16:07:19.946918Z","end":"2026-02-17T16:07:24.193951Z","steps":["trace[1317432705] 'read index received' (duration: 339.969999ms)","trace[1317432705] 'applied index is now lower than readState.Index' (duration: 3.90706166s)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:07:24.193999Z","caller":"traceutil/trace.go:171","msg":"trace[1551881387] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"394.150538ms","start":"2026-02-17T16:07:23.799845Z","end":"2026-02-17T16:07:24.193996Z","steps":["trace[1551881387] 'process raft request' (duration: 393.901811ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:24.194019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:19.824496Z","time spent":"4.369473575s","remote":"127.0.0.1:37596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:07:24.194026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:23.799825Z","time spent":"394.18317ms","remote":"127.0.0.1:37596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:07:24.194169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.221810678s","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-02-17T16:07:24.194183Z","caller":"traceutil/trace.go:171","msg":"trace[727500055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:566; }","duration":"4.221844768s","start":"2026-02-17T16:07:19.972334Z","end":"2026-02-17T16:07:24.194179Z","steps":["trace[727500055] 'agreement among raft nodes before linearized reading' (duration: 4.221774036s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:24.194197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:19.972324Z","time spent":"4.22187092s","remote":"127.0.0.1:37596","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-02-17T16:07:24.194286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.562058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:07:24.194299Z","caller":"traceutil/trace.go:171","msg":"trace[1265753145] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:566; }","duration":"236.588479ms","start":"2026-02-17T16:07:23.957706Z","end":"2026-02-17T16:07:24.194295Z","steps":["trace[1265753145] 'agreement among raft nodes before linearized reading' (duration: 236.569518ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:24.194362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.461584894s","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-02-17T16:07:24.194386Z","caller":"traceutil/trace.go:171","msg":"trace[1257109429] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:566; }","duration":"3.461641825s","start":"2026-02-17T16:07:20.732737Z","end":"2026-02-17T16:07:24.194379Z","steps":["trace[1257109429] 'agreement among raft nodes before linearized reading' (duration: 3.461561383s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:24.194408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:20.732718Z","time spent":"3.461682977s","remote":"127.0.0.1:37596","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-02-17T16:07:24.194485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.994660575s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.20\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-17T16:07:24.1945Z","caller":"traceutil/trace.go:171","msg":"trace[2090814482] range","detail":"{range_begin:/registry/masterleases/199.19.213.20; range_end:; response_count:1; response_revision:566; }","duration":"2.994699326s","start":"2026-02-17T16:07:21.199797Z","end":"2026-02-17T16:07:24.194496Z","steps":["trace[2090814482] 'agreement among raft nodes before linearized reading' (duration: 2.994670306s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:24.194513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:21.199767Z","time spent":"2.994743148s","remote":"127.0.0.1:37394","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.20\" "} {"level":"warn","ts":"2026-02-17T16:07:24.194582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.905395824s","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-02-17T16:07:24.194601Z","caller":"traceutil/trace.go:171","msg":"trace[1354131773] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:566; }","duration":"1.905450645s","start":"2026-02-17T16:07:22.289145Z","end":"2026-02-17T16:07:24.194595Z","steps":["trace[1354131773] 'agreement among raft nodes before linearized reading' (duration: 1.905398534s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:24.194619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:22.289127Z","time spent":"1.905488086s","remote":"127.0.0.1:37596","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-02-17T16:07:27.759699Z","caller":"traceutil/trace.go:171","msg":"trace[518326645] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"111.340838ms","start":"2026-02-17T16:07:27.64834Z","end":"2026-02-17T16:07:27.759681Z","steps":["trace[518326645] 'process raft request' (duration: 111.266816ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:29.586474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.773212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-17T16:07:29.586687Z","caller":"traceutil/trace.go:171","msg":"trace[1346944729] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:638; }","duration":"132.058031ms","start":"2026-02-17T16:07:29.454613Z","end":"2026-02-17T16:07:29.586671Z","steps":["trace[1346944729] 'range keys from in-memory index tree' (duration: 131.410789ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:07:32.341574Z","caller":"traceutil/trace.go:171","msg":"trace[1744339056] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"112.27727ms","start":"2026-02-17T16:07:32.229283Z","end":"2026-02-17T16:07:32.34156Z","steps":["trace[1744339056] 'process raft request' (duration: 112.014432ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:39.528576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.541284ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-02-17T16:07:40.672319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.9615ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:07:40.672414Z","caller":"traceutil/trace.go:171","msg":"trace[1069265042] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"231.831659ms","start":"2026-02-17T16:07:40.440567Z","end":"2026-02-17T16:07:40.672399Z","steps":["trace[1069265042] 'process raft request' (duration: 100.727825ms)","trace[1069265042] 'compare' (duration: 130.875256ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:07:40.672795Z","caller":"traceutil/trace.go:171","msg":"trace[2079985959] linearizableReadLoop","detail":"{readStateIndex:779; appliedIndex:777; }","duration":"161.139601ms","start":"2026-02-17T16:07:40.51165Z","end":"2026-02-17T16:07:40.67279Z","steps":["trace[2079985959] 'read index received' (duration: 29.652773ms)","trace[2079985959] 'applied index is now lower than readState.Index' (duration: 131.486318ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:07:40.672939Z","caller":"traceutil/trace.go:171","msg":"trace[155543726] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"231.350301ms","start":"2026-02-17T16:07:40.441584Z","end":"2026-02-17T16:07:40.672934Z","steps":["trace[155543726] 'process raft request' (duration: 231.172984ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:40.67305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.411782ms","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-02-17T16:07:40.67307Z","caller":"traceutil/trace.go:171","msg":"trace[1219692357] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:754; }","duration":"161.463293ms","start":"2026-02-17T16:07:40.511601Z","end":"2026-02-17T16:07:40.673065Z","steps":["trace[1219692357] 'agreement among raft nodes before linearized reading' (duration: 161.397841ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:40.673152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.082503ms","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-02-17T16:07:40.673168Z","caller":"traceutil/trace.go:171","msg":"trace[1372274775] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:754; }","duration":"160.124874ms","start":"2026-02-17T16:07:40.513039Z","end":"2026-02-17T16:07:40.673164Z","steps":["trace[1372274775] 'agreement among raft nodes before linearized reading' (duration: 160.083423ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:40.673309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.222252ms","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-02-17T16:07:40.673326Z","caller":"traceutil/trace.go:171","msg":"trace[1588320940] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:754; }","duration":"149.261504ms","start":"2026-02-17T16:07:40.524061Z","end":"2026-02-17T16:07:40.673323Z","steps":["trace[1588320940] 'agreement among raft nodes before linearized reading' (duration: 149.220272ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:40.958471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.703452ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:07:40.958737Z","caller":"traceutil/trace.go:171","msg":"trace[1898509611] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"278.360274ms","start":"2026-02-17T16:07:40.680363Z","end":"2026-02-17T16:07:40.958723Z","steps":["trace[1898509611] 'process raft request' (duration: 122.352041ms)","trace[1898509611] 'compare' (duration: 155.568867ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:07:40.959354Z","caller":"traceutil/trace.go:171","msg":"trace[187092737] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"276.395862ms","start":"2026-02-17T16:07:40.682948Z","end":"2026-02-17T16:07:40.959344Z","steps":["trace[187092737] 'process raft request' (duration: 276.158443ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:07:40.959473Z","caller":"traceutil/trace.go:171","msg":"trace[752253453] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"274.670069ms","start":"2026-02-17T16:07:40.684797Z","end":"2026-02-17T16:07:40.959467Z","steps":["trace[752253453] 'process raft request' (duration: 274.348547ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:07:40.959538Z","caller":"traceutil/trace.go:171","msg":"trace[660874075] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"273.350279ms","start":"2026-02-17T16:07:40.686183Z","end":"2026-02-17T16:07:40.959533Z","steps":["trace[660874075] 'process raft request' (duration: 272.982316ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:07:44.702092Z","caller":"traceutil/trace.go:171","msg":"trace[526442376] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"178.608783ms","start":"2026-02-17T16:07:44.523466Z","end":"2026-02-17T16:07:44.702074Z","steps":["trace[526442376] 'process raft request' (duration: 178.5124ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:07:44.705562Z","caller":"traceutil/trace.go:171","msg":"trace[1081847610] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"178.148027ms","start":"2026-02-17T16:07:44.527404Z","end":"2026-02-17T16:07:44.705552Z","steps":["trace[1081847610] 'process raft request' (duration: 177.857415ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:08:41.174442Z","caller":"traceutil/trace.go:171","msg":"trace[2130344928] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"165.005825ms","start":"2026-02-17T16:08:41.009401Z","end":"2026-02-17T16:08:41.174407Z","steps":["trace[2130344928] 'process raft request' (duration: 164.856227ms)"],"step_count":1}