{"level":"warn","ts":"2026-03-31T11:22:07.133784Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-31T11:22:07.133904Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.209:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.209:2380","--initial-cluster=instance=https://199.204.45.209:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.209:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.209:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-31T11:22:07.133991Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-31T11:22:07.134003Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.209:2380"]} {"level":"info","ts":"2026-03-31T11:22:07.134029Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-31T11:22:07.134857Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.209:2379"]} {"level":"info","ts":"2026-03-31T11:22:07.134948Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.209:2380"],"listen-peer-urls":["https://199.204.45.209:2380"],"advertise-client-urls":["https://199.204.45.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.209:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-31T11:22:07.139818Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.529422ms"} {"level":"info","ts":"2026-03-31T11:22:07.146301Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"adfb641115bf6cbd","cluster-id":"3da6b677d4b80c6"} {"level":"info","ts":"2026-03-31T11:22:07.146386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd switched to configuration voters=()"} {"level":"info","ts":"2026-03-31T11:22:07.146416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became follower at term 0"} {"level":"info","ts":"2026-03-31T11:22:07.146431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft adfb641115bf6cbd [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-31T11:22:07.146741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became follower at term 1"} {"level":"info","ts":"2026-03-31T11:22:07.146786Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd switched to configuration voters=(12536724012257995965)"} {"level":"warn","ts":"2026-03-31T11:22:07.15402Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-31T11:22:07.155603Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-31T11:22:07.156952Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-31T11:22:07.159284Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"adfb641115bf6cbd","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-31T11:22:07.159466Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"adfb641115bf6cbd","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-31T11:22:07.159507Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-31T11:22:07.159571Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-31T11:22:07.159603Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-31T11:22:07.161078Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd switched to configuration voters=(12536724012257995965)"} {"level":"info","ts":"2026-03-31T11:22:07.161248Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3da6b677d4b80c6","local-member-id":"adfb641115bf6cbd","added-peer-id":"adfb641115bf6cbd","added-peer-peer-urls":["https://199.204.45.209:2380"]} {"level":"info","ts":"2026-03-31T11:22:07.162567Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-31T11:22:07.162692Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.209:2380"} {"level":"info","ts":"2026-03-31T11:22:07.162753Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.209:2380"} {"level":"info","ts":"2026-03-31T11:22:07.162792Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"adfb641115bf6cbd","initial-advertise-peer-urls":["https://199.204.45.209:2380"],"listen-peer-urls":["https://199.204.45.209:2380"],"advertise-client-urls":["https://199.204.45.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-31T11:22:07.16282Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-31T11:22:07.347125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd is starting a new election at term 1"} {"level":"info","ts":"2026-03-31T11:22:07.347327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became pre-candidate at term 1"} {"level":"info","ts":"2026-03-31T11:22:07.347398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd received MsgPreVoteResp from adfb641115bf6cbd at term 1"} {"level":"info","ts":"2026-03-31T11:22:07.347459Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became candidate at term 2"} {"level":"info","ts":"2026-03-31T11:22:07.347488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd received MsgVoteResp from adfb641115bf6cbd at term 2"} {"level":"info","ts":"2026-03-31T11:22:07.34751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became leader at term 2"} {"level":"info","ts":"2026-03-31T11:22:07.347568Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: adfb641115bf6cbd elected leader adfb641115bf6cbd at term 2"} {"level":"info","ts":"2026-03-31T11:22:07.348765Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T11:22:07.349587Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"adfb641115bf6cbd","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.209:2379]}","request-path":"/0/members/adfb641115bf6cbd/attributes","cluster-id":"3da6b677d4b80c6","publish-timeout":"7s"} {"level":"info","ts":"2026-03-31T11:22:07.349926Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3da6b677d4b80c6","local-member-id":"adfb641115bf6cbd","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T11:22:07.350239Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T11:22:07.350282Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T11:22:07.350454Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-31T11:22:07.350516Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-31T11:22:07.352278Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-31T11:22:07.352347Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-31T11:22:07.352465Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-31T11:22:07.360486Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.209:2379"} {"level":"info","ts":"2026-03-31T11:22:39.260107Z","caller":"traceutil/trace.go:171","msg":"trace[685040920] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"399.795389ms","start":"2026-03-31T11:22:38.860289Z","end":"2026-03-31T11:22:39.260085Z","steps":["trace[685040920] 'process raft request' (duration: 399.653756ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:22:39.260593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:22:38.860285Z","time spent":"399.897092ms","remote":"127.0.0.1:46492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T11:22:39.817376Z","caller":"traceutil/trace.go:171","msg":"trace[461735470] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"956.857731ms","start":"2026-03-31T11:22:38.860487Z","end":"2026-03-31T11:22:39.817345Z","steps":["trace[461735470] 'process raft request' (duration: 956.627365ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:22:39.817521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:22:38.860168Z","time spent":"957.288602ms","remote":"127.0.0.1:46492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T11:22:39.8177Z","caller":"traceutil/trace.go:171","msg":"trace[12739850] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"947.614554ms","start":"2026-03-31T11:22:38.870053Z","end":"2026-03-31T11:22:39.817667Z","steps":["trace[12739850] 'process raft request' (duration: 947.383509ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:22:39.817874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:22:38.870049Z","time spent":"947.757448ms","remote":"127.0.0.1:46492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T11:22:39.817986Z","caller":"traceutil/trace.go:171","msg":"trace[1986799660] linearizableReadLoop","detail":"{readStateIndex:502; appliedIndex:490; }","duration":"793.844268ms","start":"2026-03-31T11:22:39.024116Z","end":"2026-03-31T11:22:39.817961Z","steps":["trace[1986799660] 'read index received' (duration: 235.817693ms)","trace[1986799660] 'applied index is now lower than readState.Index' (duration: 558.025395ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:22:39.818069Z","caller":"traceutil/trace.go:171","msg":"trace[283086061] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"942.502209ms","start":"2026-03-31T11:22:38.875554Z","end":"2026-03-31T11:22:39.818056Z","steps":["trace[283086061] 'process raft request' (duration: 942.105909ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:22:39.818094Z","caller":"traceutil/trace.go:171","msg":"trace[196933947] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"927.246063ms","start":"2026-03-31T11:22:38.890835Z","end":"2026-03-31T11:22:39.818081Z","steps":["trace[196933947] 'process raft request' (duration: 926.880164ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:22:39.818107Z","caller":"traceutil/trace.go:171","msg":"trace[874657761] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"956.316858ms","start":"2026-03-31T11:22:38.861773Z","end":"2026-03-31T11:22:39.81809Z","steps":["trace[874657761] 'process raft request' (duration: 955.478538ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:22:39.818136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:22:38.875546Z","time spent":"942.55559ms","remote":"127.0.0.1:46492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T11:22:39.818187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:22:38.890806Z","time spent":"927.316175ms","remote":"127.0.0.1:46492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T11:22:39.81824Z","caller":"traceutil/trace.go:171","msg":"trace[780973152] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"948.211109ms","start":"2026-03-31T11:22:38.870015Z","end":"2026-03-31T11:22:39.818226Z","steps":["trace[780973152] 'process raft request' (duration: 947.378469ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:22:39.818272Z","caller":"traceutil/trace.go:171","msg":"trace[214942608] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"946.801674ms","start":"2026-03-31T11:22:38.871457Z","end":"2026-03-31T11:22:39.818258Z","steps":["trace[214942608] 'process raft request' (duration: 946.037845ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:22:39.818298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:22:38.870007Z","time spent":"948.26438ms","remote":"127.0.0.1:46492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T11:22:39.81832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:22:38.87143Z","time spent":"946.866795ms","remote":"127.0.0.1:46492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T11:22:39.818404Z","caller":"traceutil/trace.go:171","msg":"trace[1234001873] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"943.641327ms","start":"2026-03-31T11:22:38.874753Z","end":"2026-03-31T11:22:39.818394Z","steps":["trace[1234001873] 'process raft request' (duration: 942.821037ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:22:39.818451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:22:38.874745Z","time spent":"943.684798ms","remote":"127.0.0.1:46492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T11:22:39.818159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:22:38.861754Z","time spent":"956.38037ms","remote":"127.0.0.1:46492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T11:22:39.81848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.353111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-31T11:22:39.818555Z","caller":"traceutil/trace.go:171","msg":"trace[1042415325] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:485; }","duration":"794.445852ms","start":"2026-03-31T11:22:39.024098Z","end":"2026-03-31T11:22:39.818544Z","steps":["trace[1042415325] 'agreement among raft nodes before linearized reading' (duration: 794.291759ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:22:39.818584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:22:39.024087Z","time spent":"794.490423ms","remote":"127.0.0.1:46708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-31T11:22:39.818627Z","caller":"traceutil/trace.go:171","msg":"trace[962815823] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"921.262567ms","start":"2026-03-31T11:22:38.897351Z","end":"2026-03-31T11:22:39.818613Z","steps":["trace[962815823] 'process raft request' (duration: 920.464198ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:22:39.818666Z","caller":"traceutil/trace.go:171","msg":"trace[788204022] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"943.863682ms","start":"2026-03-31T11:22:38.874792Z","end":"2026-03-31T11:22:39.818656Z","steps":["trace[788204022] 'process raft request' (duration: 942.821947ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:22:39.818679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:22:38.897343Z","time spent":"921.312808ms","remote":"127.0.0.1:46492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T11:22:39.818712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:22:38.874789Z","time spent":"943.903293ms","remote":"127.0.0.1:46492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T11:22:39.818736Z","caller":"traceutil/trace.go:171","msg":"trace[1257847441] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"796.656656ms","start":"2026-03-31T11:22:39.022069Z","end":"2026-03-31T11:22:39.818726Z","steps":["trace[1257847441] 'process raft request' (duration: 795.830656ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:22:39.818784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:22:39.022047Z","time spent":"796.714938ms","remote":"127.0.0.1:46492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T11:22:39.838181Z","caller":"traceutil/trace.go:171","msg":"trace[91438377] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"473.052139ms","start":"2026-03-31T11:22:39.365113Z","end":"2026-03-31T11:22:39.838165Z","steps":["trace[91438377] 'process raft request' (duration: 473.012578ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:22:39.838427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:22:39.365094Z","time spent":"473.138901ms","remote":"127.0.0.1:46708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:22:39.838637Z","caller":"traceutil/trace.go:171","msg":"trace[1364662643] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"573.440334ms","start":"2026-03-31T11:22:39.26519Z","end":"2026-03-31T11:22:39.83863Z","steps":["trace[1364662643] 'process raft request' (duration: 572.778218ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:22:39.838674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:22:39.265171Z","time spent":"573.487885ms","remote":"127.0.0.1:46960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T11:22:39.838856Z","caller":"traceutil/trace.go:171","msg":"trace[1200153334] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"570.497481ms","start":"2026-03-31T11:22:39.268351Z","end":"2026-03-31T11:22:39.838849Z","steps":["trace[1200153334] 'process raft request' (duration: 569.697041ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:22:39.838901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:22:39.268327Z","time spent":"570.551843ms","remote":"127.0.0.1:46492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:22:39.838994Z","caller":"traceutil/trace.go:171","msg":"trace[1006374941] transaction","detail":"{read_only:false; number_of_response:1; response_revision:487; }","duration":"569.285162ms","start":"2026-03-31T11:22:39.269701Z","end":"2026-03-31T11:22:39.838987Z","steps":["trace[1006374941] 'process raft request' (duration: 568.395831ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:22:39.839036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:22:39.269687Z","time spent":"569.328653ms","remote":"127.0.0.1:46492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7366,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T11:22:39.839432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.257533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-31T11:22:39.839462Z","caller":"traceutil/trace.go:171","msg":"trace[2113807165] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:488; }","duration":"815.301654ms","start":"2026-03-31T11:22:39.024155Z","end":"2026-03-31T11:22:39.839456Z","steps":["trace[2113807165] 'agreement among raft nodes before linearized reading' (duration: 815.252623ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:22:39.839481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:22:39.024142Z","time spent":"815.334735ms","remote":"127.0.0.1:46708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-31T11:22:39.839607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.494334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-31T11:22:39.83963Z","caller":"traceutil/trace.go:171","msg":"trace[1376055600] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:488; }","duration":"641.545138ms","start":"2026-03-31T11:22:39.198079Z","end":"2026-03-31T11:22:39.839625Z","steps":["trace[1376055600] 'agreement among raft nodes before linearized reading' (duration: 641.473185ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:22:39.839646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:22:39.198062Z","time spent":"641.579648ms","remote":"127.0.0.1:46708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-31T11:22:39.839719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.40907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:22:39.839755Z","caller":"traceutil/trace.go:171","msg":"trace[645500662] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:488; }","duration":"752.466232ms","start":"2026-03-31T11:22:39.087283Z","end":"2026-03-31T11:22:39.83975Z","steps":["trace[645500662] 'agreement among raft nodes before linearized reading' (duration: 752.422461ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:22:39.839769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:22:39.08727Z","time spent":"752.496182ms","remote":"127.0.0.1:46444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-31T11:22:45.593947Z","caller":"traceutil/trace.go:171","msg":"trace[2093463686] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:600; }","duration":"148.572139ms","start":"2026-03-31T11:22:45.44535Z","end":"2026-03-31T11:22:45.593922Z","steps":["trace[2093463686] 'read index received' (duration: 147.84651ms)","trace[2093463686] 'applied index is now lower than readState.Index' (duration: 724.728µs)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:22:45.594132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.767904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-khcgg\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-31T11:22:45.594169Z","caller":"traceutil/trace.go:171","msg":"trace[1418750905] range","detail":"{range_begin:/registry/pods/kube-system/cilium-khcgg; range_end:; response_count:1; response_revision:579; }","duration":"148.859335ms","start":"2026-03-31T11:22:45.445301Z","end":"2026-03-31T11:22:45.59416Z","steps":["trace[1418750905] 'agreement among raft nodes before linearized reading' (duration: 148.717762ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:22:45.594321Z","caller":"traceutil/trace.go:171","msg":"trace[665048372] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"423.986864ms","start":"2026-03-31T11:22:45.170291Z","end":"2026-03-31T11:22:45.594278Z","steps":["trace[665048372] 'process raft request' (duration: 423.118732ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:22:45.594556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:22:45.170264Z","time spent":"424.196459ms","remote":"127.0.0.1:46526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036349,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T11:22:46.43977Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835591840486371029,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T11:22:46.672265Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.042086724s","expected-duration":"1s"} {"level":"info","ts":"2026-03-31T11:22:46.672808Z","caller":"traceutil/trace.go:171","msg":"trace[498302357] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"1.042702669s","start":"2026-03-31T11:22:45.630075Z","end":"2026-03-31T11:22:46.672778Z","steps":["trace[498302357] 'process raft request' (duration: 1.042411262s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:22:46.673053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:22:45.630059Z","time spent":"1.042822333s","remote":"127.0.0.1:46618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:22:46.705067Z","caller":"traceutil/trace.go:171","msg":"trace[1577777867] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"1.033306208s","start":"2026-03-31T11:22:45.671722Z","end":"2026-03-31T11:22:46.705028Z","steps":["trace[1577777867] 'process raft request' (duration: 1.033042281s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:22:46.705239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:22:45.671684Z","time spent":"1.033481222s","remote":"127.0.0.1:46806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T11:22:46.730876Z","caller":"traceutil/trace.go:171","msg":"trace[938517234] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:601; }","duration":"792.151835ms","start":"2026-03-31T11:22:45.938692Z","end":"2026-03-31T11:22:46.730844Z","steps":["trace[938517234] 'read index received' (duration: 733.773302ms)","trace[938517234] 'applied index is now lower than readState.Index' (duration: 58.376003ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:22:46.731162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.449673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-31T11:22:46.731216Z","caller":"traceutil/trace.go:171","msg":"trace[1763970918] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:581; }","duration":"792.547726ms","start":"2026-03-31T11:22:45.938653Z","end":"2026-03-31T11:22:46.731201Z","steps":["trace[1763970918] 'agreement among raft nodes before linearized reading' (duration: 792.359501ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:22:46.731288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:22:45.938636Z","time spent":"792.641947ms","remote":"127.0.0.1:46708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-31T11:22:46.731566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.830403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-31T11:22:46.731712Z","caller":"traceutil/trace.go:171","msg":"trace[1554656827] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:581; }","duration":"793.022157ms","start":"2026-03-31T11:22:45.938671Z","end":"2026-03-31T11:22:46.731693Z","steps":["trace[1554656827] 'agreement among raft nodes before linearized reading' (duration: 792.451894ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:22:46.731765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.974236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:22:46.731847Z","caller":"traceutil/trace.go:171","msg":"trace[579500646] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:581; }","duration":"644.089479ms","start":"2026-03-31T11:22:46.087742Z","end":"2026-03-31T11:22:46.731831Z","steps":["trace[579500646] 'agreement among raft nodes before linearized reading' (duration: 643.980556ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:22:46.731879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.305246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-31T11:22:46.731929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:22:46.087702Z","time spent":"644.185251ms","remote":"127.0.0.1:46444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-31T11:22:46.73195Z","caller":"traceutil/trace.go:171","msg":"trace[1454362893] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:581; }","duration":"791.389467ms","start":"2026-03-31T11:22:45.940548Z","end":"2026-03-31T11:22:46.731937Z","steps":["trace[1454362893] 'agreement among raft nodes before linearized reading' (duration: 791.252774ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:22:46.731984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:22:45.940538Z","time spent":"791.439418ms","remote":"127.0.0.1:46708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-31T11:22:46.732086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:22:45.938664Z","time spent":"793.14491ms","remote":"127.0.0.1:46708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-31T11:22:47.792368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.974444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:22:47.792493Z","caller":"traceutil/trace.go:171","msg":"trace[1341171038] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:591; }","duration":"201.18231ms","start":"2026-03-31T11:22:47.591273Z","end":"2026-03-31T11:22:47.792456Z","steps":["trace[1341171038] 'range keys from in-memory index tree' (duration: 200.834691ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:22:47.792611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.294849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:22:47.792667Z","caller":"traceutil/trace.go:171","msg":"trace[2137217166] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:591; }","duration":"176.381691ms","start":"2026-03-31T11:22:47.616274Z","end":"2026-03-31T11:22:47.792656Z","steps":["trace[2137217166] 'count revisions from in-memory index tree' (duration: 176.256768ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:22:47.792629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.399312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:22:47.792742Z","caller":"traceutil/trace.go:171","msg":"trace[1987962344] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:591; }","duration":"176.579906ms","start":"2026-03-31T11:22:47.61614Z","end":"2026-03-31T11:22:47.79272Z","steps":["trace[1987962344] 'range keys from in-memory index tree' (duration: 176.32796ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:22:47.792868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.602061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:22:47.792943Z","caller":"traceutil/trace.go:171","msg":"trace[779964659] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:614; }","duration":"154.575697ms","start":"2026-03-31T11:22:47.638362Z","end":"2026-03-31T11:22:47.792937Z","steps":["trace[779964659] 'read index received' (duration: 152.542627ms)","trace[779964659] 'applied index is now lower than readState.Index' (duration: 2.03253ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:22:47.792935Z","caller":"traceutil/trace.go:171","msg":"trace[563385598] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:591; }","duration":"201.692823ms","start":"2026-03-31T11:22:47.591231Z","end":"2026-03-31T11:22:47.792924Z","steps":["trace[563385598] 'range keys from in-memory index tree' (duration: 201.5539ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:22:47.793315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.540076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:22:47.79335Z","caller":"traceutil/trace.go:171","msg":"trace[444750449] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:592; }","duration":"154.605657ms","start":"2026-03-31T11:22:47.638736Z","end":"2026-03-31T11:22:47.793342Z","steps":["trace[444750449] 'agreement among raft nodes before linearized reading' (duration: 154.544395ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:22:47.793375Z","caller":"traceutil/trace.go:171","msg":"trace[1349972596] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"155.825787ms","start":"2026-03-31T11:22:47.637532Z","end":"2026-03-31T11:22:47.793358Z","steps":["trace[1349972596] 'process raft request' (duration: 153.602122ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:22:47.793457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.137819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:22:47.793481Z","caller":"traceutil/trace.go:171","msg":"trace[1081803395] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:592; }","duration":"100.184611ms","start":"2026-03-31T11:22:47.69329Z","end":"2026-03-31T11:22:47.793475Z","steps":["trace[1081803395] 'agreement among raft nodes before linearized reading' (duration: 100.15186ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:22:47.793537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.217731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T11:22:47.793539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.186752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:22:47.793554Z","caller":"traceutil/trace.go:171","msg":"trace[2005439740] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:592; }","duration":"100.262903ms","start":"2026-03-31T11:22:47.693287Z","end":"2026-03-31T11:22:47.79355Z","steps":["trace[2005439740] 'agreement among raft nodes before linearized reading' (duration: 100.236272ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:22:47.793592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.311915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:22:47.793572Z","caller":"traceutil/trace.go:171","msg":"trace[349152282] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:592; }","duration":"155.251654ms","start":"2026-03-31T11:22:47.63831Z","end":"2026-03-31T11:22:47.793562Z","steps":["trace[349152282] 'agreement among raft nodes before linearized reading' (duration: 155.171902ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:22:47.793609Z","caller":"traceutil/trace.go:171","msg":"trace[1541890563] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:592; }","duration":"153.344756ms","start":"2026-03-31T11:22:47.64026Z","end":"2026-03-31T11:22:47.793604Z","steps":["trace[1541890563] 'agreement among raft nodes before linearized reading' (duration: 153.321985ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:22:47.792906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.54209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:22:47.794736Z","caller":"traceutil/trace.go:171","msg":"trace[1794235746] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:591; }","duration":"203.376894ms","start":"2026-03-31T11:22:47.591351Z","end":"2026-03-31T11:22:47.794727Z","steps":["trace[1794235746] 'count revisions from in-memory index tree' (duration: 201.455778ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:23:00.322525Z","caller":"traceutil/trace.go:171","msg":"trace[246677493] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"177.374496ms","start":"2026-03-31T11:23:00.145112Z","end":"2026-03-31T11:23:00.322487Z","steps":["trace[246677493] 'process raft request' (duration: 177.066678ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:23:03.29197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.519103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:23:03.292089Z","caller":"traceutil/trace.go:171","msg":"trace[1121754544] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:742; }","duration":"202.755139ms","start":"2026-03-31T11:23:03.089306Z","end":"2026-03-31T11:23:03.292061Z","steps":["trace[1121754544] 'range keys from in-memory index tree' (duration: 202.402321ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:23:03.292132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.822801ms","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-03-31T11:23:03.292207Z","caller":"traceutil/trace.go:171","msg":"trace[900801648] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:742; }","duration":"463.935674ms","start":"2026-03-31T11:23:02.828253Z","end":"2026-03-31T11:23:03.292188Z","steps":["trace[900801648] 'range keys from in-memory index tree' (duration: 463.688767ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:23:03.292254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:23:02.828234Z","time spent":"464.006015ms","remote":"127.0.0.1:46708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-31T11:23:03.291964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.913479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-31T11:23:03.292419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.166515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-31T11:23:03.292512Z","caller":"traceutil/trace.go:171","msg":"trace[1176340644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:742; }","duration":"463.280407ms","start":"2026-03-31T11:23:02.829209Z","end":"2026-03-31T11:23:03.292489Z","steps":["trace[1176340644] 'range keys from in-memory index tree' (duration: 462.903479ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:23:03.292301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.726169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-31T11:23:03.292569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:23:02.829098Z","time spent":"463.461062ms","remote":"127.0.0.1:46708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-31T11:23:03.292627Z","caller":"traceutil/trace.go:171","msg":"trace[1556423290] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:742; }","duration":"464.124778ms","start":"2026-03-31T11:23:02.82847Z","end":"2026-03-31T11:23:03.292595Z","steps":["trace[1556423290] 'range keys from in-memory index tree' (duration: 463.425071ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:23:03.292709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:23:02.828343Z","time spent":"464.352143ms","remote":"127.0.0.1:46708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-31T11:23:03.292513Z","caller":"traceutil/trace.go:171","msg":"trace[1715309757] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:742; }","duration":"463.478173ms","start":"2026-03-31T11:23:02.828993Z","end":"2026-03-31T11:23:03.292471Z","steps":["trace[1715309757] 'range keys from in-memory index tree' (duration: 462.614161ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:23:03.293777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:23:02.828982Z","time spent":"464.751905ms","remote":"127.0.0.1:46708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-31T11:23:03.404642Z","caller":"traceutil/trace.go:171","msg":"trace[1857839281] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"106.760362ms","start":"2026-03-31T11:23:03.297848Z","end":"2026-03-31T11:23:03.404608Z","steps":["trace[1857839281] 'process raft request' (duration: 106.518956ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:23:03.418133Z","caller":"traceutil/trace.go:171","msg":"trace[1501317702] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"120.206012ms","start":"2026-03-31T11:23:03.297892Z","end":"2026-03-31T11:23:03.418098Z","steps":["trace[1501317702] 'process raft request' (duration: 120.039838ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:23:03.418422Z","caller":"traceutil/trace.go:171","msg":"trace[1894111723] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"120.075409ms","start":"2026-03-31T11:23:03.298336Z","end":"2026-03-31T11:23:03.418411Z","steps":["trace[1894111723] 'process raft request' (duration: 119.72099ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:23:03.418702Z","caller":"traceutil/trace.go:171","msg":"trace[1896236316] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"119.938945ms","start":"2026-03-31T11:23:03.298712Z","end":"2026-03-31T11:23:03.418651Z","steps":["trace[1896236316] 'process raft request' (duration: 119.579746ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:23:37.839769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.980256ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T11:23:37.840206Z","caller":"traceutil/trace.go:171","msg":"trace[399017538] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"184.924641ms","start":"2026-03-31T11:23:37.655248Z","end":"2026-03-31T11:23:37.840173Z","steps":["trace[399017538] 'process raft request' (duration: 184.624074ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:23:37.840219Z","caller":"traceutil/trace.go:171","msg":"trace[1115683643] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"185.157026ms","start":"2026-03-31T11:23:37.654994Z","end":"2026-03-31T11:23:37.840151Z","steps":["trace[1115683643] 'process raft request' (duration: 10.564026ms)","trace[1115683643] 'compare' (duration: 173.818302ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:23:38.173173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.575831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:23:38.173263Z","caller":"traceutil/trace.go:171","msg":"trace[524285581] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:963; }","duration":"105.742536ms","start":"2026-03-31T11:23:38.0675Z","end":"2026-03-31T11:23:38.173243Z","steps":["trace[524285581] 'count revisions from in-memory index tree' (duration: 105.4819ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:24:04.095031Z","caller":"traceutil/trace.go:171","msg":"trace[2063365680] linearizableReadLoop","detail":"{readStateIndex:1086; appliedIndex:1085; }","duration":"325.377914ms","start":"2026-03-31T11:24:03.769605Z","end":"2026-03-31T11:24:04.094983Z","steps":["trace[2063365680] 'read index received' (duration: 325.147079ms)","trace[2063365680] 'applied index is now lower than readState.Index' (duration: 229.465µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:24:04.095177Z","caller":"traceutil/trace.go:171","msg":"trace[498453703] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"376.842765ms","start":"2026-03-31T11:24:03.718323Z","end":"2026-03-31T11:24:04.095166Z","steps":["trace[498453703] 'process raft request' (duration: 376.481706ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:24:04.095292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:24:03.718302Z","time spent":"376.911817ms","remote":"127.0.0.1:46708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T11:24:04.095698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.199602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-31T11:24:04.095714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.099791ms","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-03-31T11:24:04.095779Z","caller":"traceutil/trace.go:171","msg":"trace[1829809022] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1046; }","duration":"111.294574ms","start":"2026-03-31T11:24:03.984456Z","end":"2026-03-31T11:24:04.095751Z","steps":["trace[1829809022] 'agreement among raft nodes before linearized reading' (duration: 111.091739ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:24:04.095596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.640458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-31T11:24:04.095777Z","caller":"traceutil/trace.go:171","msg":"trace[118209745] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1046; }","duration":"326.195223ms","start":"2026-03-31T11:24:03.769566Z","end":"2026-03-31T11:24:04.095761Z","steps":["trace[118209745] 'agreement among raft nodes before linearized reading' (duration: 325.959868ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:24:04.095948Z","caller":"traceutil/trace.go:171","msg":"trace[415265153] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1046; }","duration":"113.933475ms","start":"2026-03-31T11:24:03.981931Z","end":"2026-03-31T11:24:04.095864Z","steps":["trace[415265153] 'agreement among raft nodes before linearized reading' (duration: 113.493345ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:24:04.095986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:24:03.769547Z","time spent":"326.347977ms","remote":"127.0.0.1:46708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-31T11:24:04.267147Z","caller":"traceutil/trace.go:171","msg":"trace[2053620666] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"166.258705ms","start":"2026-03-31T11:24:04.100852Z","end":"2026-03-31T11:24:04.267111Z","steps":["trace[2053620666] 'process raft request' (duration: 166.201264ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:24:04.267232Z","caller":"traceutil/trace.go:171","msg":"trace[172346939] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"166.746097ms","start":"2026-03-31T11:24:04.100436Z","end":"2026-03-31T11:24:04.267182Z","steps":["trace[172346939] 'process raft request' (duration: 147.088149ms)","trace[172346939] 'compare' (duration: 19.236318ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:24:04.267435Z","caller":"traceutil/trace.go:171","msg":"trace[886777969] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"166.772627ms","start":"2026-03-31T11:24:04.10063Z","end":"2026-03-31T11:24:04.267402Z","steps":["trace[886777969] 'process raft request' (duration: 166.362787ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:24:04.940352Z","caller":"traceutil/trace.go:171","msg":"trace[1272519148] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"173.163267ms","start":"2026-03-31T11:24:04.767161Z","end":"2026-03-31T11:24:04.940325Z","steps":["trace[1272519148] 'process raft request' (duration: 172.986273ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:24:05.35417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.121238ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:24:05.354297Z","caller":"traceutil/trace.go:171","msg":"trace[22937558] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1050; }","duration":"349.273571ms","start":"2026-03-31T11:24:05.005005Z","end":"2026-03-31T11:24:05.354279Z","steps":["trace[22937558] 'range keys from in-memory index tree' (duration: 349.106308ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:24:05.354864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.928621ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T11:24:05.354988Z","caller":"traceutil/trace.go:171","msg":"trace[1260980910] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"399.271918ms","start":"2026-03-31T11:24:04.955697Z","end":"2026-03-31T11:24:05.354969Z","steps":["trace[1260980910] 'process raft request' (duration: 226.139512ms)","trace[1260980910] 'compare' (duration: 172.793328ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:24:05.355064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.423424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T11:24:05.3551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:24:04.955673Z","time spent":"399.36305ms","remote":"127.0.0.1:46708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:24:05.355116Z","caller":"traceutil/trace.go:171","msg":"trace[160166782] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1051; }","duration":"267.497346ms","start":"2026-03-31T11:24:05.087606Z","end":"2026-03-31T11:24:05.355104Z","steps":["trace[160166782] 'agreement among raft nodes before linearized reading' (duration: 267.417694ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:24:05.354989Z","caller":"traceutil/trace.go:171","msg":"trace[1682437156] linearizableReadLoop","detail":"{readStateIndex:1091; appliedIndex:1090; }","duration":"267.326932ms","start":"2026-03-31T11:24:05.08764Z","end":"2026-03-31T11:24:05.354967Z","steps":["trace[1682437156] 'read index received' (duration: 94.084103ms)","trace[1682437156] 'applied index is now lower than readState.Index' (duration: 173.241359ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:24:05.536198Z","caller":"traceutil/trace.go:171","msg":"trace[1748067156] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"150.735562ms","start":"2026-03-31T11:24:05.385437Z","end":"2026-03-31T11:24:05.536173Z","steps":["trace[1748067156] 'process raft request' (duration: 150.537028ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:24:17.681158Z","caller":"traceutil/trace.go:171","msg":"trace[644608129] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"100.110426ms","start":"2026-03-31T11:24:17.581023Z","end":"2026-03-31T11:24:17.681133Z","steps":["trace[644608129] 'process raft request' (duration: 99.785368ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:24:18.944808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.468207ms","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-03-31T11:24:18.944862Z","caller":"traceutil/trace.go:171","msg":"trace[2101848887] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1143; }","duration":"613.564908ms","start":"2026-03-31T11:24:18.331284Z","end":"2026-03-31T11:24:18.944849Z","steps":["trace[2101848887] 'range keys from in-memory index tree' (duration: 613.314693ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:24:18.944884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:24:18.331262Z","time spent":"613.61723ms","remote":"127.0.0.1:46708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-31T11:24:18.945016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.521193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-31T11:24:18.945029Z","caller":"traceutil/trace.go:171","msg":"trace[1481512718] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1143; }","duration":"612.577724ms","start":"2026-03-31T11:24:18.332447Z","end":"2026-03-31T11:24:18.945024Z","steps":["trace[1481512718] 'range keys from in-memory index tree' (duration: 612.40591ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:24:18.945041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:24:18.332421Z","time spent":"612.616816ms","remote":"127.0.0.1:46708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-31T11:24:18.945119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.709408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-31T11:24:18.945133Z","caller":"traceutil/trace.go:171","msg":"trace[548115952] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1143; }","duration":"610.743309ms","start":"2026-03-31T11:24:18.334385Z","end":"2026-03-31T11:24:18.945128Z","steps":["trace[548115952] 'range keys from in-memory index tree' (duration: 610.620575ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:24:18.945146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:24:18.334374Z","time spent":"610.770109ms","remote":"127.0.0.1:46708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-31T11:24:18.945368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.838324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-31T11:24:18.945382Z","caller":"traceutil/trace.go:171","msg":"trace[2012473486] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1143; }","duration":"313.889185ms","start":"2026-03-31T11:24:18.631488Z","end":"2026-03-31T11:24:18.945377Z","steps":["trace[2012473486] 'count revisions from in-memory index tree' (duration: 313.746111ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:24:18.945397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:24:18.631469Z","time spent":"313.924455ms","remote":"127.0.0.1:46910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"info","ts":"2026-03-31T11:24:19.406184Z","caller":"traceutil/trace.go:171","msg":"trace[58139596] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"456.962485ms","start":"2026-03-31T11:24:18.949188Z","end":"2026-03-31T11:24:19.40615Z","steps":["trace[58139596] 'process raft request' (duration: 456.725249ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:24:19.406408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:24:18.949166Z","time spent":"457.108649ms","remote":"127.0.0.1:46708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:24:19.424172Z","caller":"traceutil/trace.go:171","msg":"trace[1681984799] linearizableReadLoop","detail":"{readStateIndex:1191; appliedIndex:1188; }","duration":"365.460271ms","start":"2026-03-31T11:24:19.058701Z","end":"2026-03-31T11:24:19.424162Z","steps":["trace[1681984799] 'read index received' (duration: 347.187339ms)","trace[1681984799] 'applied index is now lower than readState.Index' (duration: 18.272142ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:24:19.424282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.577864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-03-31T11:24:19.424309Z","caller":"traceutil/trace.go:171","msg":"trace[1323817190] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1146; }","duration":"365.661186ms","start":"2026-03-31T11:24:19.05864Z","end":"2026-03-31T11:24:19.424301Z","steps":["trace[1323817190] 'agreement among raft nodes before linearized reading' (duration: 365.562933ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:24:19.424333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:24:19.058614Z","time spent":"365.713768ms","remote":"127.0.0.1:46708","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":"info","ts":"2026-03-31T11:24:19.424399Z","caller":"traceutil/trace.go:171","msg":"trace[329639099] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"473.030342ms","start":"2026-03-31T11:24:18.951363Z","end":"2026-03-31T11:24:19.424393Z","steps":["trace[329639099] 'process raft request' (duration: 472.766276ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:24:19.424444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:24:18.951351Z","time spent":"473.065903ms","remote":"127.0.0.1:46708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:24:19.424484Z","caller":"traceutil/trace.go:171","msg":"trace[472220518] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"474.831026ms","start":"2026-03-31T11:24:18.94961Z","end":"2026-03-31T11:24:19.424441Z","steps":["trace[472220518] 'process raft request' (duration: 474.464487ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:24:19.424656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:24:18.949591Z","time spent":"474.976129ms","remote":"127.0.0.1:46708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:24:35.061713Z","caller":"traceutil/trace.go:171","msg":"trace[80795348] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"165.73695ms","start":"2026-03-31T11:24:34.895948Z","end":"2026-03-31T11:24:35.061685Z","steps":["trace[80795348] 'process raft request' (duration: 165.592766ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:24:35.249424Z","caller":"traceutil/trace.go:171","msg":"trace[1405584001] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"172.377273ms","start":"2026-03-31T11:24:35.077029Z","end":"2026-03-31T11:24:35.249406Z","steps":["trace[1405584001] 'process raft request' (duration: 172.339482ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:24:35.249465Z","caller":"traceutil/trace.go:171","msg":"trace[1216134149] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"177.180623ms","start":"2026-03-31T11:24:35.072251Z","end":"2026-03-31T11:24:35.249432Z","steps":["trace[1216134149] 'process raft request' (duration: 176.989248ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:24:35.622914Z","caller":"traceutil/trace.go:171","msg":"trace[1920617242] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"120.412199ms","start":"2026-03-31T11:24:35.502474Z","end":"2026-03-31T11:24:35.622886Z","steps":["trace[1920617242] 'process raft request' (duration: 120.235725ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:24:35.970454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.670788ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T11:24:35.970652Z","caller":"traceutil/trace.go:171","msg":"trace[1925034793] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"466.383037ms","start":"2026-03-31T11:24:35.504217Z","end":"2026-03-31T11:24:35.9706Z","steps":["trace[1925034793] 'process raft request' (duration: 302.456592ms)","trace[1925034793] 'compare' (duration: 163.514305ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:24:35.970702Z","caller":"traceutil/trace.go:171","msg":"trace[119608424] linearizableReadLoop","detail":"{readStateIndex:1264; appliedIndex:1262; }","duration":"465.488416ms","start":"2026-03-31T11:24:35.505182Z","end":"2026-03-31T11:24:35.970671Z","steps":["trace[119608424] 'read index received' (duration: 117.567228ms)","trace[119608424] 'applied index is now lower than readState.Index' (duration: 347.918698ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:24:35.970808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:24:35.504204Z","time spent":"466.521891ms","remote":"127.0.0.1:46708","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-03-31T11:24:35.970999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.789244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5375"} {"level":"info","ts":"2026-03-31T11:24:35.971107Z","caller":"traceutil/trace.go:171","msg":"trace[1917018587] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1216; }","duration":"465.960878ms","start":"2026-03-31T11:24:35.505119Z","end":"2026-03-31T11:24:35.97108Z","steps":["trace[1917018587] 'agreement among raft nodes before linearized reading' (duration: 465.64302ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:24:35.971154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:24:35.505109Z","time spent":"466.036229ms","remote":"127.0.0.1:46616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5398,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-03-31T11:24:35.971325Z","caller":"traceutil/trace.go:171","msg":"trace[1724687536] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"237.478485ms","start":"2026-03-31T11:24:35.733826Z","end":"2026-03-31T11:24:35.971304Z","steps":["trace[1724687536] 'process raft request' (duration: 236.741556ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:24:36.031418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.756045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-31T11:24:36.031504Z","caller":"traceutil/trace.go:171","msg":"trace[1891089391] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1217; }","duration":"508.886998ms","start":"2026-03-31T11:24:35.5226Z","end":"2026-03-31T11:24:36.031487Z","steps":["trace[1891089391] 'agreement among raft nodes before linearized reading' (duration: 508.641972ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:24:36.031539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:24:35.522579Z","time spent":"508.95261ms","remote":"127.0.0.1:46708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-31T11:24:36.031575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.578508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-31T11:24:36.031642Z","caller":"traceutil/trace.go:171","msg":"trace[1799165862] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1217; }","duration":"503.698731ms","start":"2026-03-31T11:24:35.52793Z","end":"2026-03-31T11:24:36.031628Z","steps":["trace[1799165862] 'agreement among raft nodes before linearized reading' (duration: 503.521197ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:24:36.031684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:24:35.527878Z","time spent":"503.795823ms","remote":"127.0.0.1:46708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-31T11:24:37.011687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.103844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:24:37.011807Z","caller":"traceutil/trace.go:171","msg":"trace[1113736723] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1225; }","duration":"397.258588ms","start":"2026-03-31T11:24:36.614528Z","end":"2026-03-31T11:24:37.011786Z","steps":["trace[1113736723] 'range keys from in-memory index tree' (duration: 397.015372ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:24:37.011923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:24:36.614509Z","time spent":"397.37367ms","remote":"127.0.0.1:46708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-31T11:24:37.0127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.114954ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-31T11:24:37.012797Z","caller":"traceutil/trace.go:171","msg":"trace[746217337] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"179.568653ms","start":"2026-03-31T11:24:36.833213Z","end":"2026-03-31T11:24:37.012782Z","steps":["trace[746217337] 'process raft request' (duration: 70.70425ms)","trace[746217337] 'compare' (duration: 107.731435ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:24:37.321391Z","caller":"traceutil/trace.go:171","msg":"trace[1162839560] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"305.18264ms","start":"2026-03-31T11:24:37.016182Z","end":"2026-03-31T11:24:37.321364Z","steps":["trace[1162839560] 'process raft request' (duration: 305.005566ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:24:37.321552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:24:37.016158Z","time spent":"305.319383ms","remote":"127.0.0.1:46510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T11:24:37.345935Z","caller":"traceutil/trace.go:171","msg":"trace[1187406218] linearizableReadLoop","detail":"{readStateIndex:1277; appliedIndex:1276; }","duration":"256.871784ms","start":"2026-03-31T11:24:37.089033Z","end":"2026-03-31T11:24:37.345904Z","steps":["trace[1187406218] 'read index received' (duration: 232.968003ms)","trace[1187406218] 'applied index is now lower than readState.Index' (duration: 23.902701ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:24:37.346135Z","caller":"traceutil/trace.go:171","msg":"trace[461308991] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"328.029995ms","start":"2026-03-31T11:24:37.018034Z","end":"2026-03-31T11:24:37.346064Z","steps":["trace[461308991] 'process raft request' (duration: 327.717478ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:24:37.346185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.126141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:24:37.346243Z","caller":"traceutil/trace.go:171","msg":"trace[159203438] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1228; }","duration":"257.242614ms","start":"2026-03-31T11:24:37.088982Z","end":"2026-03-31T11:24:37.346225Z","steps":["trace[159203438] 'agreement among raft nodes before linearized reading' (duration: 257.038428ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:24:37.346299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:24:37.01802Z","time spent":"328.20949ms","remote":"127.0.0.1:46708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T11:24:37.802164Z","caller":"traceutil/trace.go:171","msg":"trace[1710765615] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"170.011175ms","start":"2026-03-31T11:24:37.632124Z","end":"2026-03-31T11:24:37.802135Z","steps":["trace[1710765615] 'process raft request' (duration: 169.790979ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:24:38.188953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.494323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:24:38.18902Z","caller":"traceutil/trace.go:171","msg":"trace[1552439211] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1231; }","duration":"357.607316ms","start":"2026-03-31T11:24:37.831396Z","end":"2026-03-31T11:24:38.189004Z","steps":["trace[1552439211] 'count revisions from in-memory index tree' (duration: 357.343859ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:24:38.189198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.60717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-31T11:24:38.189278Z","caller":"traceutil/trace.go:171","msg":"trace[974488320] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1231; }","duration":"210.755593ms","start":"2026-03-31T11:24:37.978505Z","end":"2026-03-31T11:24:38.189261Z","steps":["trace[974488320] 'range keys from in-memory index tree' (duration: 210.382364ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:24:38.189052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:24:37.831377Z","time spent":"357.666898ms","remote":"127.0.0.1:38190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"info","ts":"2026-03-31T11:24:38.238122Z","caller":"traceutil/trace.go:171","msg":"trace[856596831] linearizableReadLoop","detail":"{readStateIndex:1281; appliedIndex:1280; }","duration":"176.874245ms","start":"2026-03-31T11:24:38.061224Z","end":"2026-03-31T11:24:38.238098Z","steps":["trace[856596831] 'read index received' (duration: 176.704011ms)","trace[856596831] 'applied index is now lower than readState.Index' (duration: 169.004µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:24:38.238452Z","caller":"traceutil/trace.go:171","msg":"trace[1025161047] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"256.68558ms","start":"2026-03-31T11:24:37.981752Z","end":"2026-03-31T11:24:38.238437Z","steps":["trace[1025161047] 'process raft request' (duration: 256.191028ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:24:38.238626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.360787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-31T11:24:38.238694Z","caller":"traceutil/trace.go:171","msg":"trace[1748955074] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1232; }","duration":"177.509891ms","start":"2026-03-31T11:24:38.061167Z","end":"2026-03-31T11:24:38.238677Z","steps":["trace[1748955074] 'agreement among raft nodes before linearized reading' (duration: 177.249835ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:24:38.700913Z","caller":"traceutil/trace.go:171","msg":"trace[958881208] linearizableReadLoop","detail":"{readStateIndex:1282; appliedIndex:1281; }","duration":"462.693796ms","start":"2026-03-31T11:24:38.238193Z","end":"2026-03-31T11:24:38.700886Z","steps":["trace[958881208] 'read index received' (duration: 462.653785ms)","trace[958881208] 'applied index is now lower than readState.Index' (duration: 38.781µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:24:38.70104Z","caller":"traceutil/trace.go:171","msg":"trace[743649725] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"507.443283ms","start":"2026-03-31T11:24:38.193586Z","end":"2026-03-31T11:24:38.701029Z","steps":["trace[743649725] 'process raft request' (duration: 507.101554ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:24:38.701159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:24:38.193556Z","time spent":"507.516455ms","remote":"127.0.0.1:46708","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-03-31T11:24:38.701443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.127865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-31T11:24:38.701538Z","caller":"traceutil/trace.go:171","msg":"trace[1397397753] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1233; }","duration":"640.3072ms","start":"2026-03-31T11:24:38.061208Z","end":"2026-03-31T11:24:38.701515Z","steps":["trace[1397397753] 'agreement among raft nodes before linearized reading' (duration: 640.063134ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:24:38.701682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:24:38.06118Z","time spent":"640.487403ms","remote":"127.0.0.1:46708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-31T11:24:38.715323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.360961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-31T11:24:38.715418Z","caller":"traceutil/trace.go:171","msg":"trace[1188019813] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1234; }","duration":"372.529616ms","start":"2026-03-31T11:24:38.342866Z","end":"2026-03-31T11:24:38.715396Z","steps":["trace[1188019813] 'agreement among raft nodes before linearized reading' (duration: 372.267079ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:24:38.715456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:24:38.342836Z","time spent":"372.612668ms","remote":"127.0.0.1:46708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-31T11:24:38.715553Z","caller":"traceutil/trace.go:171","msg":"trace[1968758576] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"473.578265ms","start":"2026-03-31T11:24:38.241961Z","end":"2026-03-31T11:24:38.71554Z","steps":["trace[1968758576] 'process raft request' (duration: 473.018512ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:24:38.715654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:24:38.241937Z","time spent":"473.647597ms","remote":"127.0.0.1:46708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:24:38.901755Z","caller":"traceutil/trace.go:171","msg":"trace[1644627180] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"181.137301ms","start":"2026-03-31T11:24:38.720584Z","end":"2026-03-31T11:24:38.901721Z","steps":["trace[1644627180] 'process raft request' (duration: 101.843378ms)","trace[1644627180] 'compare' (duration: 79.131389ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:24:39.207257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.610189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:24:39.207318Z","caller":"traceutil/trace.go:171","msg":"trace[505717356] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1236; }","duration":"119.723242ms","start":"2026-03-31T11:24:39.087582Z","end":"2026-03-31T11:24:39.207305Z","steps":["trace[505717356] 'range keys from in-memory index tree' (duration: 119.535127ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:24:52.575589Z","caller":"traceutil/trace.go:171","msg":"trace[816600145] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"305.765454ms","start":"2026-03-31T11:24:52.269796Z","end":"2026-03-31T11:24:52.575561Z","steps":["trace[816600145] 'process raft request' (duration: 305.58681ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:24:52.575768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:24:52.269777Z","time spent":"305.905337ms","remote":"127.0.0.1:46708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:24:52.798295Z","caller":"traceutil/trace.go:171","msg":"trace[414277572] linearizableReadLoop","detail":"{readStateIndex:1376; appliedIndex:1375; }","duration":"354.214752ms","start":"2026-03-31T11:24:52.444055Z","end":"2026-03-31T11:24:52.79827Z","steps":["trace[414277572] 'read index received' (duration: 131.568825ms)","trace[414277572] 'applied index is now lower than readState.Index' (duration: 222.644897ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:24:52.798518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.450538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-03-31T11:24:52.798556Z","caller":"traceutil/trace.go:171","msg":"trace[231069307] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1324; }","duration":"354.542881ms","start":"2026-03-31T11:24:52.444004Z","end":"2026-03-31T11:24:52.798547Z","steps":["trace[231069307] 'agreement among raft nodes before linearized reading' (duration: 354.395986ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:24:52.798375Z","caller":"traceutil/trace.go:171","msg":"trace[38949929] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"513.470512ms","start":"2026-03-31T11:24:52.284867Z","end":"2026-03-31T11:24:52.798338Z","steps":["trace[38949929] 'process raft request' (duration: 513.214025ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:24:52.79878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:24:52.284848Z","time spent":"513.807091ms","remote":"127.0.0.1:46708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T11:24:52.798607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:24:52.443979Z","time spent":"354.619903ms","remote":"127.0.0.1:46708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-31T11:24:53.144432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.109111ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T11:24:53.144668Z","caller":"traceutil/trace.go:171","msg":"trace[2043107409] linearizableReadLoop","detail":"{readStateIndex:1380; appliedIndex:1376; }","duration":"289.291856ms","start":"2026-03-31T11:24:52.855358Z","end":"2026-03-31T11:24:53.14465Z","steps":["trace[2043107409] 'read index received' (duration: 258.962906ms)","trace[2043107409] 'applied index is now lower than readState.Index' (duration: 30.32791ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:24:53.14481Z","caller":"traceutil/trace.go:171","msg":"trace[1971572715] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"341.271093ms","start":"2026-03-31T11:24:52.803488Z","end":"2026-03-31T11:24:53.144759Z","steps":["trace[1971572715] 'process raft request' (duration: 341.045888ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:24:53.144871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.87216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-03-31T11:24:53.144905Z","caller":"traceutil/trace.go:171","msg":"trace[1862631290] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1328; }","duration":"257.940181ms","start":"2026-03-31T11:24:52.886955Z","end":"2026-03-31T11:24:53.144895Z","steps":["trace[1862631290] 'agreement among raft nodes before linearized reading' (duration: 257.803288ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:24:53.144982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:24:52.803459Z","time spent":"341.439486ms","remote":"127.0.0.1:46708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T11:24:53.145011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.612684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-31T11:24:53.145019Z","caller":"traceutil/trace.go:171","msg":"trace[1371091650] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"341.811116ms","start":"2026-03-31T11:24:52.803176Z","end":"2026-03-31T11:24:53.144987Z","steps":["trace[1371091650] 'compare' (duration: 334.771352ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:24:53.14504Z","caller":"traceutil/trace.go:171","msg":"trace[566976586] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"341.321614ms","start":"2026-03-31T11:24:52.803689Z","end":"2026-03-31T11:24:53.14501Z","steps":["trace[566976586] 'process raft request' (duration: 340.894584ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:24:53.145099Z","caller":"traceutil/trace.go:171","msg":"trace[1688604383] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1328; }","duration":"289.763318ms","start":"2026-03-31T11:24:52.855318Z","end":"2026-03-31T11:24:53.145081Z","steps":["trace[1688604383] 'agreement among raft nodes before linearized reading' (duration: 289.45364ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:24:53.145109Z","caller":"traceutil/trace.go:171","msg":"trace[10424247] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"340.7876ms","start":"2026-03-31T11:24:52.804311Z","end":"2026-03-31T11:24:53.145099Z","steps":["trace[10424247] 'process raft request' (duration: 340.305079ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:24:53.145173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:24:52.804293Z","time spent":"340.845331ms","remote":"127.0.0.1:46708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T11:24:53.145177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:24:52.803128Z","time spent":"341.969909ms","remote":"127.0.0.1:46708","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-03-31T11:24:53.1452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:24:52.80368Z","time spent":"341.430497ms","remote":"127.0.0.1:46708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T11:24:53.145052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.493945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-31T11:24:53.145271Z","caller":"traceutil/trace.go:171","msg":"trace[1153632838] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1328; }","duration":"182.749311ms","start":"2026-03-31T11:24:52.962499Z","end":"2026-03-31T11:24:53.145248Z","steps":["trace[1153632838] 'agreement among raft nodes before linearized reading' (duration: 182.495225ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:24:53.314912Z","caller":"traceutil/trace.go:171","msg":"trace[1097577619] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"163.922046ms","start":"2026-03-31T11:24:53.150964Z","end":"2026-03-31T11:24:53.314886Z","steps":["trace[1097577619] 'process raft request' (duration: 163.69512ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:24:53.315091Z","caller":"traceutil/trace.go:171","msg":"trace[1238107884] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"164.021008ms","start":"2026-03-31T11:24:53.151042Z","end":"2026-03-31T11:24:53.315063Z","steps":["trace[1238107884] 'process raft request' (duration: 163.779722ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:27:10.813651Z","caller":"traceutil/trace.go:171","msg":"trace[1106429978] linearizableReadLoop","detail":"{readStateIndex:2039; appliedIndex:2038; }","duration":"102.986859ms","start":"2026-03-31T11:27:10.710636Z","end":"2026-03-31T11:27:10.813623Z","steps":["trace[1106429978] 'read index received' (duration: 102.655291ms)","trace[1106429978] 'applied index is now lower than readState.Index' (duration: 330.428µs)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:27:10.813943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.268106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-03-31T11:27:10.813989Z","caller":"traceutil/trace.go:171","msg":"trace[46587006] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1959; }","duration":"103.392069ms","start":"2026-03-31T11:27:10.710583Z","end":"2026-03-31T11:27:10.813975Z","steps":["trace[46587006] 'agreement among raft nodes before linearized reading' (duration: 103.156393ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:27:10.814062Z","caller":"traceutil/trace.go:171","msg":"trace[871312330] transaction","detail":"{read_only:false; response_revision:1959; number_of_response:1; }","duration":"106.341012ms","start":"2026-03-31T11:27:10.707683Z","end":"2026-03-31T11:27:10.814024Z","steps":["trace[871312330] 'process raft request' (duration: 105.671676ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:27:11.017534Z","caller":"traceutil/trace.go:171","msg":"trace[1125904822] transaction","detail":"{read_only:false; response_revision:1960; number_of_response:1; }","duration":"196.75128ms","start":"2026-03-31T11:27:10.820721Z","end":"2026-03-31T11:27:11.017472Z","steps":["trace[1125904822] 'process raft request' (duration: 162.078949ms)","trace[1125904822] 'compare' (duration: 34.355693ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:28:33.075889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.618698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kubelet-api-admin\" ","response":"range_response_count:1 size:662"} {"level":"info","ts":"2026-03-31T11:28:33.076035Z","caller":"traceutil/trace.go:171","msg":"trace[1811548599] range","detail":"{range_begin:/registry/clusterroles/system:kubelet-api-admin; range_end:; response_count:1; response_revision:2373; }","duration":"103.831963ms","start":"2026-03-31T11:28:32.972179Z","end":"2026-03-31T11:28:33.076011Z","steps":["trace[1811548599] 'range keys from in-memory index tree' (duration: 103.405472ms)"],"step_count":1}