{"level":"warn","ts":"2026-03-31T05:00:56.020217Z","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-31T05:00:56.020294Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.115: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.115:2380","--initial-cluster=instance=https://199.204.45.115:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.115:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.115: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-31T05:00:56.02035Z","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-31T05:00:56.020364Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-03-31T05:00:56.020381Z","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-31T05:00:56.021025Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"]} {"level":"info","ts":"2026-03-31T05:00:56.021093Z","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.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.115: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-31T05:00:56.026177Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.872451ms"} {"level":"info","ts":"2026-03-31T05:00:56.033442Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e3aaa5c123e150e0","cluster-id":"61ec519698823541"} {"level":"info","ts":"2026-03-31T05:00:56.033557Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=()"} {"level":"info","ts":"2026-03-31T05:00:56.033589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 0"} {"level":"info","ts":"2026-03-31T05:00:56.033609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e3aaa5c123e150e0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-31T05:00:56.033636Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 1"} {"level":"info","ts":"2026-03-31T05:00:56.033699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"warn","ts":"2026-03-31T05:00:56.043443Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-31T05:00:56.044592Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-31T05:00:56.045675Z","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-31T05:00:56.047966Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e3aaa5c123e150e0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-31T05:00:56.048142Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e3aaa5c123e150e0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-31T05:00:56.048354Z","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-31T05:00:56.048426Z","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-31T05:00:56.048436Z","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-31T05:00:56.051665Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"info","ts":"2026-03-31T05:00:56.051883Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","added-peer-id":"e3aaa5c123e150e0","added-peer-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-03-31T05:00:56.053651Z","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-31T05:00:56.053694Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-03-31T05:00:56.053747Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-03-31T05:00:56.053824Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e3aaa5c123e150e0","initial-advertise-peer-urls":["https://199.204.45.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-31T05:00:56.053846Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-31T05:00:56.934434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 is starting a new election at term 1"} {"level":"info","ts":"2026-03-31T05:00:56.934515Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-31T05:00:56.934556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgPreVoteResp from e3aaa5c123e150e0 at term 1"} {"level":"info","ts":"2026-03-31T05:00:56.934576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became candidate at term 2"} {"level":"info","ts":"2026-03-31T05:00:56.934587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgVoteResp from e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-03-31T05:00:56.934603Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became leader at term 2"} {"level":"info","ts":"2026-03-31T05:00:56.934627Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e3aaa5c123e150e0 elected leader e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-03-31T05:00:56.935655Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T05:00:56.936605Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e3aaa5c123e150e0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.115:2379]}","request-path":"/0/members/e3aaa5c123e150e0/attributes","cluster-id":"61ec519698823541","publish-timeout":"7s"} {"level":"info","ts":"2026-03-31T05:00:56.936655Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-31T05:00:56.936695Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-31T05:00:56.937043Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-31T05:00:56.937079Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T05:00:56.937118Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-31T05:00:56.937223Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T05:00:56.937272Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T05:00:56.93969Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.115:2379"} {"level":"info","ts":"2026-03-31T05:00:56.939708Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-31T05:01:26.07475Z","caller":"traceutil/trace.go:171","msg":"trace[1026819518] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"502.152131ms","start":"2026-03-31T05:01:25.572553Z","end":"2026-03-31T05:01:26.074705Z","steps":["trace[1026819518] 'process raft request' (duration: 497.4428ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:26.075844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:01:25.572535Z","time spent":"502.314095ms","remote":"127.0.0.1:57678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T05:01:26.088366Z","caller":"traceutil/trace.go:171","msg":"trace[1410002633] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"511.069375ms","start":"2026-03-31T05:01:25.577271Z","end":"2026-03-31T05:01:26.08834Z","steps":["trace[1410002633] 'process raft request' (duration: 510.876799ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:01:26.088416Z","caller":"traceutil/trace.go:171","msg":"trace[1257472292] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"493.624198ms","start":"2026-03-31T05:01:25.594757Z","end":"2026-03-31T05:01:26.088381Z","steps":["trace[1257472292] 'process raft request' (duration: 493.533465ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:01:26.08853Z","caller":"traceutil/trace.go:171","msg":"trace[2127507491] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"475.732607ms","start":"2026-03-31T05:01:25.61279Z","end":"2026-03-31T05:01:26.088522Z","steps":["trace[2127507491] 'process raft request' (duration: 475.579312ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:26.088562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:01:25.612772Z","time spent":"475.775448ms","remote":"127.0.0.1:57678","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-31T05:01:26.088523Z","caller":"traceutil/trace.go:171","msg":"trace[556417640] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"481.000115ms","start":"2026-03-31T05:01:25.607473Z","end":"2026-03-31T05:01:26.088473Z","steps":["trace[556417640] 'process raft request' (duration: 480.857911ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:26.08869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:01:25.59474Z","time spent":"493.818844ms","remote":"127.0.0.1:57678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T05:01:26.088491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:01:25.577255Z","time spent":"511.179848ms","remote":"127.0.0.1:57678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T05:01:26.088736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:01:25.607454Z","time spent":"481.16085ms","remote":"127.0.0.1:57678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T05:01:26.088768Z","caller":"traceutil/trace.go:171","msg":"trace[1507644607] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"474.775946ms","start":"2026-03-31T05:01:25.613942Z","end":"2026-03-31T05:01:26.088718Z","steps":["trace[1507644607] 'process raft request' (duration: 474.463696ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:01:26.08888Z","caller":"traceutil/trace.go:171","msg":"trace[1153229100] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"471.824342ms","start":"2026-03-31T05:01:25.617041Z","end":"2026-03-31T05:01:26.088865Z","steps":["trace[1153229100] 'process raft request' (duration: 471.617455ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:26.088938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:01:25.617035Z","time spent":"471.881944ms","remote":"127.0.0.1:57678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T05:01:26.088956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:01:25.613936Z","time spent":"474.928812ms","remote":"127.0.0.1:57678","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-31T05:01:26.089146Z","caller":"traceutil/trace.go:171","msg":"trace[1035627793] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"471.75129ms","start":"2026-03-31T05:01:25.617373Z","end":"2026-03-31T05:01:26.089124Z","steps":["trace[1035627793] 'process raft request' (duration: 471.43353ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:01:26.089202Z","caller":"traceutil/trace.go:171","msg":"trace[631758850] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"466.419609ms","start":"2026-03-31T05:01:25.622773Z","end":"2026-03-31T05:01:26.089193Z","steps":["trace[631758850] 'process raft request' (duration: 466.313206ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:26.089218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:01:25.617367Z","time spent":"471.817422ms","remote":"127.0.0.1:57678","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-31T05:01:26.089246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:01:25.622767Z","time spent":"466.4612ms","remote":"127.0.0.1:57678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T05:01:26.089411Z","caller":"traceutil/trace.go:171","msg":"trace[413972780] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"456.660708ms","start":"2026-03-31T05:01:25.632736Z","end":"2026-03-31T05:01:26.089397Z","steps":["trace[413972780] 'process raft request' (duration: 456.392679ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:01:26.089455Z","caller":"traceutil/trace.go:171","msg":"trace[1990701927] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"441.153512ms","start":"2026-03-31T05:01:25.648294Z","end":"2026-03-31T05:01:26.089448Z","steps":["trace[1990701927] 'process raft request' (duration: 440.925925ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:26.089461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:01:25.632718Z","time spent":"456.7226ms","remote":"127.0.0.1:57678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T05:01:26.089495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:01:25.648269Z","time spent":"441.212284ms","remote":"127.0.0.1:57678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T05:01:26.089969Z","caller":"traceutil/trace.go:171","msg":"trace[503353856] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"345.714936ms","start":"2026-03-31T05:01:25.744232Z","end":"2026-03-31T05:01:26.089947Z","steps":["trace[503353856] 'process raft request' (duration: 345.121237ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:26.09065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:01:25.744214Z","time spent":"345.83825ms","remote":"127.0.0.1:57678","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-31T05:01:27.216652Z","caller":"traceutil/trace.go:171","msg":"trace[2061386031] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"183.479947ms","start":"2026-03-31T05:01:27.033147Z","end":"2026-03-31T05:01:27.216627Z","steps":["trace[2061386031] 'process raft request' (duration: 182.744604ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:01:27.220616Z","caller":"traceutil/trace.go:171","msg":"trace[1308456806] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"137.332985ms","start":"2026-03-31T05:01:27.083253Z","end":"2026-03-31T05:01:27.220586Z","steps":["trace[1308456806] 'process raft request' (duration: 137.146479ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:30.443161Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830825754256788,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-31T05:01:30.500829Z","caller":"traceutil/trace.go:171","msg":"trace[1763183396] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:572; }","duration":"558.619623ms","start":"2026-03-31T05:01:29.94211Z","end":"2026-03-31T05:01:30.500729Z","steps":["trace[1763183396] 'read index received' (duration: 554.14117ms)","trace[1763183396] 'applied index is now lower than readState.Index' (duration: 4.476503ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:01:30.501251Z","caller":"traceutil/trace.go:171","msg":"trace[1214103216] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"783.153391ms","start":"2026-03-31T05:01:29.718076Z","end":"2026-03-31T05:01:30.50123Z","steps":["trace[1214103216] 'process raft request' (duration: 779.415642ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:30.502787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:01:29.718032Z","time spent":"783.285376ms","remote":"127.0.0.1:57732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036101,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T05:01:30.502963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.889212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:01:30.503051Z","caller":"traceutil/trace.go:171","msg":"trace[256810626] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:552; }","duration":"351.038957ms","start":"2026-03-31T05:01:30.151987Z","end":"2026-03-31T05:01:30.503026Z","steps":["trace[256810626] 'agreement among raft nodes before linearized reading' (duration: 350.649154ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:30.5031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:01:30.151967Z","time spent":"351.12234ms","remote":"127.0.0.1:57602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T05:01:30.503296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.184576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.115\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-31T05:01:30.503369Z","caller":"traceutil/trace.go:171","msg":"trace[734457324] range","detail":"{range_begin:/registry/masterleases/199.204.45.115; range_end:; response_count:1; response_revision:552; }","duration":"561.272709ms","start":"2026-03-31T05:01:29.942062Z","end":"2026-03-31T05:01:30.503334Z","steps":["trace[734457324] 'agreement among raft nodes before linearized reading' (duration: 559.512202ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:30.503373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.201884ms","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-31T05:01:30.503411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:01:29.942044Z","time spent":"561.356811ms","remote":"127.0.0.1:57646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.115\" "} {"level":"info","ts":"2026-03-31T05:01:30.503434Z","caller":"traceutil/trace.go:171","msg":"trace[357115456] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:552; }","duration":"186.300948ms","start":"2026-03-31T05:01:30.317114Z","end":"2026-03-31T05:01:30.503415Z","steps":["trace[357115456] 'agreement among raft nodes before linearized reading' (duration: 186.173404ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:01:33.558198Z","caller":"traceutil/trace.go:171","msg":"trace[1699455975] linearizableReadLoop","detail":"{readStateIndex:597; appliedIndex:596; }","duration":"399.749442ms","start":"2026-03-31T05:01:33.15842Z","end":"2026-03-31T05:01:33.55817Z","steps":["trace[1699455975] 'read index received' (duration: 399.0569ms)","trace[1699455975] 'applied index is now lower than readState.Index' (duration: 691.712µs)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:01:33.558319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.891846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:01:33.558342Z","caller":"traceutil/trace.go:171","msg":"trace[486193055] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:575; }","duration":"399.933987ms","start":"2026-03-31T05:01:33.1584Z","end":"2026-03-31T05:01:33.558334Z","steps":["trace[486193055] 'agreement among raft nodes before linearized reading' (duration: 399.861275ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:33.558371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:01:33.158356Z","time spent":"400.00989ms","remote":"127.0.0.1:57604","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-31T05:01:33.558671Z","caller":"traceutil/trace.go:171","msg":"trace[250442505] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"533.058738ms","start":"2026-03-31T05:01:33.025537Z","end":"2026-03-31T05:01:33.558596Z","steps":["trace[250442505] 'process raft request' (duration: 532.123018ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:33.559012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:01:33.025509Z","time spent":"533.299746ms","remote":"127.0.0.1:57732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T05:01:34.191025Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830825754256837,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T05:01:34.691761Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830825754256837,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T05:01:35.192476Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830825754256837,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T05:01:35.446402Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.811773349s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-31T05:01:35.584027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:01:35.007295Z","time spent":"576.722951ms","remote":"127.0.0.1:58224","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-31T05:01:35.584346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.469044ms","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-31T05:01:35.584447Z","caller":"traceutil/trace.go:171","msg":"trace[1207883666] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:575; }","duration":"493.581067ms","start":"2026-03-31T05:01:35.09085Z","end":"2026-03-31T05:01:35.584431Z","steps":["trace[1207883666] 'range keys from in-memory index tree' (duration: 493.452373ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:35.585355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.250554ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-31T05:01:35.585925Z","caller":"traceutil/trace.go:171","msg":"trace[363122075] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"1.951359555s","start":"2026-03-31T05:01:33.634523Z","end":"2026-03-31T05:01:35.585882Z","steps":["trace[363122075] 'process raft request' (duration: 1.812271265s)","trace[363122075] 'compare' (duration: 137.633443ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:01:35.58609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:01:33.634497Z","time spent":"1.951501769s","remote":"127.0.0.1:58172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T05:01:35.601571Z","caller":"traceutil/trace.go:171","msg":"trace[106557353] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:597; }","duration":"1.911631116s","start":"2026-03-31T05:01:33.689908Z","end":"2026-03-31T05:01:35.601539Z","steps":["trace[106557353] 'read index received' (duration: 1.756903527s)","trace[106557353] 'applied index is now lower than readState.Index' (duration: 154.725709ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:01:35.601791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.447938484s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:01:35.601848Z","caller":"traceutil/trace.go:171","msg":"trace[1963721704] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:576; }","duration":"1.448077538s","start":"2026-03-31T05:01:34.153759Z","end":"2026-03-31T05:01:35.601837Z","steps":["trace[1963721704] 'agreement among raft nodes before linearized reading' (duration: 1.447970715s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:35.601865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.540515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:01:35.601928Z","caller":"traceutil/trace.go:171","msg":"trace[479321524] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:576; }","duration":"280.647699ms","start":"2026-03-31T05:01:35.321265Z","end":"2026-03-31T05:01:35.601913Z","steps":["trace[479321524] 'agreement among raft nodes before linearized reading' (duration: 280.504125ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:35.601973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.351682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:01:35.602004Z","caller":"traceutil/trace.go:171","msg":"trace[1713179624] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:576; }","duration":"281.386873ms","start":"2026-03-31T05:01:35.32061Z","end":"2026-03-31T05:01:35.601997Z","steps":["trace[1713179624] 'agreement among raft nodes before linearized reading' (duration: 281.320861ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:35.601995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.520002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T05:01:35.602079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.912168613s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8xj8f\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-03-31T05:01:35.602128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.693057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:01:35.602159Z","caller":"traceutil/trace.go:171","msg":"trace[1254488720] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:576; }","duration":"148.763469ms","start":"2026-03-31T05:01:35.453386Z","end":"2026-03-31T05:01:35.602149Z","steps":["trace[1254488720] 'agreement among raft nodes before linearized reading' (duration: 148.713447ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:01:35.602145Z","caller":"traceutil/trace.go:171","msg":"trace[392065095] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:576; }","duration":"148.690087ms","start":"2026-03-31T05:01:35.453433Z","end":"2026-03-31T05:01:35.602123Z","steps":["trace[392065095] 'agreement among raft nodes before linearized reading' (duration: 148.47526ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:01:35.602151Z","caller":"traceutil/trace.go:171","msg":"trace[1440839125] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8xj8f; range_end:; response_count:1; response_revision:576; }","duration":"1.912311199s","start":"2026-03-31T05:01:33.689825Z","end":"2026-03-31T05:01:35.602136Z","steps":["trace[1440839125] 'agreement among raft nodes before linearized reading' (duration: 1.911871444s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:35.602223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:01:33.689801Z","time spent":"1.912411721s","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-8xj8f\" "} {"level":"warn","ts":"2026-03-31T05:01:35.602261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.157611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:01:35.602292Z","caller":"traceutil/trace.go:171","msg":"trace[2118976682] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:576; }","duration":"180.187812ms","start":"2026-03-31T05:01:35.422097Z","end":"2026-03-31T05:01:35.602285Z","steps":["trace[2118976682] 'agreement among raft nodes before linearized reading' (duration: 180.147541ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:35.602297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.715334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:01:35.602348Z","caller":"traceutil/trace.go:171","msg":"trace[758430003] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:576; }","duration":"281.794066ms","start":"2026-03-31T05:01:35.320546Z","end":"2026-03-31T05:01:35.60234Z","steps":["trace[758430003] 'agreement among raft nodes before linearized reading' (duration: 281.697923ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:35.602504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.759444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T05:01:35.602529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.553004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:01:35.602559Z","caller":"traceutil/trace.go:171","msg":"trace[597639104] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:576; }","duration":"180.602596ms","start":"2026-03-31T05:01:35.421949Z","end":"2026-03-31T05:01:35.602551Z","steps":["trace[597639104] 'agreement among raft nodes before linearized reading' (duration: 180.553384ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:35.602609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.116025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:01:35.602642Z","caller":"traceutil/trace.go:171","msg":"trace[1998863812] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:576; }","duration":"422.191518ms","start":"2026-03-31T05:01:35.180442Z","end":"2026-03-31T05:01:35.602633Z","steps":["trace[1998863812] 'agreement among raft nodes before linearized reading' (duration: 422.148507ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:35.60265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.960997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:01:35.602675Z","caller":"traceutil/trace.go:171","msg":"trace[1625943206] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:576; }","duration":"181.016039ms","start":"2026-03-31T05:01:35.421652Z","end":"2026-03-31T05:01:35.602668Z","steps":["trace[1625943206] 'agreement among raft nodes before linearized reading' (duration: 180.979497ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:35.602715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:01:35.180425Z","time spent":"422.283411ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 "} {"level":"warn","ts":"2026-03-31T05:01:35.602757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.62679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:01:35.602778Z","caller":"traceutil/trace.go:171","msg":"trace[1852139968] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:576; }","duration":"203.672532ms","start":"2026-03-31T05:01:35.399099Z","end":"2026-03-31T05:01:35.602772Z","steps":["trace[1852139968] 'agreement among raft nodes before linearized reading' (duration: 203.637281ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:35.602775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.56193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:01:35.602817Z","caller":"traceutil/trace.go:171","msg":"trace[1321234452] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:576; }","duration":"105.634622ms","start":"2026-03-31T05:01:35.497171Z","end":"2026-03-31T05:01:35.602805Z","steps":["trace[1321234452] 'agreement among raft nodes before linearized reading' (duration: 105.57272ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:35.602889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.995722ms","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":"info","ts":"2026-03-31T05:01:35.602912Z","caller":"traceutil/trace.go:171","msg":"trace[1413088830] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:576; }","duration":"204.036844ms","start":"2026-03-31T05:01:35.398868Z","end":"2026-03-31T05:01:35.602905Z","steps":["trace[1413088830] 'agreement among raft nodes before linearized reading' (duration: 204.005563ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:35.602894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.689109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:01:35.60295Z","caller":"traceutil/trace.go:171","msg":"trace[1320255462] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:576; }","duration":"601.764921ms","start":"2026-03-31T05:01:35.001178Z","end":"2026-03-31T05:01:35.602943Z","steps":["trace[1320255462] 'agreement among raft nodes before linearized reading' (duration: 601.694339ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:35.602983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:01:35.001165Z","time spent":"601.811072ms","remote":"127.0.0.1:58214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 "} {"level":"info","ts":"2026-03-31T05:01:35.602543Z","caller":"traceutil/trace.go:171","msg":"trace[1515306069] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:576; }","duration":"421.849257ms","start":"2026-03-31T05:01:35.180688Z","end":"2026-03-31T05:01:35.602537Z","steps":["trace[1515306069] 'agreement among raft nodes before linearized reading' (duration: 421.759444ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:35.603038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:01:35.18065Z","time spent":"422.380304ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-03-31T05:01:35.60306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.520763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T05:01:35.603064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.889046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:01:35.603103Z","caller":"traceutil/trace.go:171","msg":"trace[1297570503] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:576; }","duration":"107.583275ms","start":"2026-03-31T05:01:35.495508Z","end":"2026-03-31T05:01:35.603092Z","steps":["trace[1297570503] 'agreement among raft nodes before linearized reading' (duration: 107.516513ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:01:35.603113Z","caller":"traceutil/trace.go:171","msg":"trace[405114605] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:576; }","duration":"253.951617ms","start":"2026-03-31T05:01:35.349145Z","end":"2026-03-31T05:01:35.603097Z","steps":["trace[405114605] 'agreement among raft nodes before linearized reading' (duration: 253.877945ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:35.603004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.047148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T05:01:35.603178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.746373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:01:35.603228Z","caller":"traceutil/trace.go:171","msg":"trace[2146057416] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:576; }","duration":"602.830625ms","start":"2026-03-31T05:01:35.00039Z","end":"2026-03-31T05:01:35.603221Z","steps":["trace[2146057416] 'agreement among raft nodes before linearized reading' (duration: 602.766453ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:35.603261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:01:35.000363Z","time spent":"602.892287ms","remote":"127.0.0.1:58214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-03-31T05:01:35.603298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.389871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T05:01:35.603308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.136665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:01:35.603335Z","caller":"traceutil/trace.go:171","msg":"trace[1397804366] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:576; }","duration":"124.424633ms","start":"2026-03-31T05:01:35.4789Z","end":"2026-03-31T05:01:35.603325Z","steps":["trace[1397804366] 'agreement among raft nodes before linearized reading' (duration: 124.378351ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:01:35.603352Z","caller":"traceutil/trace.go:171","msg":"trace[1209865062] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:576; }","duration":"255.180947ms","start":"2026-03-31T05:01:35.348149Z","end":"2026-03-31T05:01:35.60333Z","steps":["trace[1209865062] 'agreement among raft nodes before linearized reading' (duration: 255.133905ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:35.603406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.104114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:01:35.603444Z","caller":"traceutil/trace.go:171","msg":"trace[633363408] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:576; }","duration":"603.181606ms","start":"2026-03-31T05:01:35.000255Z","end":"2026-03-31T05:01:35.603437Z","steps":["trace[633363408] 'agreement among raft nodes before linearized reading' (duration: 603.123824ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:35.603475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:01:35.000233Z","time spent":"603.236348ms","remote":"127.0.0.1:58214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 "} {"level":"warn","ts":"2026-03-31T05:01:35.603487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.579877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:01:35.603517Z","caller":"traceutil/trace.go:171","msg":"trace[1625315781] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:576; }","duration":"124.626518ms","start":"2026-03-31T05:01:35.478882Z","end":"2026-03-31T05:01:35.603509Z","steps":["trace[1625315781] 'agreement among raft nodes before linearized reading' (duration: 124.582667ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:35.603604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.015029ms","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-31T05:01:35.603645Z","caller":"traceutil/trace.go:171","msg":"trace[1506875035] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:576; }","duration":"732.091471ms","start":"2026-03-31T05:01:34.871544Z","end":"2026-03-31T05:01:35.603636Z","steps":["trace[1506875035] 'agreement among raft nodes before linearized reading' (duration: 732.03911ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:35.603677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:01:34.871524Z","time spent":"732.146754ms","remote":"127.0.0.1:58200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 "} {"level":"warn","ts":"2026-03-31T05:01:35.602988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.23349ms","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":"warn","ts":"2026-03-31T05:01:35.603728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.03370777s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-31T05:01:35.603745Z","caller":"traceutil/trace.go:171","msg":"trace[467723833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:576; }","duration":"1.033740761s","start":"2026-03-31T05:01:34.569999Z","end":"2026-03-31T05:01:35.60374Z","steps":["trace[467723833] 'agreement among raft nodes before linearized reading' (duration: 1.033635977s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:35.603767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:01:34.569989Z","time spent":"1.033774873s","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-31T05:01:35.603759Z","caller":"traceutil/trace.go:171","msg":"trace[1422186610] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:576; }","duration":"204.997214ms","start":"2026-03-31T05:01:35.398748Z","end":"2026-03-31T05:01:35.603745Z","steps":["trace[1422186610] 'agreement among raft nodes before linearized reading' (duration: 204.217149ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:35.603784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.96747ms","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":"warn","ts":"2026-03-31T05:01:35.60383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.708562ms","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-31T05:01:35.603867Z","caller":"traceutil/trace.go:171","msg":"trace[1999285885] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:576; }","duration":"764.785405ms","start":"2026-03-31T05:01:34.839073Z","end":"2026-03-31T05:01:35.603858Z","steps":["trace[1999285885] 'agreement among raft nodes before linearized reading' (duration: 764.734113ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:35.603877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.032692ms","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":"warn","ts":"2026-03-31T05:01:35.603901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.364433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:01:35.603905Z","caller":"traceutil/trace.go:171","msg":"trace[321057868] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:576; }","duration":"733.076923ms","start":"2026-03-31T05:01:34.870819Z","end":"2026-03-31T05:01:35.603896Z","steps":["trace[321057868] 'agreement among raft nodes before linearized reading' (duration: 733.029671ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:35.603907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:01:34.839048Z","time spent":"764.848147ms","remote":"127.0.0.1:58194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 "} {"level":"info","ts":"2026-03-31T05:01:35.603923Z","caller":"traceutil/trace.go:171","msg":"trace[1718379284] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:576; }","duration":"801.455275ms","start":"2026-03-31T05:01:34.802462Z","end":"2026-03-31T05:01:35.603917Z","steps":["trace[1718379284] 'agreement among raft nodes before linearized reading' (duration: 801.425184ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:35.603938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:01:34.870811Z","time spent":"733.115264ms","remote":"127.0.0.1:58200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-03-31T05:01:35.603971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.109014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T05:01:35.603946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:01:34.802436Z","time spent":"801.504977ms","remote":"127.0.0.1:58192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 "} {"level":"warn","ts":"2026-03-31T05:01:35.603788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.145255ms","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-31T05:01:35.604067Z","caller":"traceutil/trace.go:171","msg":"trace[1070273091] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:576; }","duration":"764.442294ms","start":"2026-03-31T05:01:34.839613Z","end":"2026-03-31T05:01:35.604055Z","steps":["trace[1070273091] 'agreement among raft nodes before linearized reading' (duration: 764.148954ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:35.604104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.746959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-31T05:01:35.603615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.914936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T05:01:35.604123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:01:34.839599Z","time spent":"764.498256ms","remote":"127.0.0.1:58194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-03-31T05:01:35.60415Z","caller":"traceutil/trace.go:171","msg":"trace[1089789450] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:576; }","duration":"150.475243ms","start":"2026-03-31T05:01:35.453662Z","end":"2026-03-31T05:01:35.604137Z","steps":["trace[1089789450] 'agreement among raft nodes before linearized reading' (duration: 149.928796ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:35.603257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.295606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:01:35.604254Z","caller":"traceutil/trace.go:171","msg":"trace[733916971] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:576; }","duration":"124.329229ms","start":"2026-03-31T05:01:35.479914Z","end":"2026-03-31T05:01:35.604243Z","steps":["trace[733916971] 'agreement among raft nodes before linearized reading' (duration: 123.318337ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:35.603734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.631539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:01:35.604309Z","caller":"traceutil/trace.go:171","msg":"trace[821649236] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:576; }","duration":"801.232548ms","start":"2026-03-31T05:01:34.803062Z","end":"2026-03-31T05:01:35.604294Z","steps":["trace[821649236] 'agreement among raft nodes before linearized reading' (duration: 800.637939ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:35.604355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:01:34.803053Z","time spent":"801.28886ms","remote":"127.0.0.1:58190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 "} {"level":"info","ts":"2026-03-31T05:01:35.603202Z","caller":"traceutil/trace.go:171","msg":"trace[638011443] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:576; }","duration":"107.262114ms","start":"2026-03-31T05:01:35.495932Z","end":"2026-03-31T05:01:35.603194Z","steps":["trace[638011443] 'agreement among raft nodes before linearized reading' (duration: 107.043717ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:35.603258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.900118ms","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-31T05:01:35.603829Z","caller":"traceutil/trace.go:171","msg":"trace[648598137] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:576; }","duration":"733.052822ms","start":"2026-03-31T05:01:34.870766Z","end":"2026-03-31T05:01:35.603818Z","steps":["trace[648598137] 'agreement among raft nodes before linearized reading' (duration: 732.98087ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:35.604552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:01:34.870743Z","time spent":"733.793466ms","remote":"127.0.0.1:58200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 "} {"level":"warn","ts":"2026-03-31T05:01:35.60459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.067408326s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-31T05:01:35.604614Z","caller":"traceutil/trace.go:171","msg":"trace[1416906509] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:576; }","duration":"1.067450498s","start":"2026-03-31T05:01:34.537158Z","end":"2026-03-31T05:01:35.604608Z","steps":["trace[1416906509] 'agreement among raft nodes before linearized reading' (duration: 1.067397566s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:35.60467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:01:34.537146Z","time spent":"1.067518799s","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-31T05:01:35.604034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.28701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:01:35.604747Z","caller":"traceutil/trace.go:171","msg":"trace[394939413] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:576; }","duration":"833.007643ms","start":"2026-03-31T05:01:34.771715Z","end":"2026-03-31T05:01:35.604723Z","steps":["trace[394939413] 'agreement among raft nodes before linearized reading' (duration: 832.29124ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:35.604794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:01:34.771697Z","time spent":"833.083875ms","remote":"127.0.0.1:58190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-03-31T05:01:35.604814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.069509453s","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-31T05:01:35.604831Z","caller":"traceutil/trace.go:171","msg":"trace[834759484] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:576; }","duration":"1.069565784s","start":"2026-03-31T05:01:34.53526Z","end":"2026-03-31T05:01:35.604826Z","steps":["trace[834759484] 'agreement among raft nodes before linearized reading' (duration: 1.069493822s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:35.60485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:01:34.535241Z","time spent":"1.069605416s","remote":"127.0.0.1:57912","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-31T05:01:35.604124Z","caller":"traceutil/trace.go:171","msg":"trace[16260111] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:576; }","duration":"954.76516ms","start":"2026-03-31T05:01:34.649353Z","end":"2026-03-31T05:01:35.604118Z","steps":["trace[16260111] 'agreement among raft nodes before linearized reading' (duration: 954.695498ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:35.603874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.320152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:01:35.605007Z","caller":"traceutil/trace.go:171","msg":"trace[137382121] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:576; }","duration":"802.465098ms","start":"2026-03-31T05:01:34.802522Z","end":"2026-03-31T05:01:35.604987Z","steps":["trace[137382121] 'agreement among raft nodes before linearized reading' (duration: 801.329771ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:35.60504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:01:34.802508Z","time spent":"802.51764ms","remote":"127.0.0.1:58192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-03-31T05:01:35.603995Z","caller":"traceutil/trace.go:171","msg":"trace[956971242] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:576; }","duration":"832.179347ms","start":"2026-03-31T05:01:34.771811Z","end":"2026-03-31T05:01:35.60399Z","steps":["trace[956971242] 'agreement among raft nodes before linearized reading' (duration: 832.145846ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:35.601887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:01:34.153692Z","time spent":"1.448187992s","remote":"127.0.0.1:57602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T05:01:35.605187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:01:34.771783Z","time spent":"833.397656ms","remote":"127.0.0.1:58190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 "} {"level":"warn","ts":"2026-03-31T05:01:35.605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:01:34.649303Z","time spent":"955.68405ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-31T05:01:35.60451Z","caller":"traceutil/trace.go:171","msg":"trace[1202944340] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:576; }","duration":"256.159248ms","start":"2026-03-31T05:01:35.348341Z","end":"2026-03-31T05:01:35.6045Z","steps":["trace[1202944340] 'agreement among raft nodes before linearized reading' (duration: 254.877657ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:01:51.879698Z","caller":"traceutil/trace.go:171","msg":"trace[1979032173] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"131.278661ms","start":"2026-03-31T05:01:51.7484Z","end":"2026-03-31T05:01:51.879679Z","steps":["trace[1979032173] 'process raft request' (duration: 130.993642ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:01:51.879893Z","caller":"traceutil/trace.go:171","msg":"trace[1137349479] linearizableReadLoop","detail":"{readStateIndex:764; appliedIndex:763; }","duration":"107.497032ms","start":"2026-03-31T05:01:51.772145Z","end":"2026-03-31T05:01:51.879642Z","steps":["trace[1137349479] 'read index received' (duration: 107.421339ms)","trace[1137349479] 'applied index is now lower than readState.Index' (duration: 74.713µs)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:01:51.880007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.829142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-31T05:01:51.880113Z","caller":"traceutil/trace.go:171","msg":"trace[1915418562] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:737; }","duration":"107.962596ms","start":"2026-03-31T05:01:51.772109Z","end":"2026-03-31T05:01:51.880072Z","steps":["trace[1915418562] 'agreement among raft nodes before linearized reading' (duration: 107.683188ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:52.380533Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830825754257180,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-31T05:01:52.666996Z","caller":"traceutil/trace.go:171","msg":"trace[1620804474] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"853.822088ms","start":"2026-03-31T05:01:51.813158Z","end":"2026-03-31T05:01:52.66698Z","steps":["trace[1620804474] 'process raft request' (duration: 834.105158ms)","trace[1620804474] 'compare' (duration: 19.156472ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:01:52.667127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:01:51.813129Z","time spent":"853.919151ms","remote":"127.0.0.1:57868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T05:01:52.701851Z","caller":"traceutil/trace.go:171","msg":"trace[783926781] linearizableReadLoop","detail":"{readStateIndex:766; appliedIndex:764; }","duration":"821.907018ms","start":"2026-03-31T05:01:51.879918Z","end":"2026-03-31T05:01:52.701825Z","steps":["trace[783926781] 'read index received' (duration: 786.702164ms)","trace[783926781] 'applied index is now lower than readState.Index' (duration: 35.203914ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:01:52.701969Z","caller":"traceutil/trace.go:171","msg":"trace[646898522] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"133.882915ms","start":"2026-03-31T05:01:52.568047Z","end":"2026-03-31T05:01:52.70193Z","steps":["trace[646898522] 'process raft request' (duration: 133.73464ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:52.702142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"884.763086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-31T05:01:52.702156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"884.691373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-31T05:01:52.702199Z","caller":"traceutil/trace.go:171","msg":"trace[1535836063] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:740; }","duration":"884.863009ms","start":"2026-03-31T05:01:51.817319Z","end":"2026-03-31T05:01:52.702182Z","steps":["trace[1535836063] 'agreement among raft nodes before linearized reading' (duration: 884.610991ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:01:52.702218Z","caller":"traceutil/trace.go:171","msg":"trace[2059523193] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:740; }","duration":"884.773226ms","start":"2026-03-31T05:01:51.817428Z","end":"2026-03-31T05:01:52.702201Z","steps":["trace[2059523193] 'agreement among raft nodes before linearized reading' (duration: 884.58013ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:52.702251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:01:51.817299Z","time spent":"884.934591ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-31T05:01:52.702262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:01:51.817421Z","time spent":"884.828958ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-31T05:01:52.7023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"842.560169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-mpc7l\" ","response":"range_response_count:1 size:5047"} {"level":"warn","ts":"2026-03-31T05:01:52.702341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"884.90394ms","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-31T05:01:52.702386Z","caller":"traceutil/trace.go:171","msg":"trace[1414690218] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:740; }","duration":"884.979603ms","start":"2026-03-31T05:01:51.817391Z","end":"2026-03-31T05:01:52.702371Z","steps":["trace[1414690218] 'agreement among raft nodes before linearized reading' (duration: 884.806867ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:01:52.702385Z","caller":"traceutil/trace.go:171","msg":"trace[1680983855] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-mpc7l; range_end:; response_count:1; response_revision:740; }","duration":"842.672792ms","start":"2026-03-31T05:01:51.859682Z","end":"2026-03-31T05:01:52.702355Z","steps":["trace[1680983855] 'agreement among raft nodes before linearized reading' (duration: 842.380803ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:52.702426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:01:51.817372Z","time spent":"885.046064ms","remote":"127.0.0.1:57912","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-31T05:01:52.702435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:01:51.859662Z","time spent":"842.763285ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":5070,"request content":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-mpc7l\" "} {"level":"info","ts":"2026-03-31T05:01:52.702868Z","caller":"traceutil/trace.go:171","msg":"trace[1734736189] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"817.939652ms","start":"2026-03-31T05:01:51.884914Z","end":"2026-03-31T05:01:52.702854Z","steps":["trace[1734736189] 'process raft request' (duration: 816.200107ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:01:52.703004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:01:51.884888Z","time spent":"818.034535ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T05:02:21.674193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.781938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:02:21.674269Z","caller":"traceutil/trace.go:171","msg":"trace[1461277189] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:941; }","duration":"520.900622ms","start":"2026-03-31T05:02:21.153355Z","end":"2026-03-31T05:02:21.674255Z","steps":["trace[1461277189] 'range keys from in-memory index tree' (duration: 520.699326ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:02:21.674302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:02:21.153335Z","time spent":"520.958873ms","remote":"127.0.0.1:57602","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-31T05:02:27.11198Z","caller":"traceutil/trace.go:171","msg":"trace[1577841689] linearizableReadLoop","detail":"{readStateIndex:996; appliedIndex:995; }","duration":"214.59321ms","start":"2026-03-31T05:02:26.897353Z","end":"2026-03-31T05:02:27.111947Z","steps":["trace[1577841689] 'read index received' (duration: 214.377483ms)","trace[1577841689] 'applied index is now lower than readState.Index' (duration: 214.377µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:02:27.112128Z","caller":"traceutil/trace.go:171","msg":"trace[1935610422] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"216.235492ms","start":"2026-03-31T05:02:26.895877Z","end":"2026-03-31T05:02:27.112112Z","steps":["trace[1935610422] 'process raft request' (duration: 215.87102ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:02:27.112371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.998792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-31T05:02:27.112447Z","caller":"traceutil/trace.go:171","msg":"trace[1776021788] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:960; }","duration":"215.113277ms","start":"2026-03-31T05:02:26.897314Z","end":"2026-03-31T05:02:27.112428Z","steps":["trace[1776021788] 'agreement among raft nodes before linearized reading' (duration: 214.90367ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:02:27.120331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.087943ms","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-31T05:02:27.120362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.306845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-31T05:02:27.120456Z","caller":"traceutil/trace.go:171","msg":"trace[1452919241] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:961; }","duration":"221.438239ms","start":"2026-03-31T05:02:26.898998Z","end":"2026-03-31T05:02:27.120436Z","steps":["trace[1452919241] 'agreement among raft nodes before linearized reading' (duration: 221.16126ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:02:27.120373Z","caller":"traceutil/trace.go:171","msg":"trace[1949727203] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:961; }","duration":"219.154806ms","start":"2026-03-31T05:02:26.901207Z","end":"2026-03-31T05:02:27.120362Z","steps":["trace[1949727203] 'agreement among raft nodes before linearized reading' (duration: 219.023941ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:02:27.120719Z","caller":"traceutil/trace.go:171","msg":"trace[1103975249] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"144.031704ms","start":"2026-03-31T05:02:26.976656Z","end":"2026-03-31T05:02:27.120688Z","steps":["trace[1103975249] 'process raft request' (duration: 143.374703ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:02:29.147251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"993.913852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:02:29.147317Z","caller":"traceutil/trace.go:171","msg":"trace[1494885779] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:964; }","duration":"994.042536ms","start":"2026-03-31T05:02:28.15326Z","end":"2026-03-31T05:02:29.147302Z","steps":["trace[1494885779] 'range keys from in-memory index tree' (duration: 993.826409ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:02:29.147349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:02:28.153216Z","time spent":"994.126669ms","remote":"127.0.0.1:57602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T05:02:29.147589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"994.908024ms","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-31T05:02:29.147646Z","caller":"traceutil/trace.go:171","msg":"trace[1402188441] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:964; }","duration":"995.003607ms","start":"2026-03-31T05:02:28.152626Z","end":"2026-03-31T05:02:29.14763Z","steps":["trace[1402188441] 'range keys from in-memory index tree' (duration: 994.77513ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:02:29.147673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:02:28.152606Z","time spent":"995.060589ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-31T05:02:29.575469Z","caller":"traceutil/trace.go:171","msg":"trace[1078522188] linearizableReadLoop","detail":"{readStateIndex:1001; appliedIndex:1000; }","duration":"426.43571ms","start":"2026-03-31T05:02:29.149002Z","end":"2026-03-31T05:02:29.575438Z","steps":["trace[1078522188] 'read index received' (duration: 426.354968ms)","trace[1078522188] 'applied index is now lower than readState.Index' (duration: 78.962µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:02:29.575612Z","caller":"traceutil/trace.go:171","msg":"trace[714829000] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"450.246572ms","start":"2026-03-31T05:02:29.125334Z","end":"2026-03-31T05:02:29.57558Z","steps":["trace[714829000] 'process raft request' (duration: 449.924822ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:02:29.575648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.621636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:02:29.575699Z","caller":"traceutil/trace.go:171","msg":"trace[1965662412] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:965; }","duration":"426.689188ms","start":"2026-03-31T05:02:29.148997Z","end":"2026-03-31T05:02:29.575686Z","steps":["trace[1965662412] 'agreement among raft nodes before linearized reading' (duration: 426.588675ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:02:29.575739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:02:29.148954Z","time spent":"426.777732ms","remote":"127.0.0.1:57604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T05:02:29.575743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:02:29.125315Z","time spent":"450.356656ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T05:02:29.582357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.499001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-31T05:02:29.582424Z","caller":"traceutil/trace.go:171","msg":"trace[508793711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:967; }","duration":"420.592024ms","start":"2026-03-31T05:02:29.16182Z","end":"2026-03-31T05:02:29.582412Z","steps":["trace[508793711] 'agreement among raft nodes before linearized reading' (duration: 420.400968ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:02:29.582458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:02:29.16181Z","time spent":"420.640146ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-31T05:02:29.582613Z","caller":"traceutil/trace.go:171","msg":"trace[1504226589] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"430.966275ms","start":"2026-03-31T05:02:29.151634Z","end":"2026-03-31T05:02:29.5826Z","steps":["trace[1504226589] 'process raft request' (duration: 430.371616ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:02:29.58237Z","caller":"traceutil/trace.go:171","msg":"trace[1312501794] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"430.252583ms","start":"2026-03-31T05:02:29.152095Z","end":"2026-03-31T05:02:29.582347Z","steps":["trace[1312501794] 'process raft request' (duration: 430.081868ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:02:29.582701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:02:29.151618Z","time spent":"431.036688ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T05:02:29.582729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.353986ms","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-31T05:02:29.582762Z","caller":"traceutil/trace.go:171","msg":"trace[1835890940] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:967; }","duration":"420.433199ms","start":"2026-03-31T05:02:29.162319Z","end":"2026-03-31T05:02:29.582752Z","steps":["trace[1835890940] 'agreement among raft nodes before linearized reading' (duration: 420.340096ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:02:29.582796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:02:29.162302Z","time spent":"420.487641ms","remote":"127.0.0.1:57912","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-31T05:02:29.5828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:02:29.152077Z","time spent":"430.629855ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T05:02:29.582925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.738519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-31T05:02:29.582956Z","caller":"traceutil/trace.go:171","msg":"trace[922530225] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:967; }","duration":"420.796101ms","start":"2026-03-31T05:02:29.162153Z","end":"2026-03-31T05:02:29.582949Z","steps":["trace[922530225] 'agreement among raft nodes before linearized reading' (duration: 420.715528ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:02:29.582981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:02:29.16214Z","time spent":"420.835402ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-31T05:02:33.771875Z","caller":"traceutil/trace.go:171","msg":"trace[1405887051] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"107.504347ms","start":"2026-03-31T05:02:33.664348Z","end":"2026-03-31T05:02:33.771852Z","steps":["trace[1405887051] 'process raft request' (duration: 107.410164ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:02:33.77188Z","caller":"traceutil/trace.go:171","msg":"trace[11618853] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"107.656812ms","start":"2026-03-31T05:02:33.664193Z","end":"2026-03-31T05:02:33.77185Z","steps":["trace[11618853] 'process raft request' (duration: 107.259799ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:02:33.772135Z","caller":"traceutil/trace.go:171","msg":"trace[1972516359] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"107.714313ms","start":"2026-03-31T05:02:33.664398Z","end":"2026-03-31T05:02:33.772113Z","steps":["trace[1972516359] 'process raft request' (duration: 107.427264ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:02:33.772232Z","caller":"traceutil/trace.go:171","msg":"trace[1392001111] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"107.784386ms","start":"2026-03-31T05:02:33.664435Z","end":"2026-03-31T05:02:33.772219Z","steps":["trace[1392001111] 'process raft request' (duration: 107.586829ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:02:35.892228Z","caller":"traceutil/trace.go:171","msg":"trace[1508601166] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"112.345361ms","start":"2026-03-31T05:02:35.779857Z","end":"2026-03-31T05:02:35.892203Z","steps":["trace[1508601166] 'process raft request' (duration: 94.569613ms)","trace[1508601166] 'compare' (duration: 17.652944ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:02:35.892343Z","caller":"traceutil/trace.go:171","msg":"trace[1620418918] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"112.372092ms","start":"2026-03-31T05:02:35.779963Z","end":"2026-03-31T05:02:35.892335Z","steps":["trace[1620418918] 'process raft request' (duration: 112.218968ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:02:35.892518Z","caller":"traceutil/trace.go:171","msg":"trace[1838996638] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"111.566707ms","start":"2026-03-31T05:02:35.780926Z","end":"2026-03-31T05:02:35.892492Z","steps":["trace[1838996638] 'process raft request' (duration: 111.37602ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:02:41.888635Z","caller":"traceutil/trace.go:171","msg":"trace[2092993788] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"150.527072ms","start":"2026-03-31T05:02:41.738068Z","end":"2026-03-31T05:02:41.888595Z","steps":["trace[2092993788] 'process raft request' (duration: 59.646277ms)","trace[2092993788] 'compare' (duration: 90.630387ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:02:42.135061Z","caller":"traceutil/trace.go:171","msg":"trace[1145407773] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"209.484577ms","start":"2026-03-31T05:02:41.925553Z","end":"2026-03-31T05:02:42.135037Z","steps":["trace[1145407773] 'process raft request' (duration: 158.123685ms)","trace[1145407773] 'compare' (duration: 51.248978ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:02:42.180654Z","caller":"traceutil/trace.go:171","msg":"trace[528400559] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"254.803575ms","start":"2026-03-31T05:02:41.925825Z","end":"2026-03-31T05:02:42.180629Z","steps":["trace[528400559] 'process raft request' (duration: 254.614199ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:02:42.180896Z","caller":"traceutil/trace.go:171","msg":"trace[1629879802] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"255.023562ms","start":"2026-03-31T05:02:41.925853Z","end":"2026-03-31T05:02:42.180877Z","steps":["trace[1629879802] 'process raft request' (duration: 254.728593ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:02:44.319114Z","caller":"traceutil/trace.go:171","msg":"trace[1801942367] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"130.174382ms","start":"2026-03-31T05:02:44.188915Z","end":"2026-03-31T05:02:44.319089Z","steps":["trace[1801942367] 'process raft request' (duration: 67.591401ms)","trace[1801942367] 'compare' (duration: 62.488698ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:02:44.334436Z","caller":"traceutil/trace.go:171","msg":"trace[1387371619] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"144.319124ms","start":"2026-03-31T05:02:44.190097Z","end":"2026-03-31T05:02:44.334416Z","steps":["trace[1387371619] 'process raft request' (duration: 144.232101ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:02:52.437596Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830825754257843,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-31T05:02:52.643629Z","caller":"traceutil/trace.go:171","msg":"trace[640299416] linearizableReadLoop","detail":"{readStateIndex:1080; appliedIndex:1079; }","duration":"706.795663ms","start":"2026-03-31T05:02:51.936808Z","end":"2026-03-31T05:02:52.643603Z","steps":["trace[640299416] 'read index received' (duration: 706.649879ms)","trace[640299416] 'applied index is now lower than readState.Index' (duration: 144.684µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:02:52.643742Z","caller":"traceutil/trace.go:171","msg":"trace[529326045] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"876.948063ms","start":"2026-03-31T05:02:51.766759Z","end":"2026-03-31T05:02:52.643707Z","steps":["trace[529326045] 'process raft request' (duration: 876.694765ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:02:52.643896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:02:51.76673Z","time spent":"877.083107ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T05:02:52.643948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.129955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-31T05:02:52.643963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.778266ms","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-31T05:02:52.644027Z","caller":"traceutil/trace.go:171","msg":"trace[1617106013] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1039; }","duration":"707.254018ms","start":"2026-03-31T05:02:51.936753Z","end":"2026-03-31T05:02:52.644007Z","steps":["trace[1617106013] 'agreement among raft nodes before linearized reading' (duration: 707.013101ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:02:52.644046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.77348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-03-31T05:02:52.644031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.363988ms","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-31T05:02:52.644126Z","caller":"traceutil/trace.go:171","msg":"trace[1843202117] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1039; }","duration":"277.896844ms","start":"2026-03-31T05:02:52.366212Z","end":"2026-03-31T05:02:52.644109Z","steps":["trace[1843202117] 'agreement among raft nodes before linearized reading' (duration: 277.654756ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:02:52.64404Z","caller":"traceutil/trace.go:171","msg":"trace[1994095461] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1039; }","duration":"428.914091ms","start":"2026-03-31T05:02:52.21511Z","end":"2026-03-31T05:02:52.644024Z","steps":["trace[1994095461] 'agreement among raft nodes before linearized reading' (duration: 428.715374ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:02:52.644194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:02:52.21509Z","time spent":"429.091526ms","remote":"127.0.0.1:57912","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-31T05:02:52.644235Z","caller":"traceutil/trace.go:171","msg":"trace[749205896] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1039; }","duration":"659.552764ms","start":"2026-03-31T05:02:51.984569Z","end":"2026-03-31T05:02:52.644122Z","steps":["trace[749205896] 'agreement among raft nodes before linearized reading' (duration: 659.203443ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:02:52.644082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:02:51.936725Z","time spent":"707.342831ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-31T05:02:52.643958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.20549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T05:02:52.644301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:02:51.984553Z","time spent":"659.730549ms","remote":"127.0.0.1:57912","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-31T05:02:52.644322Z","caller":"traceutil/trace.go:171","msg":"trace[897665852] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1039; }","duration":"490.611943ms","start":"2026-03-31T05:02:52.153701Z","end":"2026-03-31T05:02:52.644313Z","steps":["trace[897665852] 'agreement among raft nodes before linearized reading' (duration: 490.20144ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:02:52.644348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:02:52.153683Z","time spent":"490.658144ms","remote":"127.0.0.1:57602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T05:02:52.644527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.266705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-31T05:02:52.644584Z","caller":"traceutil/trace.go:171","msg":"trace[1294746180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1039; }","duration":"293.324536ms","start":"2026-03-31T05:02:52.351249Z","end":"2026-03-31T05:02:52.644573Z","steps":["trace[1294746180] 'agreement among raft nodes before linearized reading' (duration: 292.552392ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:02:53.336403Z","caller":"traceutil/trace.go:171","msg":"trace[382595635] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"687.309821ms","start":"2026-03-31T05:02:52.649071Z","end":"2026-03-31T05:02:53.336381Z","steps":["trace[382595635] 'process raft request' (duration: 687.238199ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:02:53.336603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:02:52.649035Z","time spent":"687.495497ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T05:02:53.336632Z","caller":"traceutil/trace.go:171","msg":"trace[1520905125] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"687.532578ms","start":"2026-03-31T05:02:52.649092Z","end":"2026-03-31T05:02:53.336624Z","steps":["trace[1520905125] 'process raft request' (duration: 687.258319ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:02:53.336413Z","caller":"traceutil/trace.go:171","msg":"trace[372230146] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"687.577119ms","start":"2026-03-31T05:02:52.648805Z","end":"2026-03-31T05:02:53.336383Z","steps":["trace[372230146] 'process raft request' (duration: 687.317101ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:02:53.336747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:02:52.649027Z","time spent":"687.639982ms","remote":"127.0.0.1:57912","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-31T05:02:53.33675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:02:52.648797Z","time spent":"687.926601ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T05:02:53.336993Z","caller":"traceutil/trace.go:171","msg":"trace[1977212567] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"687.170357ms","start":"2026-03-31T05:02:52.649781Z","end":"2026-03-31T05:02:53.336952Z","steps":["trace[1977212567] 'process raft request' (duration: 686.764244ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:02:53.337205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:02:52.649768Z","time spent":"687.335723ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T05:02:55.283373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.285417ms","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-31T05:02:55.283545Z","caller":"traceutil/trace.go:171","msg":"trace[945247073] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1046; }","duration":"192.470213ms","start":"2026-03-31T05:02:55.091056Z","end":"2026-03-31T05:02:55.283527Z","steps":["trace[945247073] 'range keys from in-memory index tree' (duration: 192.268336ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:02:55.283604Z","caller":"traceutil/trace.go:171","msg":"trace[496836497] linearizableReadLoop","detail":"{readStateIndex:1088; appliedIndex:1087; }","duration":"121.08217ms","start":"2026-03-31T05:02:55.162494Z","end":"2026-03-31T05:02:55.283577Z","steps":["trace[496836497] 'read index received' (duration: 72.567629ms)","trace[496836497] 'applied index is now lower than readState.Index' (duration: 48.513101ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:02:55.283791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.301847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-31T05:02:55.283829Z","caller":"traceutil/trace.go:171","msg":"trace[2012878453] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1047; }","duration":"121.399241ms","start":"2026-03-31T05:02:55.162421Z","end":"2026-03-31T05:02:55.28382Z","steps":["trace[2012878453] 'agreement among raft nodes before linearized reading' (duration: 121.248916ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:02:55.284008Z","caller":"traceutil/trace.go:171","msg":"trace[177095604] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"569.250428ms","start":"2026-03-31T05:02:54.714724Z","end":"2026-03-31T05:02:55.283974Z","steps":["trace[177095604] 'process raft request' (duration: 520.365895ms)","trace[177095604] 'compare' (duration: 47.664584ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:02:55.284209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:02:54.714696Z","time spent":"569.373362ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T05:02:55.613066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.596258ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-31T05:02:55.613212Z","caller":"traceutil/trace.go:171","msg":"trace[1998672973] linearizableReadLoop","detail":"{readStateIndex:1089; appliedIndex:1088; }","duration":"272.363117ms","start":"2026-03-31T05:02:55.340823Z","end":"2026-03-31T05:02:55.613186Z","steps":["trace[1998672973] 'read index received' (duration: 30.781µs)","trace[1998672973] 'applied index is now lower than readState.Index' (duration: 272.330226ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:02:55.613506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.665516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-31T05:02:55.613557Z","caller":"traceutil/trace.go:171","msg":"trace[1557831434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1047; }","duration":"272.760509ms","start":"2026-03-31T05:02:55.340783Z","end":"2026-03-31T05:02:55.613543Z","steps":["trace[1557831434] 'agreement among raft nodes before linearized reading' (duration: 272.524611ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:02:55.613563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.700478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-31T05:02:55.613661Z","caller":"traceutil/trace.go:171","msg":"trace[2078903693] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1047; }","duration":"272.832361ms","start":"2026-03-31T05:02:55.340805Z","end":"2026-03-31T05:02:55.613638Z","steps":["trace[2078903693] 'agreement among raft nodes before linearized reading' (duration: 272.566803ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:02:55.613758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.262763ms","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-31T05:02:55.613782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.373177ms","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-31T05:02:55.613835Z","caller":"traceutil/trace.go:171","msg":"trace[2118564992] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1047; }","duration":"272.354336ms","start":"2026-03-31T05:02:55.341464Z","end":"2026-03-31T05:02:55.613818Z","steps":["trace[2118564992] 'agreement among raft nodes before linearized reading' (duration: 272.1748ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:02:55.613851Z","caller":"traceutil/trace.go:171","msg":"trace[330071185] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1047; }","duration":"272.463219ms","start":"2026-03-31T05:02:55.341375Z","end":"2026-03-31T05:02:55.613838Z","steps":["trace[330071185] 'agreement among raft nodes before linearized reading' (duration: 272.317345ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:02:56.849503Z","caller":"traceutil/trace.go:171","msg":"trace[564937296] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"134.108877ms","start":"2026-03-31T05:02:56.715373Z","end":"2026-03-31T05:02:56.849482Z","steps":["trace[564937296] 'process raft request' (duration: 133.816028ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:02:57.926934Z","caller":"traceutil/trace.go:171","msg":"trace[892890592] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"634.787402ms","start":"2026-03-31T05:02:57.292121Z","end":"2026-03-31T05:02:57.926908Z","steps":["trace[892890592] 'process raft request' (duration: 634.588055ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:02:57.92705Z","caller":"traceutil/trace.go:171","msg":"trace[375279422] linearizableReadLoop","detail":"{readStateIndex:1095; appliedIndex:1094; }","duration":"290.646971ms","start":"2026-03-31T05:02:57.636374Z","end":"2026-03-31T05:02:57.927021Z","steps":["trace[375279422] 'read index received' (duration: 290.27906ms)","trace[375279422] 'applied index is now lower than readState.Index' (duration: 365.581µs)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:02:57.927106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:02:57.292095Z","time spent":"634.931076ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T05:02:57.927307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.921859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-31T05:02:57.927347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.546878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-31T05:02:57.927394Z","caller":"traceutil/trace.go:171","msg":"trace[800286509] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1053; }","duration":"291.058524ms","start":"2026-03-31T05:02:57.636311Z","end":"2026-03-31T05:02:57.92737Z","steps":["trace[800286509] 'agreement among raft nodes before linearized reading' (duration: 290.856528ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:02:57.9274Z","caller":"traceutil/trace.go:171","msg":"trace[639875085] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1053; }","duration":"280.635941ms","start":"2026-03-31T05:02:57.64675Z","end":"2026-03-31T05:02:57.927386Z","steps":["trace[639875085] 'agreement among raft nodes before linearized reading' (duration: 280.444735ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:02:57.927432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.861388ms","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-31T05:02:57.927432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.749604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-31T05:02:57.927479Z","caller":"traceutil/trace.go:171","msg":"trace[1240354348] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1053; }","duration":"280.948171ms","start":"2026-03-31T05:02:57.646518Z","end":"2026-03-31T05:02:57.927467Z","steps":["trace[1240354348] 'agreement among raft nodes before linearized reading' (duration: 280.661541ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:02:57.927488Z","caller":"traceutil/trace.go:171","msg":"trace[1898698535] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1053; }","duration":"280.859128ms","start":"2026-03-31T05:02:57.646615Z","end":"2026-03-31T05:02:57.927474Z","steps":["trace[1898698535] 'agreement among raft nodes before linearized reading' (duration: 280.671112ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:03:02.052917Z","caller":"traceutil/trace.go:171","msg":"trace[1508246323] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"107.231819ms","start":"2026-03-31T05:03:01.945652Z","end":"2026-03-31T05:03:02.052884Z","steps":["trace[1508246323] 'process raft request' (duration: 107.097574ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:03:02.32113Z","caller":"traceutil/trace.go:171","msg":"trace[2058064214] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"264.758203ms","start":"2026-03-31T05:03:02.056329Z","end":"2026-03-31T05:03:02.321088Z","steps":["trace[2058064214] 'process raft request' (duration: 229.700672ms)","trace[2058064214] 'compare' (duration: 34.780762ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:03:02.365968Z","caller":"traceutil/trace.go:171","msg":"trace[487881373] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"308.541012ms","start":"2026-03-31T05:03:02.057406Z","end":"2026-03-31T05:03:02.365947Z","steps":["trace[487881373] 'process raft request' (duration: 308.48133ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:02.366188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.100409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:03:02.366228Z","caller":"traceutil/trace.go:171","msg":"trace[810639060] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1071; }","duration":"212.200203ms","start":"2026-03-31T05:03:02.154018Z","end":"2026-03-31T05:03:02.366218Z","steps":["trace[810639060] 'agreement among raft nodes before linearized reading' (duration: 212.11867ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:02.366297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:02.0574Z","time spent":"308.79013ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T05:03:02.366053Z","caller":"traceutil/trace.go:171","msg":"trace[613275197] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"309.175182ms","start":"2026-03-31T05:03:02.056857Z","end":"2026-03-31T05:03:02.366032Z","steps":["trace[613275197] 'process raft request' (duration: 308.898753ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:03:02.366105Z","caller":"traceutil/trace.go:171","msg":"trace[1352857240] linearizableReadLoop","detail":"{readStateIndex:1114; appliedIndex:1110; }","duration":"212.008657ms","start":"2026-03-31T05:03:02.154079Z","end":"2026-03-31T05:03:02.366088Z","steps":["trace[1352857240] 'read index received' (duration: 131.966658ms)","trace[1352857240] 'applied index is now lower than readState.Index' (duration: 80.040799ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:03:02.366113Z","caller":"traceutil/trace.go:171","msg":"trace[729535137] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"308.320975ms","start":"2026-03-31T05:03:02.057783Z","end":"2026-03-31T05:03:02.366103Z","steps":["trace[729535137] 'process raft request' (duration: 308.134179ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:02.366517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:02.056851Z","time spent":"309.568575ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T05:03:02.366586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:02.057776Z","time spent":"308.767209ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T05:03:11.660147Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830825754258090,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T05:03:12.160659Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830825754258090,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T05:03:12.661114Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830825754258090,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T05:03:12.775515Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.875081343s","expected-duration":"1s"} {"level":"info","ts":"2026-03-31T05:03:12.775886Z","caller":"traceutil/trace.go:171","msg":"trace[1473564298] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"1.875532477s","start":"2026-03-31T05:03:10.900334Z","end":"2026-03-31T05:03:12.775867Z","steps":["trace[1473564298] 'process raft request' (duration: 1.875402443s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:12.776001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:10.90031Z","time spent":"1.87562988s","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T05:03:12.815186Z","caller":"traceutil/trace.go:171","msg":"trace[1525185056] linearizableReadLoop","detail":"{readStateIndex:1197; appliedIndex:1195; }","duration":"1.65510522s","start":"2026-03-31T05:03:11.160057Z","end":"2026-03-31T05:03:12.815162Z","steps":["trace[1525185056] 'read index received' (duration: 1.615720841s)","trace[1525185056] 'applied index is now lower than readState.Index' (duration: 39.383749ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:03:12.815478Z","caller":"traceutil/trace.go:171","msg":"trace[644980225] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"1.834506666s","start":"2026-03-31T05:03:10.980961Z","end":"2026-03-31T05:03:12.815468Z","steps":["trace[644980225] 'process raft request' (duration: 1.834087092s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:12.815584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:10.980936Z","time spent":"1.834563958s","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T05:03:12.81573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.655685109s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:03:12.815759Z","caller":"traceutil/trace.go:171","msg":"trace[774249923] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1150; }","duration":"1.655757681s","start":"2026-03-31T05:03:11.159993Z","end":"2026-03-31T05:03:12.81575Z","steps":["trace[774249923] 'agreement among raft nodes before linearized reading' (duration: 1.655710969s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:12.815786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:11.159966Z","time spent":"1.655814993s","remote":"127.0.0.1:57602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T05:03:12.815957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.401559525s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:03:12.815989Z","caller":"traceutil/trace.go:171","msg":"trace[352195569] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1150; }","duration":"1.401616316s","start":"2026-03-31T05:03:11.414361Z","end":"2026-03-31T05:03:12.815977Z","steps":["trace[352195569] 'agreement among raft nodes before linearized reading' (duration: 1.401571785s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:12.816016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:11.414347Z","time spent":"1.401661818s","remote":"127.0.0.1:58416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-03-31T05:03:12.816304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.288711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-31T05:03:12.816338Z","caller":"traceutil/trace.go:171","msg":"trace[157867410] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1150; }","duration":"454.352733ms","start":"2026-03-31T05:03:12.361977Z","end":"2026-03-31T05:03:12.816329Z","steps":["trace[157867410] 'agreement among raft nodes before linearized reading' (duration: 454.24425ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:12.816368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:12.361955Z","time spent":"454.407155ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-31T05:03:12.816401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.268289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:03:12.816452Z","caller":"traceutil/trace.go:171","msg":"trace[641741414] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1150; }","duration":"397.365182ms","start":"2026-03-31T05:03:12.419073Z","end":"2026-03-31T05:03:12.816438Z","steps":["trace[641741414] 'agreement among raft nodes before linearized reading' (duration: 397.283739ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:12.816461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.203338ms","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-31T05:03:12.816511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.698135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-31T05:03:12.81655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.705455ms","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":"warn","ts":"2026-03-31T05:03:12.81656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.446554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:03:12.816583Z","caller":"traceutil/trace.go:171","msg":"trace[342854336] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1150; }","duration":"718.740346ms","start":"2026-03-31T05:03:12.097835Z","end":"2026-03-31T05:03:12.816575Z","steps":["trace[342854336] 'agreement among raft nodes before linearized reading' (duration: 718.657944ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:03:12.816587Z","caller":"traceutil/trace.go:171","msg":"trace[1989572624] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1150; }","duration":"218.512635ms","start":"2026-03-31T05:03:12.598066Z","end":"2026-03-31T05:03:12.816579Z","steps":["trace[1989572624] 'agreement among raft nodes before linearized reading' (duration: 218.469914ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:03:12.816589Z","caller":"traceutil/trace.go:171","msg":"trace[484789488] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1150; }","duration":"403.778997ms","start":"2026-03-31T05:03:12.412789Z","end":"2026-03-31T05:03:12.816568Z","steps":["trace[484789488] 'agreement among raft nodes before linearized reading' (duration: 403.639173ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:12.816614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:12.097786Z","time spent":"718.820548ms","remote":"127.0.0.1:57912","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-31T05:03:12.816648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:12.412782Z","time spent":"403.859419ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-31T05:03:12.816515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:12.419051Z","time spent":"397.431354ms","remote":"127.0.0.1:58378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-03-31T05:03:12.816369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.274641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-31T05:03:12.816512Z","caller":"traceutil/trace.go:171","msg":"trace[2144485842] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1150; }","duration":"403.254801ms","start":"2026-03-31T05:03:12.413241Z","end":"2026-03-31T05:03:12.816496Z","steps":["trace[2144485842] 'agreement among raft nodes before linearized reading' (duration: 403.165548ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:03:12.816857Z","caller":"traceutil/trace.go:171","msg":"trace[1284678697] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1150; }","duration":"408.785177ms","start":"2026-03-31T05:03:12.408059Z","end":"2026-03-31T05:03:12.816844Z","steps":["trace[1284678697] 'agreement among raft nodes before linearized reading' (duration: 408.149356ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:12.816875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:12.413235Z","time spent":"403.627622ms","remote":"127.0.0.1:57912","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-31T05:03:12.816901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:12.408014Z","time spent":"408.87389ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-31T05:03:19.005097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.009299ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T05:03:19.005166Z","caller":"traceutil/trace.go:171","msg":"trace[1646689898] linearizableReadLoop","detail":"{readStateIndex:1218; appliedIndex:1217; }","duration":"120.456231ms","start":"2026-03-31T05:03:18.884698Z","end":"2026-03-31T05:03:19.005155Z","steps":["trace[1646689898] 'read index received' (duration: 9.261376ms)","trace[1646689898] 'applied index is now lower than readState.Index' (duration: 111.194185ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:03:19.00522Z","caller":"traceutil/trace.go:171","msg":"trace[2077035492] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"121.829715ms","start":"2026-03-31T05:03:18.883385Z","end":"2026-03-31T05:03:19.005215Z","steps":["trace[2077035492] 'process raft request' (duration: 10.646521ms)","trace[2077035492] 'compare' (duration: 110.930446ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:03:19.005328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.472861ms","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-31T05:03:19.00535Z","caller":"traceutil/trace.go:171","msg":"trace[1606942223] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1170; }","duration":"120.544703ms","start":"2026-03-31T05:03:18.884798Z","end":"2026-03-31T05:03:19.005343Z","steps":["trace[1606942223] 'agreement among raft nodes before linearized reading' (duration: 120.45872ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:19.005388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.678988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-31T05:03:19.005452Z","caller":"traceutil/trace.go:171","msg":"trace[1722089971] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1170; }","duration":"120.787601ms","start":"2026-03-31T05:03:18.884647Z","end":"2026-03-31T05:03:19.005434Z","steps":["trace[1722089971] 'agreement among raft nodes before linearized reading' (duration: 120.627436ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:19.005469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.681172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T05:03:19.00544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.314278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-31T05:03:19.00548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.969301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-31T05:03:19.005531Z","caller":"traceutil/trace.go:171","msg":"trace[941588159] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1170; }","duration":"111.419502ms","start":"2026-03-31T05:03:18.894094Z","end":"2026-03-31T05:03:19.005514Z","steps":["trace[941588159] 'agreement among raft nodes before linearized reading' (duration: 111.174774ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:03:19.005568Z","caller":"traceutil/trace.go:171","msg":"trace[672635488] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1170; }","duration":"113.069124ms","start":"2026-03-31T05:03:18.89247Z","end":"2026-03-31T05:03:19.005539Z","steps":["trace[672635488] 'agreement among raft nodes before linearized reading' (duration: 112.850667ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:03:19.005486Z","caller":"traceutil/trace.go:171","msg":"trace[1971451850] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1170; }","duration":"117.710602ms","start":"2026-03-31T05:03:18.887769Z","end":"2026-03-31T05:03:19.00548Z","steps":["trace[1971451850] 'agreement among raft nodes before linearized reading' (duration: 117.680611ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:03:27.778567Z","caller":"traceutil/trace.go:171","msg":"trace[1703788414] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"139.988825ms","start":"2026-03-31T05:03:27.638554Z","end":"2026-03-31T05:03:27.778543Z","steps":["trace[1703788414] 'process raft request' (duration: 139.806459ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:29.597917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.556127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:03:29.598008Z","caller":"traceutil/trace.go:171","msg":"trace[1436178481] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1210; }","duration":"731.687051ms","start":"2026-03-31T05:03:28.866295Z","end":"2026-03-31T05:03:29.597982Z","steps":["trace[1436178481] 'count revisions from in-memory index tree' (duration: 731.489654ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:29.598023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.444707956s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T05:03:29.598059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:28.866281Z","time spent":"731.766103ms","remote":"127.0.0.1:57858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-03-31T05:03:29.598077Z","caller":"traceutil/trace.go:171","msg":"trace[2078714621] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1210; }","duration":"1.444796849s","start":"2026-03-31T05:03:28.153267Z","end":"2026-03-31T05:03:29.598064Z","steps":["trace[2078714621] 'range keys from in-memory index tree' (duration: 1.444654624s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:29.598105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:28.153254Z","time spent":"1.44484313s","remote":"127.0.0.1:57602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T05:03:29.598184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.04947626s","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":"info","ts":"2026-03-31T05:03:29.598234Z","caller":"traceutil/trace.go:171","msg":"trace[1559773610] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1210; }","duration":"1.049579614s","start":"2026-03-31T05:03:28.548641Z","end":"2026-03-31T05:03:29.598221Z","steps":["trace[1559773610] 'count revisions from in-memory index tree' (duration: 1.049392718s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:29.598258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:28.548621Z","time spent":"1.049630136s","remote":"127.0.0.1:58262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-03-31T05:03:29.598424Z","caller":"traceutil/trace.go:171","msg":"trace[1493671084] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"659.007748ms","start":"2026-03-31T05:03:28.9394Z","end":"2026-03-31T05:03:29.598407Z","steps":["trace[1493671084] 'process raft request' (duration: 597.361957ms)","trace[1493671084] 'compare' (duration: 61.335761ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:03:29.598422Z","caller":"traceutil/trace.go:171","msg":"trace[2107616699] linearizableReadLoop","detail":"{readStateIndex:1261; appliedIndex:1260; }","duration":"440.499022ms","start":"2026-03-31T05:03:29.157906Z","end":"2026-03-31T05:03:29.598405Z","steps":["trace[2107616699] 'read index received' (duration: 378.770159ms)","trace[2107616699] 'applied index is now lower than readState.Index' (duration: 61.727883ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:03:29.598564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:28.939374Z","time spent":"659.08587ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T05:03:29.598693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.234743ms","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-31T05:03:29.598731Z","caller":"traceutil/trace.go:171","msg":"trace[785319750] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1211; }","duration":"267.299626ms","start":"2026-03-31T05:03:29.33142Z","end":"2026-03-31T05:03:29.59872Z","steps":["trace[785319750] 'agreement among raft nodes before linearized reading' (duration: 267.155381ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:29.598944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.058004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-31T05:03:29.598975Z","caller":"traceutil/trace.go:171","msg":"trace[2064992570] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1211; }","duration":"194.121025ms","start":"2026-03-31T05:03:29.404845Z","end":"2026-03-31T05:03:29.598966Z","steps":["trace[2064992570] 'agreement among raft nodes before linearized reading' (duration: 194.068194ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:29.599418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.032866ms","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-31T05:03:29.599452Z","caller":"traceutil/trace.go:171","msg":"trace[1663156750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1211; }","duration":"363.096108ms","start":"2026-03-31T05:03:29.236347Z","end":"2026-03-31T05:03:29.599443Z","steps":["trace[1663156750] 'agreement among raft nodes before linearized reading' (duration: 362.958173ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:29.599481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:29.236332Z","time spent":"363.141469ms","remote":"127.0.0.1:57912","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-31T05:03:29.599867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.438549ms","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-31T05:03:29.599902Z","caller":"traceutil/trace.go:171","msg":"trace[1165397269] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1211; }","duration":"409.514931ms","start":"2026-03-31T05:03:29.190377Z","end":"2026-03-31T05:03:29.599892Z","steps":["trace[1165397269] 'agreement among raft nodes before linearized reading' (duration: 409.400477ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:29.599931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:29.190357Z","time spent":"409.568043ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-31T05:03:29.599949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.056332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-31T05:03:29.599974Z","caller":"traceutil/trace.go:171","msg":"trace[1231395500] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1211; }","duration":"442.117084ms","start":"2026-03-31T05:03:29.157847Z","end":"2026-03-31T05:03:29.599964Z","steps":["trace[1231395500] 'agreement among raft nodes before linearized reading' (duration: 440.644717ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:29.599988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:29.157817Z","time spent":"442.167315ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-31T05:03:29.599992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.282732ms","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-31T05:03:29.600054Z","caller":"traceutil/trace.go:171","msg":"trace[2120068102] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1211; }","duration":"413.351595ms","start":"2026-03-31T05:03:29.186688Z","end":"2026-03-31T05:03:29.600039Z","steps":["trace[2120068102] 'agreement among raft nodes before linearized reading' (duration: 411.882758ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:29.600084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:29.18665Z","time spent":"413.428857ms","remote":"127.0.0.1:57912","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-31T05:03:30.108382Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830825754258236,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T05:03:30.409008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.063407ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T05:03:30.409129Z","caller":"traceutil/trace.go:171","msg":"trace[1852928452] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"804.055634ms","start":"2026-03-31T05:03:29.60505Z","end":"2026-03-31T05:03:30.409106Z","steps":["trace[1852928452] 'process raft request' (duration: 627.78493ms)","trace[1852928452] 'compare' (duration: 175.971314ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:03:30.40934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:29.605036Z","time spent":"804.25735ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T05:03:30.570266Z","caller":"traceutil/trace.go:171","msg":"trace[84363049] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"964.817553ms","start":"2026-03-31T05:03:29.605349Z","end":"2026-03-31T05:03:30.570167Z","steps":["trace[84363049] 'process raft request' (duration: 932.81226ms)","trace[84363049] 'compare' (duration: 31.827197ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:03:30.570424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:29.605327Z","time spent":"965.012499ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T05:03:30.570508Z","caller":"traceutil/trace.go:171","msg":"trace[1138639723] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"965.017889ms","start":"2026-03-31T05:03:29.605404Z","end":"2026-03-31T05:03:30.570421Z","steps":["trace[1138639723] 'process raft request' (duration: 964.718659ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:03:30.570531Z","caller":"traceutil/trace.go:171","msg":"trace[2053606139] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"965.051621ms","start":"2026-03-31T05:03:29.605455Z","end":"2026-03-31T05:03:30.570506Z","steps":["trace[2053606139] 'process raft request' (duration: 964.877875ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:03:30.570603Z","caller":"traceutil/trace.go:171","msg":"trace[996884294] linearizableReadLoop","detail":"{readStateIndex:1266; appliedIndex:1261; }","duration":"962.479238ms","start":"2026-03-31T05:03:29.60811Z","end":"2026-03-31T05:03:30.570589Z","steps":["trace[996884294] 'read index received' (duration: 624.637049ms)","trace[996884294] 'applied index is now lower than readState.Index' (duration: 337.841369ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:03:30.570645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:29.605392Z","time spent":"965.187715ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T05:03:30.570697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:29.605424Z","time spent":"965.194745ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T05:03:30.570744Z","caller":"traceutil/trace.go:171","msg":"trace[1010914481] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"963.894823ms","start":"2026-03-31T05:03:29.606839Z","end":"2026-03-31T05:03:30.570734Z","steps":["trace[1010914481] 'process raft request' (duration: 963.610904ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:30.570823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.715476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:03:30.570846Z","caller":"traceutil/trace.go:171","msg":"trace[1177142166] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1216; }","duration":"962.748036ms","start":"2026-03-31T05:03:29.608092Z","end":"2026-03-31T05:03:30.57084Z","steps":["trace[1177142166] 'agreement among raft nodes before linearized reading' (duration: 962.715275ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:30.57086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:29.608084Z","time spent":"962.773688ms","remote":"127.0.0.1:57602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T05:03:30.570852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:29.606822Z","time spent":"963.994176ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T05:03:30.570793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.541297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:03:30.570943Z","caller":"traceutil/trace.go:171","msg":"trace[669735620] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1216; }","duration":"905.723464ms","start":"2026-03-31T05:03:29.665207Z","end":"2026-03-31T05:03:30.570931Z","steps":["trace[669735620] 'agreement among raft nodes before linearized reading' (duration: 905.534188ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:30.570973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:29.665188Z","time spent":"905.775075ms","remote":"127.0.0.1:38776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-03-31T05:03:30.570963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.528469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:03:30.57101Z","caller":"traceutil/trace.go:171","msg":"trace[490256719] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1216; }","duration":"469.610001ms","start":"2026-03-31T05:03:30.101386Z","end":"2026-03-31T05:03:30.570996Z","steps":["trace[490256719] 'agreement among raft nodes before linearized reading' (duration: 469.532429ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:30.571049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:30.10137Z","time spent":"469.668774ms","remote":"127.0.0.1:58170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-03-31T05:03:30.571072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.892849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.115\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-31T05:03:30.571089Z","caller":"traceutil/trace.go:171","msg":"trace[366519486] range","detail":"{range_begin:/registry/masterleases/199.204.45.115; range_end:; response_count:1; response_revision:1216; }","duration":"620.94369ms","start":"2026-03-31T05:03:29.95014Z","end":"2026-03-31T05:03:30.571084Z","steps":["trace[366519486] 'agreement among raft nodes before linearized reading' (duration: 620.859097ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:30.571102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:29.95011Z","time spent":"620.989652ms","remote":"127.0.0.1:57646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.115\" "} {"level":"warn","ts":"2026-03-31T05:03:30.571273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.47056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:03:30.571316Z","caller":"traceutil/trace.go:171","msg":"trace[2044647490] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1216; }","duration":"399.557732ms","start":"2026-03-31T05:03:30.171746Z","end":"2026-03-31T05:03:30.571304Z","steps":["trace[2044647490] 'agreement among raft nodes before linearized reading' (duration: 399.48723ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:30.571347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:30.171716Z","time spent":"399.621894ms","remote":"127.0.0.1:58344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-03-31T05:03:31.700539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.075591ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-31T05:03:31.700825Z","caller":"traceutil/trace.go:171","msg":"trace[389366405] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"141.602917ms","start":"2026-03-31T05:03:31.559032Z","end":"2026-03-31T05:03:31.700635Z","steps":["trace[389366405] 'process raft request' (duration: 25.35655ms)","trace[389366405] 'compare' (duration: 115.914656ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:03:31.795476Z","caller":"traceutil/trace.go:171","msg":"trace[820987054] linearizableReadLoop","detail":"{readStateIndex:1271; appliedIndex:1270; }","duration":"143.670013ms","start":"2026-03-31T05:03:31.65179Z","end":"2026-03-31T05:03:31.79546Z","steps":["trace[820987054] 'read index received' (duration: 143.606171ms)","trace[820987054] 'applied index is now lower than readState.Index' (duration: 63.362µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:03:31.795537Z","caller":"traceutil/trace.go:171","msg":"trace[1595123250] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"190.763609ms","start":"2026-03-31T05:03:31.604753Z","end":"2026-03-31T05:03:31.795517Z","steps":["trace[1595123250] 'process raft request' (duration: 190.607494ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:31.795578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.774546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-31T05:03:31.795596Z","caller":"traceutil/trace.go:171","msg":"trace[1185955022] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1220; }","duration":"143.847538ms","start":"2026-03-31T05:03:31.651742Z","end":"2026-03-31T05:03:31.79559Z","steps":["trace[1185955022] 'agreement among raft nodes before linearized reading' (duration: 143.790926ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:32.296437Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830825754258256,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-31T05:03:32.760696Z","caller":"traceutil/trace.go:171","msg":"trace[829321084] linearizableReadLoop","detail":"{readStateIndex:1272; appliedIndex:1271; }","duration":"965.150975ms","start":"2026-03-31T05:03:31.795521Z","end":"2026-03-31T05:03:32.760672Z","steps":["trace[829321084] 'read index received' (duration: 964.983849ms)","trace[829321084] 'applied index is now lower than readState.Index' (duration: 165.936µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:03:32.760863Z","caller":"traceutil/trace.go:171","msg":"trace[669221521] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"1.056305829s","start":"2026-03-31T05:03:31.70454Z","end":"2026-03-31T05:03:32.760846Z","steps":["trace[669221521] 'process raft request' (duration: 1.055981209s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:32.760984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:31.704525Z","time spent":"1.056392701s","remote":"127.0.0.1:57714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":823,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T05:03:33.500983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.157723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-31T05:03:33.501051Z","caller":"traceutil/trace.go:171","msg":"trace[1790839458] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1221; }","duration":"451.290117ms","start":"2026-03-31T05:03:33.049745Z","end":"2026-03-31T05:03:33.501035Z","steps":["trace[1790839458] 'count revisions from in-memory index tree' (duration: 451.072ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:33.501084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:33.049722Z","time spent":"451.351839ms","remote":"127.0.0.1:58398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-03-31T05:03:33.501066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.178945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:03:33.501169Z","caller":"traceutil/trace.go:171","msg":"trace[1611229298] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1221; }","duration":"350.385771ms","start":"2026-03-31T05:03:33.150736Z","end":"2026-03-31T05:03:33.501122Z","steps":["trace[1611229298] 'range keys from in-memory index tree' (duration: 350.034561ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:33.501161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.030056ms","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":"warn","ts":"2026-03-31T05:03:33.501209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:33.150717Z","time spent":"350.483165ms","remote":"127.0.0.1:57602","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-31T05:03:33.501218Z","caller":"traceutil/trace.go:171","msg":"trace[1241597489] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1221; }","duration":"927.161841ms","start":"2026-03-31T05:03:32.57404Z","end":"2026-03-31T05:03:33.501202Z","steps":["trace[1241597489] 'agreement among raft nodes before linearized reading' (duration: 187.102872ms)","trace[1241597489] 'range keys from in-memory index tree' (duration: 739.898984ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:03:33.501248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.101937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-03-31T05:03:33.50126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:32.574019Z","time spent":"927.234812ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-31T05:03:33.501288Z","caller":"traceutil/trace.go:171","msg":"trace[1501139780] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1221; }","duration":"926.148308ms","start":"2026-03-31T05:03:32.575125Z","end":"2026-03-31T05:03:33.501273Z","steps":["trace[1501139780] 'agreement among raft nodes before linearized reading' (duration: 185.989926ms)","trace[1501139780] 'range keys from in-memory index tree' (duration: 740.049379ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:03:33.501311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:32.575116Z","time spent":"926.188679ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-31T05:03:33.501383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.53319ms","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-31T05:03:33.501392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.75515229s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5331"} {"level":"info","ts":"2026-03-31T05:03:33.501406Z","caller":"traceutil/trace.go:171","msg":"trace[1722461303] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1221; }","duration":"926.590242ms","start":"2026-03-31T05:03:32.574808Z","end":"2026-03-31T05:03:33.501398Z","steps":["trace[1722461303] 'agreement among raft nodes before linearized reading' (duration: 186.314276ms)","trace[1722461303] 'range keys from in-memory index tree' (duration: 740.214584ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:03:33.501407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.286184ms","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-31T05:03:33.501442Z","caller":"traceutil/trace.go:171","msg":"trace[936088696] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1221; }","duration":"927.339006ms","start":"2026-03-31T05:03:32.574095Z","end":"2026-03-31T05:03:33.501434Z","steps":["trace[936088696] 'agreement among raft nodes before linearized reading' (duration: 187.039649ms)","trace[936088696] 'range keys from in-memory index tree' (duration: 740.229934ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:03:33.501436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:32.574801Z","time spent":"926.629903ms","remote":"127.0.0.1:57912","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-31T05:03:33.501443Z","caller":"traceutil/trace.go:171","msg":"trace[1934753876] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1221; }","duration":"1.755243183s","start":"2026-03-31T05:03:31.746183Z","end":"2026-03-31T05:03:33.501426Z","steps":["trace[1934753876] 'agreement among raft nodes before linearized reading' (duration: 1.014922345s)","trace[1934753876] 'range keys from in-memory index tree' (duration: 740.205583ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:03:33.50146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:32.574019Z","time spent":"927.436079ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-31T05:03:33.501392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.088541899s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-31T05:03:33.501481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:31.746161Z","time spent":"1.755312305s","remote":"127.0.0.1:57816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5354,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-03-31T05:03:33.5015Z","caller":"traceutil/trace.go:171","msg":"trace[726193607] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1221; }","duration":"1.088703515s","start":"2026-03-31T05:03:32.412787Z","end":"2026-03-31T05:03:33.50149Z","steps":["trace[726193607] 'agreement among raft nodes before linearized reading' (duration: 348.364757ms)","trace[726193607] 'range keys from in-memory index tree' (duration: 740.124341ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:03:33.501569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:32.412771Z","time spent":"1.088786887s","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-31T05:03:33.50169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.233166503s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:03:33.501779Z","caller":"traceutil/trace.go:171","msg":"trace[907387261] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1221; }","duration":"1.233322208s","start":"2026-03-31T05:03:32.268433Z","end":"2026-03-31T05:03:33.501755Z","steps":["trace[907387261] 'agreement among raft nodes before linearized reading' (duration: 492.65166ms)","trace[907387261] 'count revisions from in-memory index tree' (duration: 740.513634ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:03:33.50183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:32.268413Z","time spent":"1.23340623s","remote":"127.0.0.1:58074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"info","ts":"2026-03-31T05:03:33.586168Z","caller":"traceutil/trace.go:171","msg":"trace[1812362102] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"405.729051ms","start":"2026-03-31T05:03:33.180416Z","end":"2026-03-31T05:03:33.586145Z","steps":["trace[1812362102] 'process raft request' (duration: 405.252286ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:33.586441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:33.180404Z","time spent":"405.957678ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T05:03:35.028713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.332418ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T05:03:35.029016Z","caller":"traceutil/trace.go:171","msg":"trace[571083033] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"1.512805481s","start":"2026-03-31T05:03:33.516194Z","end":"2026-03-31T05:03:35.029Z","steps":["trace[571083033] 'process raft request' (duration: 1.51276769s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:35.02912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:33.516178Z","time spent":"1.512890915s","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T05:03:35.029387Z","caller":"traceutil/trace.go:171","msg":"trace[1178497047] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"1.52338321s","start":"2026-03-31T05:03:33.505984Z","end":"2026-03-31T05:03:35.029367Z","steps":["trace[1178497047] 'process raft request' (duration: 1.522890874s)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:03:35.029423Z","caller":"traceutil/trace.go:171","msg":"trace[482612575] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"1.52400843s","start":"2026-03-31T05:03:33.505382Z","end":"2026-03-31T05:03:35.02939Z","steps":["trace[482612575] 'process raft request' (duration: 914.924008ms)","trace[482612575] 'compare' (duration: 608.219964ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:03:35.029459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:33.505964Z","time spent":"1.523455162s","remote":"127.0.0.1:57912","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-31T05:03:35.029478Z","caller":"traceutil/trace.go:171","msg":"trace[1637210865] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"1.523800443s","start":"2026-03-31T05:03:33.505662Z","end":"2026-03-31T05:03:35.029463Z","steps":["trace[1637210865] 'process raft request' (duration: 1.523131682s)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:03:35.029529Z","caller":"traceutil/trace.go:171","msg":"trace[810580952] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"1.513978579s","start":"2026-03-31T05:03:33.51553Z","end":"2026-03-31T05:03:35.029508Z","steps":["trace[810580952] 'process raft request' (duration: 1.51338849s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:35.029563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:33.505652Z","time spent":"1.523861495s","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T05:03:35.029584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:33.505359Z","time spent":"1.524132264s","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T05:03:35.029634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:33.51552Z","time spent":"1.514056762s","remote":"127.0.0.1:57816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5457,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T05:03:35.02931Z","caller":"traceutil/trace.go:171","msg":"trace[360455149] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"1.523562026s","start":"2026-03-31T05:03:33.50573Z","end":"2026-03-31T05:03:35.029292Z","steps":["trace[360455149] 'process raft request' (duration: 1.523110801s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:35.029739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:33.505721Z","time spent":"1.523959088s","remote":"127.0.0.1:57912","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-31T05:03:35.030175Z","caller":"traceutil/trace.go:171","msg":"trace[614386257] linearizableReadLoop","detail":"{readStateIndex:1280; appliedIndex:1273; }","duration":"876.406938ms","start":"2026-03-31T05:03:34.153754Z","end":"2026-03-31T05:03:35.030161Z","steps":["trace[614386257] 'read index received' (duration: 266.50494ms)","trace[614386257] 'applied index is now lower than readState.Index' (duration: 609.901268ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:03:35.03026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"876.49431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:03:35.030281Z","caller":"traceutil/trace.go:171","msg":"trace[2093660673] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1229; }","duration":"876.560232ms","start":"2026-03-31T05:03:34.153714Z","end":"2026-03-31T05:03:35.030274Z","steps":["trace[2093660673] 'agreement among raft nodes before linearized reading' (duration: 876.5033ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:35.030313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:34.153697Z","time spent":"876.610874ms","remote":"127.0.0.1:57602","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-31T05:03:35.030437Z","caller":"traceutil/trace.go:171","msg":"trace[624049346] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"1.229590107s","start":"2026-03-31T05:03:33.800839Z","end":"2026-03-31T05:03:35.030429Z","steps":["trace[624049346] 'process raft request' (duration: 1.229247326s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:35.030524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:33.800812Z","time spent":"1.229643819s","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T05:03:35.532Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830825754258272,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-31T05:03:35.57995Z","caller":"traceutil/trace.go:171","msg":"trace[1015718529] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"1.053749607s","start":"2026-03-31T05:03:34.526177Z","end":"2026-03-31T05:03:35.579927Z","steps":["trace[1015718529] 'process raft request' (duration: 1.053579192s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:35.580092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:34.526157Z","time spent":"1.053860781s","remote":"127.0.0.1:57912","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-31T05:03:35.580136Z","caller":"traceutil/trace.go:171","msg":"trace[615952856] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1230; }","duration":"556.413678ms","start":"2026-03-31T05:03:35.023694Z","end":"2026-03-31T05:03:35.580107Z","steps":["trace[615952856] 'process raft request' (duration: 556.199781ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:35.580319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:35.023671Z","time spent":"556.560893ms","remote":"127.0.0.1:57816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30,"response count":0,"response size":5505,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T05:03:36.116916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.835397ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-03-31T05:03:36.117184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.08577458s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:03:36.117271Z","caller":"traceutil/trace.go:171","msg":"trace[1961190435] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1230; }","duration":"1.085899095s","start":"2026-03-31T05:03:35.031352Z","end":"2026-03-31T05:03:36.117251Z","steps":["trace[1961190435] 'agreement among raft nodes before linearized reading' (duration: 1.085741769s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:36.117321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:35.031341Z","time spent":"1.085972157s","remote":"127.0.0.1:57604","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-31T05:03:36.117046Z","caller":"traceutil/trace.go:171","msg":"trace[506277303] linearizableReadLoop","detail":"{readStateIndex:1283; appliedIndex:1280; }","duration":"1.085641286s","start":"2026-03-31T05:03:35.031378Z","end":"2026-03-31T05:03:36.11702Z","steps":["trace[506277303] 'read index received' (duration: 548.37201ms)","trace[506277303] 'applied index is now lower than readState.Index' (duration: 537.267186ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:03:36.137388Z","caller":"traceutil/trace.go:171","msg":"trace[1679205570] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"551.15644ms","start":"2026-03-31T05:03:35.586209Z","end":"2026-03-31T05:03:36.137365Z","steps":["trace[1679205570] 'process raft request' (duration: 550.992834ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:36.1375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.105004885s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-03-31T05:03:36.137539Z","caller":"traceutil/trace.go:171","msg":"trace[420198096] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:1231; }","duration":"1.105084348s","start":"2026-03-31T05:03:35.032444Z","end":"2026-03-31T05:03:36.137528Z","steps":["trace[420198096] 'agreement among raft nodes before linearized reading' (duration: 1.104982875s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:36.137517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:35.586194Z","time spent":"551.257653ms","remote":"127.0.0.1:57816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5883,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T05:03:36.137574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:35.032404Z","time spent":"1.105163041s","remote":"127.0.0.1:58050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-03-31T05:03:37.566139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.164249ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T05:03:37.566437Z","caller":"traceutil/trace.go:171","msg":"trace[1704785260] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"503.521396ms","start":"2026-03-31T05:03:37.062864Z","end":"2026-03-31T05:03:37.566386Z","steps":["trace[1704785260] 'process raft request' (duration: 79.003705ms)","trace[1704785260] 'compare' (duration: 424.058406ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:03:37.566461Z","caller":"traceutil/trace.go:171","msg":"trace[979023017] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"451.351217ms","start":"2026-03-31T05:03:37.115092Z","end":"2026-03-31T05:03:37.566443Z","steps":["trace[979023017] 'process raft request' (duration: 451.194212ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:37.566571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.017772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T05:03:37.566596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:37.115071Z","time spent":"451.461391ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T05:03:37.5666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:37.062846Z","time spent":"503.684891ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T05:03:37.566637Z","caller":"traceutil/trace.go:171","msg":"trace[1263877104] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1240; }","duration":"433.086764ms","start":"2026-03-31T05:03:37.133531Z","end":"2026-03-31T05:03:37.566618Z","steps":["trace[1263877104] 'agreement among raft nodes before linearized reading' (duration: 432.991311ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:03:37.566614Z","caller":"traceutil/trace.go:171","msg":"trace[862083651] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"450.400718ms","start":"2026-03-31T05:03:37.116186Z","end":"2026-03-31T05:03:37.566586Z","steps":["trace[862083651] 'process raft request' (duration: 450.185061ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:03:37.566451Z","caller":"traceutil/trace.go:171","msg":"trace[1239028736] linearizableReadLoop","detail":"{readStateIndex:1293; appliedIndex:1290; }","duration":"432.889518ms","start":"2026-03-31T05:03:37.133537Z","end":"2026-03-31T05:03:37.566427Z","steps":["trace[1239028736] 'read index received' (duration: 8.324126ms)","trace[1239028736] 'applied index is now lower than readState.Index' (duration: 424.564112ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:03:37.566696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:37.133511Z","time spent":"433.169037ms","remote":"127.0.0.1:57984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-03-31T05:03:37.566775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.390105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T05:03:37.566786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:37.116175Z","time spent":"450.530582ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T05:03:37.566816Z","caller":"traceutil/trace.go:171","msg":"trace[2131935051] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1240; }","duration":"413.453977ms","start":"2026-03-31T05:03:37.153351Z","end":"2026-03-31T05:03:37.566805Z","steps":["trace[2131935051] 'agreement among raft nodes before linearized reading' (duration: 413.393765ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:37.566843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:37.153335Z","time spent":"413.502928ms","remote":"127.0.0.1:57602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T05:03:37.567027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.623591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-9lbrh\" ","response":"range_response_count:1 size:11061"} {"level":"info","ts":"2026-03-31T05:03:37.567129Z","caller":"traceutil/trace.go:171","msg":"trace[890007314] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-9lbrh; range_end:; response_count:1; response_revision:1240; }","duration":"362.749615ms","start":"2026-03-31T05:03:37.204345Z","end":"2026-03-31T05:03:37.567095Z","steps":["trace[890007314] 'agreement among raft nodes before linearized reading' (duration: 362.480537ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:37.567172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:37.204333Z","time spent":"362.830018ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":11084,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-9lbrh\" "} {"level":"warn","ts":"2026-03-31T05:03:38.489921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.523471ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T05:03:38.490614Z","caller":"traceutil/trace.go:171","msg":"trace[801780988] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"910.89183ms","start":"2026-03-31T05:03:37.579685Z","end":"2026-03-31T05:03:38.490577Z","steps":["trace[801780988] 'process raft request' (duration: 252.619546ms)","trace[801780988] 'compare' (duration: 657.402886ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:03:38.490767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:37.57966Z","time spent":"911.063296ms","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11375,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T05:03:38.730019Z","caller":"traceutil/trace.go:171","msg":"trace[972515487] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"231.835251ms","start":"2026-03-31T05:03:38.498157Z","end":"2026-03-31T05:03:38.729992Z","steps":["trace[972515487] 'process raft request' (duration: 174.698994ms)","trace[972515487] 'compare' (duration: 57.006583ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:03:39.109288Z","caller":"traceutil/trace.go:171","msg":"trace[171478345] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"606.52792ms","start":"2026-03-31T05:03:38.502738Z","end":"2026-03-31T05:03:39.109266Z","steps":["trace[171478345] 'process raft request' (duration: 606.434987ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:39.109427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:38.502726Z","time spent":"606.628833ms","remote":"127.0.0.1:58146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T05:03:39.109515Z","caller":"traceutil/trace.go:171","msg":"trace[1953888407] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"611.082486ms","start":"2026-03-31T05:03:38.498399Z","end":"2026-03-31T05:03:39.109481Z","steps":["trace[1953888407] 'process raft request' (duration: 589.866827ms)","trace[1953888407] 'compare' (duration: 20.649641ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:03:39.109755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:38.498376Z","time spent":"611.23634ms","remote":"127.0.0.1:57926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T05:03:39.502139Z","caller":"traceutil/trace.go:171","msg":"trace[1237250554] linearizableReadLoop","detail":"{readStateIndex:1300; appliedIndex:1298; }","duration":"349.368629ms","start":"2026-03-31T05:03:39.152751Z","end":"2026-03-31T05:03:39.50212Z","steps":["trace[1237250554] 'read index received' (duration: 10.693632ms)","trace[1237250554] 'applied index is now lower than readState.Index' (duration: 338.674207ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:03:39.50223Z","caller":"traceutil/trace.go:171","msg":"trace[273275868] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"379.409369ms","start":"2026-03-31T05:03:39.122812Z","end":"2026-03-31T05:03:39.502222Z","steps":["trace[273275868] 'process raft request' (duration: 352.953773ms)","trace[273275868] 'compare' (duration: 26.042463ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:03:39.50259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:39.122803Z","time spent":"379.712558ms","remote":"127.0.0.1:57912","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-31T05:03:39.502623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.810603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-31T05:03:39.502722Z","caller":"traceutil/trace.go:171","msg":"trace[1600020691] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1247; }","duration":"324.972318ms","start":"2026-03-31T05:03:39.177724Z","end":"2026-03-31T05:03:39.502697Z","steps":["trace[1600020691] 'agreement among raft nodes before linearized reading' (duration: 324.7032ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:39.502794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:39.177703Z","time spent":"325.073632ms","remote":"127.0.0.1:58400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-03-31T05:03:39.503016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.250147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:03:39.503104Z","caller":"traceutil/trace.go:171","msg":"trace[1038120920] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1247; }","duration":"350.33849ms","start":"2026-03-31T05:03:39.152746Z","end":"2026-03-31T05:03:39.503084Z","steps":["trace[1038120920] 'agreement among raft nodes before linearized reading' (duration: 350.159534ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:39.503229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:39.152706Z","time spent":"350.506095ms","remote":"127.0.0.1:57602","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-31T05:03:43.996618Z","caller":"traceutil/trace.go:171","msg":"trace[42680283] linearizableReadLoop","detail":"{readStateIndex:1335; appliedIndex:1334; }","duration":"403.432847ms","start":"2026-03-31T05:03:43.593151Z","end":"2026-03-31T05:03:43.996584Z","steps":["trace[42680283] 'read index received' (duration: 403.349765ms)","trace[42680283] 'applied index is now lower than readState.Index' (duration: 81.562µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:03:43.996895Z","caller":"traceutil/trace.go:171","msg":"trace[584943864] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"477.755543ms","start":"2026-03-31T05:03:43.519106Z","end":"2026-03-31T05:03:43.996862Z","steps":["trace[584943864] 'process raft request' (duration: 477.318279ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:43.997051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:43.519086Z","time spent":"477.872397ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T05:03:43.997134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.941764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-31T05:03:43.997237Z","caller":"traceutil/trace.go:171","msg":"trace[661821418] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1281; }","duration":"404.106258ms","start":"2026-03-31T05:03:43.593106Z","end":"2026-03-31T05:03:43.997212Z","steps":["trace[661821418] 'agreement among raft nodes before linearized reading' (duration: 403.799689ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:43.997289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:43.593085Z","time spent":"404.193822ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-31T05:03:43.997433Z","caller":"traceutil/trace.go:171","msg":"trace[1134146324] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"364.000796ms","start":"2026-03-31T05:03:43.633414Z","end":"2026-03-31T05:03:43.997415Z","steps":["trace[1134146324] 'process raft request' (duration: 363.919873ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:43.997527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:43.633393Z","time spent":"364.083349ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T05:03:43.99773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.909453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-31T05:03:43.997733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.978405ms","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-31T05:03:43.997777Z","caller":"traceutil/trace.go:171","msg":"trace[1349740400] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1282; }","duration":"403.995355ms","start":"2026-03-31T05:03:43.593767Z","end":"2026-03-31T05:03:43.997762Z","steps":["trace[1349740400] 'agreement among raft nodes before linearized reading' (duration: 403.85047ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:43.997837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:43.593749Z","time spent":"404.071688ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-31T05:03:43.997788Z","caller":"traceutil/trace.go:171","msg":"trace[1969162964] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1282; }","duration":"404.051267ms","start":"2026-03-31T05:03:43.593719Z","end":"2026-03-31T05:03:43.99777Z","steps":["trace[1969162964] 'agreement among raft nodes before linearized reading' (duration: 403.894602ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:43.998712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:43.593709Z","time spent":"404.965146ms","remote":"127.0.0.1:57912","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-31T05:03:44.749959Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827830825754258376,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T05:03:45.401744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.117376ms","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-31T05:03:45.401892Z","caller":"traceutil/trace.go:171","msg":"trace[185232178] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1282; }","duration":"311.27252ms","start":"2026-03-31T05:03:45.090595Z","end":"2026-03-31T05:03:45.401868Z","steps":["trace[185232178] 'range keys from in-memory index tree' (duration: 311.100315ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:45.402286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.355738ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T05:03:45.402405Z","caller":"traceutil/trace.go:171","msg":"trace[927936799] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"1.400512991s","start":"2026-03-31T05:03:44.001871Z","end":"2026-03-31T05:03:45.402384Z","steps":["trace[927936799] 'process raft request' (duration: 832.972427ms)","trace[927936799] 'compare' (duration: 567.002367ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:03:45.402504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:44.001855Z","time spent":"1.400584394s","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T05:03:45.424924Z","caller":"traceutil/trace.go:171","msg":"trace[406973479] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"1.422526255s","start":"2026-03-31T05:03:44.002354Z","end":"2026-03-31T05:03:45.42488Z","steps":["trace[406973479] 'process raft request' (duration: 1.422281827s)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:03:45.424796Z","caller":"traceutil/trace.go:171","msg":"trace[1541964412] linearizableReadLoop","detail":"{readStateIndex:1339; appliedIndex:1336; }","duration":"1.175667044s","start":"2026-03-31T05:03:44.249105Z","end":"2026-03-31T05:03:45.424772Z","steps":["trace[1541964412] 'read index received' (duration: 585.761885ms)","trace[1541964412] 'applied index is now lower than readState.Index' (duration: 589.904269ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:03:45.425102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:44.002347Z","time spent":"1.422662559s","remote":"127.0.0.1:57912","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-31T05:03:45.425171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.176039005s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-31T05:03:45.425245Z","caller":"traceutil/trace.go:171","msg":"trace[1169075863] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1285; }","duration":"1.176167149s","start":"2026-03-31T05:03:44.249059Z","end":"2026-03-31T05:03:45.425226Z","steps":["trace[1169075863] 'agreement among raft nodes before linearized reading' (duration: 1.175997364s)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:03:45.424917Z","caller":"traceutil/trace.go:171","msg":"trace[1932810691] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"1.42174219s","start":"2026-03-31T05:03:44.003143Z","end":"2026-03-31T05:03:45.424886Z","steps":["trace[1932810691] 'process raft request' (duration: 1.421593896s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:45.425288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:44.249031Z","time spent":"1.176247402s","remote":"127.0.0.1:58114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-03-31T05:03:45.425437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:44.003137Z","time spent":"1.422154713s","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T05:03:45.430056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.086815304s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-31T05:03:45.43014Z","caller":"traceutil/trace.go:171","msg":"trace[1295470354] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1286; }","duration":"1.086918606s","start":"2026-03-31T05:03:44.343209Z","end":"2026-03-31T05:03:45.430127Z","steps":["trace[1295470354] 'agreement among raft nodes before linearized reading' (duration: 1.086802022s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:45.43021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:44.343198Z","time spent":"1.086969298s","remote":"127.0.0.1:57806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":5,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"info","ts":"2026-03-31T05:03:45.430225Z","caller":"traceutil/trace.go:171","msg":"trace[214238786] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"253.934038ms","start":"2026-03-31T05:03:45.176273Z","end":"2026-03-31T05:03:45.430207Z","steps":["trace[214238786] 'process raft request' (duration: 253.410141ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:45.430527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.193133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-31T05:03:45.430591Z","caller":"traceutil/trace.go:171","msg":"trace[1044738238] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1286; }","duration":"247.298796ms","start":"2026-03-31T05:03:45.183282Z","end":"2026-03-31T05:03:45.430581Z","steps":["trace[1044738238] 'agreement among raft nodes before linearized reading' (duration: 246.719707ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:45.430601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.664957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:03:45.431233Z","caller":"traceutil/trace.go:171","msg":"trace[209799384] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1286; }","duration":"278.339928ms","start":"2026-03-31T05:03:45.152883Z","end":"2026-03-31T05:03:45.431223Z","steps":["trace[209799384] 'agreement among raft nodes before linearized reading' (duration: 277.667377ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:45.43062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.107240516s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-31T05:03:45.4314Z","caller":"traceutil/trace.go:171","msg":"trace[181732057] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1286; }","duration":"1.108754795s","start":"2026-03-31T05:03:44.322637Z","end":"2026-03-31T05:03:45.431392Z","steps":["trace[181732057] 'agreement among raft nodes before linearized reading' (duration: 1.107230296s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:45.431473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:44.32262Z","time spent":"1.108832038s","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":5,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-03-31T05:03:45.430757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.109494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-31T05:03:45.432272Z","caller":"traceutil/trace.go:171","msg":"trace[426379059] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1286; }","duration":"651.56219ms","start":"2026-03-31T05:03:44.780596Z","end":"2026-03-31T05:03:45.432158Z","steps":["trace[426379059] 'agreement among raft nodes before linearized reading' (duration: 650.054772ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:45.432342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:44.780581Z","time spent":"651.746996ms","remote":"127.0.0.1:58050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"info","ts":"2026-03-31T05:03:46.983136Z","caller":"traceutil/trace.go:171","msg":"trace[247970606] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"109.848212ms","start":"2026-03-31T05:03:46.873262Z","end":"2026-03-31T05:03:46.98311Z","steps":["trace[247970606] 'process raft request' (duration: 109.688667ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:03:47.622059Z","caller":"traceutil/trace.go:171","msg":"trace[913742494] linearizableReadLoop","detail":"{readStateIndex:1360; appliedIndex:1359; }","duration":"466.853144ms","start":"2026-03-31T05:03:47.155177Z","end":"2026-03-31T05:03:47.62203Z","steps":["trace[913742494] 'read index received' (duration: 454.630894ms)","trace[913742494] 'applied index is now lower than readState.Index' (duration: 12.22049ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:03:47.622068Z","caller":"traceutil/trace.go:171","msg":"trace[785003953] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"578.339579ms","start":"2026-03-31T05:03:47.043703Z","end":"2026-03-31T05:03:47.622042Z","steps":["trace[785003953] 'process raft request' (duration: 566.119358ms)","trace[785003953] 'compare' (duration: 11.764056ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:03:47.622425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.224506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:03:47.6226Z","caller":"traceutil/trace.go:171","msg":"trace[282347518] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1305; }","duration":"467.450154ms","start":"2026-03-31T05:03:47.155133Z","end":"2026-03-31T05:03:47.622583Z","steps":["trace[282347518] 'agreement among raft nodes before linearized reading' (duration: 467.050771ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:47.622649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:47.155113Z","time spent":"467.523166ms","remote":"127.0.0.1:57602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T05:03:47.622771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:03:47.043676Z","time spent":"578.858546ms","remote":"127.0.0.1:57714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":796,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T05:03:47.639728Z","caller":"traceutil/trace.go:171","msg":"trace[590163859] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"204.445805ms","start":"2026-03-31T05:03:47.435255Z","end":"2026-03-31T05:03:47.6397Z","steps":["trace[590163859] 'process raft request' (duration: 204.081494ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:47.6398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.675688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-03-31T05:03:47.63981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.653605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-31T05:03:47.639877Z","caller":"traceutil/trace.go:171","msg":"trace[839628314] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1306; }","duration":"232.802652ms","start":"2026-03-31T05:03:47.407057Z","end":"2026-03-31T05:03:47.63986Z","steps":["trace[839628314] 'agreement among raft nodes before linearized reading' (duration: 232.504053ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:03:47.639893Z","caller":"traceutil/trace.go:171","msg":"trace[2134096240] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1306; }","duration":"200.776999ms","start":"2026-03-31T05:03:47.439099Z","end":"2026-03-31T05:03:47.639876Z","steps":["trace[2134096240] 'agreement among raft nodes before linearized reading' (duration: 200.445108ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:47.639751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.048494ms","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-31T05:03:47.640008Z","caller":"traceutil/trace.go:171","msg":"trace[794347239] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1306; }","duration":"210.352595ms","start":"2026-03-31T05:03:47.429641Z","end":"2026-03-31T05:03:47.639994Z","steps":["trace[794347239] 'agreement among raft nodes before linearized reading' (duration: 209.907451ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:03:47.64008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.531572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-31T05:03:47.640117Z","caller":"traceutil/trace.go:171","msg":"trace[1021381070] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1306; }","duration":"211.594884ms","start":"2026-03-31T05:03:47.428513Z","end":"2026-03-31T05:03:47.640108Z","steps":["trace[1021381070] 'agreement among raft nodes before linearized reading' (duration: 211.500881ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:04:01.818543Z","caller":"traceutil/trace.go:171","msg":"trace[319157015] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"145.918225ms","start":"2026-03-31T05:04:01.672596Z","end":"2026-03-31T05:04:01.818514Z","steps":["trace[319157015] 'process raft request' (duration: 145.709198ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:04:01.904216Z","caller":"traceutil/trace.go:171","msg":"trace[1377427455] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"140.904795ms","start":"2026-03-31T05:04:01.763284Z","end":"2026-03-31T05:04:01.904189Z","steps":["trace[1377427455] 'process raft request' (duration: 105.342378ms)","trace[1377427455] 'compare' (duration: 35.331089ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:04:28.314014Z","caller":"traceutil/trace.go:171","msg":"trace[1023694512] transaction","detail":"{read_only:false; response_revision:1499; number_of_response:1; }","duration":"121.483638ms","start":"2026-03-31T05:04:28.19249Z","end":"2026-03-31T05:04:28.313974Z","steps":["trace[1023694512] 'process raft request' (duration: 42.220811ms)","trace[1023694512] 'compare' (duration: 78.981078ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:04:28.314201Z","caller":"traceutil/trace.go:171","msg":"trace[1006966237] transaction","detail":"{read_only:false; response_revision:1500; number_of_response:1; }","duration":"121.182799ms","start":"2026-03-31T05:04:28.192972Z","end":"2026-03-31T05:04:28.314154Z","steps":["trace[1006966237] 'process raft request' (duration: 120.938501ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:04:32.602698Z","caller":"traceutil/trace.go:171","msg":"trace[716812861] linearizableReadLoop","detail":"{readStateIndex:1583; appliedIndex:1581; }","duration":"124.65244ms","start":"2026-03-31T05:04:32.478017Z","end":"2026-03-31T05:04:32.602669Z","steps":["trace[716812861] 'read index received' (duration: 39.577297ms)","trace[716812861] 'applied index is now lower than readState.Index' (duration: 85.074003ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:04:32.602876Z","caller":"traceutil/trace.go:171","msg":"trace[1343956927] transaction","detail":"{read_only:false; response_revision:1519; number_of_response:1; }","duration":"159.078272ms","start":"2026-03-31T05:04:32.443742Z","end":"2026-03-31T05:04:32.60282Z","steps":["trace[1343956927] 'process raft request' (duration: 130.306411ms)","trace[1343956927] 'compare' (duration: 28.368658ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:04:32.60295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.918259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-31T05:04:32.603003Z","caller":"traceutil/trace.go:171","msg":"trace[351657077] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1519; }","duration":"125.025062ms","start":"2026-03-31T05:04:32.477964Z","end":"2026-03-31T05:04:32.60299Z","steps":["trace[351657077] 'agreement among raft nodes before linearized reading' (duration: 124.818786ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:05:17.202107Z","caller":"traceutil/trace.go:171","msg":"trace[1971303257] transaction","detail":"{read_only:false; response_revision:1722; number_of_response:1; }","duration":"122.063458ms","start":"2026-03-31T05:05:17.080024Z","end":"2026-03-31T05:05:17.202088Z","steps":["trace[1971303257] 'process raft request' (duration: 121.735107ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:06:06.907997Z","caller":"traceutil/trace.go:171","msg":"trace[566305614] transaction","detail":"{read_only:false; response_revision:1948; number_of_response:1; }","duration":"115.440247ms","start":"2026-03-31T05:06:06.79254Z","end":"2026-03-31T05:06:06.90798Z","steps":["trace[566305614] 'process raft request' (duration: 69.687795ms)","trace[566305614] 'compare' (duration: 45.523214ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:06:12.4315Z","caller":"traceutil/trace.go:171","msg":"trace[1952787015] transaction","detail":"{read_only:false; response_revision:1974; number_of_response:1; }","duration":"105.566621ms","start":"2026-03-31T05:06:12.325908Z","end":"2026-03-31T05:06:12.431475Z","steps":["trace[1952787015] 'process raft request' (duration: 105.424496ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:06:47.002192Z","caller":"traceutil/trace.go:171","msg":"trace[1910217079] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"106.619628ms","start":"2026-03-31T05:06:46.895549Z","end":"2026-03-31T05:06:47.002169Z","steps":["trace[1910217079] 'process raft request' (duration: 106.501514ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:06:55.494879Z","caller":"traceutil/trace.go:171","msg":"trace[1450565877] linearizableReadLoop","detail":"{readStateIndex:2286; appliedIndex:2285; }","duration":"340.814395ms","start":"2026-03-31T05:06:55.154036Z","end":"2026-03-31T05:06:55.494851Z","steps":["trace[1450565877] 'read index received' (duration: 264.052438ms)","trace[1450565877] 'applied index is now lower than readState.Index' (duration: 76.760457ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:06:55.495211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.943387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-31T05:06:55.495262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.736871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-03-31T05:06:55.495299Z","caller":"traceutil/trace.go:171","msg":"trace[194979146] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2192; }","duration":"324.084791ms","start":"2026-03-31T05:06:55.171194Z","end":"2026-03-31T05:06:55.495279Z","steps":["trace[194979146] 'agreement among raft nodes before linearized reading' (duration: 323.845044ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:06:55.495347Z","caller":"traceutil/trace.go:171","msg":"trace[1479704683] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2192; }","duration":"153.891566ms","start":"2026-03-31T05:06:55.341438Z","end":"2026-03-31T05:06:55.49533Z","steps":["trace[1479704683] 'agreement among raft nodes before linearized reading' (duration: 153.628977ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:06:55.495353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:06:55.171151Z","time spent":"324.193494ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-31T05:06:55.495436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.678921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:06:55.4955Z","caller":"traceutil/trace.go:171","msg":"trace[1306554836] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:2192; }","duration":"239.819105ms","start":"2026-03-31T05:06:55.255662Z","end":"2026-03-31T05:06:55.495481Z","steps":["trace[1306554836] 'agreement among raft nodes before linearized reading' (duration: 239.613678ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:06:55.495809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.558848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-31T05:06:55.495802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.770455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T05:06:55.495921Z","caller":"traceutil/trace.go:171","msg":"trace[1891532813] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2192; }","duration":"341.92604ms","start":"2026-03-31T05:06:55.153983Z","end":"2026-03-31T05:06:55.495909Z","steps":["trace[1891532813] 'agreement among raft nodes before linearized reading' (duration: 340.97184ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:06:55.49597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T05:06:55.153963Z","time spent":"341.998882ms","remote":"127.0.0.1:57602","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-31T05:06:55.495902Z","caller":"traceutil/trace.go:171","msg":"trace[978325979] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2192; }","duration":"213.709242ms","start":"2026-03-31T05:06:55.282174Z","end":"2026-03-31T05:06:55.495883Z","steps":["trace[978325979] 'agreement among raft nodes before linearized reading' (duration: 213.057932ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:06:56.916955Z","caller":"traceutil/trace.go:171","msg":"trace[1614113699] transaction","detail":"{read_only:false; response_revision:2200; number_of_response:1; }","duration":"171.385264ms","start":"2026-03-31T05:06:56.745545Z","end":"2026-03-31T05:06:56.91693Z","steps":["trace[1614113699] 'process raft request' (duration: 171.232009ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:06:57.7463Z","caller":"traceutil/trace.go:171","msg":"trace[1985120743] transaction","detail":"{read_only:false; response_revision:2205; number_of_response:1; }","duration":"123.017761ms","start":"2026-03-31T05:06:57.623252Z","end":"2026-03-31T05:06:57.74627Z","steps":["trace[1985120743] 'process raft request' (duration: 122.886017ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:06:57.878026Z","caller":"traceutil/trace.go:171","msg":"trace[693725861] transaction","detail":"{read_only:false; response_revision:2207; number_of_response:1; }","duration":"125.582434ms","start":"2026-03-31T05:06:57.752418Z","end":"2026-03-31T05:06:57.878Z","steps":["trace[693725861] 'process raft request' (duration: 103.177969ms)","trace[693725861] 'compare' (duration: 22.158527ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:07:03.89634Z","caller":"traceutil/trace.go:171","msg":"trace[246803596] linearizableReadLoop","detail":"{readStateIndex:2333; appliedIndex:2332; }","duration":"221.915344ms","start":"2026-03-31T05:07:03.674397Z","end":"2026-03-31T05:07:03.896313Z","steps":["trace[246803596] 'read index received' (duration: 200.311846ms)","trace[246803596] 'applied index is now lower than readState.Index' (duration: 21.602028ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T05:07:03.896494Z","caller":"traceutil/trace.go:171","msg":"trace[1437512440] transaction","detail":"{read_only:false; response_revision:2238; number_of_response:1; }","duration":"226.718128ms","start":"2026-03-31T05:07:03.669759Z","end":"2026-03-31T05:07:03.896477Z","steps":["trace[1437512440] 'process raft request' (duration: 204.895812ms)","trace[1437512440] 'compare' (duration: 21.282108ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T05:07:03.89665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.421369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-03-31T05:07:03.896688Z","caller":"traceutil/trace.go:171","msg":"trace[1199722889] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2238; }","duration":"109.510441ms","start":"2026-03-31T05:07:03.787168Z","end":"2026-03-31T05:07:03.896678Z","steps":["trace[1199722889] 'agreement among raft nodes before linearized reading' (duration: 109.379107ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T05:07:03.896775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.381659ms","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-31T05:07:03.896797Z","caller":"traceutil/trace.go:171","msg":"trace[805447476] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2238; }","duration":"222.426631ms","start":"2026-03-31T05:07:03.674364Z","end":"2026-03-31T05:07:03.896791Z","steps":["trace[805447476] 'agreement among raft nodes before linearized reading' (duration: 222.375749ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T05:07:04.482793Z","caller":"traceutil/trace.go:171","msg":"trace[704431536] transaction","detail":"{read_only:false; response_revision:2243; number_of_response:1; }","duration":"106.170954ms","start":"2026-03-31T05:07:04.376595Z","end":"2026-03-31T05:07:04.482766Z","steps":["trace[704431536] 'process raft request' (duration: 106.02289ms)"],"step_count":1}