{"level":"warn","ts":"2026-05-12T18:44:31.635073Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-12T18:44:31.635231Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.95: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.95:2380","--initial-cluster=instance=https://199.204.45.95:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.95:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.95: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-05-12T18:44:31.635397Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-12T18:44:31.635438Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-05-12T18:44:31.635486Z","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-05-12T18:44:31.637177Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"]} {"level":"info","ts":"2026-05-12T18:44:31.637391Z","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.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.95: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-05-12T18:44:31.641938Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.137889ms"} {"level":"info","ts":"2026-05-12T18:44:31.650669Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"be773dfc027af3eb","cluster-id":"9ba5fbd7288fd5af"} {"level":"info","ts":"2026-05-12T18:44:31.650783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=()"} {"level":"info","ts":"2026-05-12T18:44:31.650823Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 0"} {"level":"info","ts":"2026-05-12T18:44:31.650834Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft be773dfc027af3eb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-12T18:44:31.650844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 1"} {"level":"info","ts":"2026-05-12T18:44:31.650905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"warn","ts":"2026-05-12T18:44:31.661604Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-12T18:44:31.663227Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-12T18:44:31.664825Z","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-05-12T18:44:31.668869Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"be773dfc027af3eb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-12T18:44:31.669025Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"be773dfc027af3eb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-12T18:44:31.670471Z","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-05-12T18:44:31.670614Z","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-05-12T18:44:31.670641Z","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-05-12T18:44:31.672284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"info","ts":"2026-05-12T18:44:31.672413Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","added-peer-id":"be773dfc027af3eb","added-peer-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-05-12T18:44:31.672953Z","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-05-12T18:44:31.673101Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-05-12T18:44:31.673175Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-05-12T18:44:31.673213Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"be773dfc027af3eb","initial-advertise-peer-urls":["https://199.204.45.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-12T18:44:31.673241Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-12T18:44:31.951584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb is starting a new election at term 1"} {"level":"info","ts":"2026-05-12T18:44:31.951716Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became pre-candidate at term 1"} {"level":"info","ts":"2026-05-12T18:44:31.951745Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgPreVoteResp from be773dfc027af3eb at term 1"} {"level":"info","ts":"2026-05-12T18:44:31.951782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became candidate at term 2"} {"level":"info","ts":"2026-05-12T18:44:31.951797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgVoteResp from be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-05-12T18:44:31.951814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became leader at term 2"} {"level":"info","ts":"2026-05-12T18:44:31.951833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: be773dfc027af3eb elected leader be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-05-12T18:44:31.953096Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"be773dfc027af3eb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.95:2379]}","request-path":"/0/members/be773dfc027af3eb/attributes","cluster-id":"9ba5fbd7288fd5af","publish-timeout":"7s"} {"level":"info","ts":"2026-05-12T18:44:31.953383Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-12T18:44:31.953408Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-12T18:44:31.953593Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T18:44:31.953618Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-12T18:44:31.953711Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-12T18:44:31.95808Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.95:2379"} {"level":"info","ts":"2026-05-12T18:44:31.958424Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T18:44:31.958661Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T18:44:31.958744Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T18:44:31.961345Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-12T18:45:01.720494Z","caller":"traceutil/trace.go:171","msg":"trace[455057825] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"462.246307ms","start":"2026-05-12T18:45:01.258167Z","end":"2026-05-12T18:45:01.720413Z","steps":["trace[455057825] 'process raft request' (duration: 461.987841ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:45:01.721102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:45:01.25814Z","time spent":"462.492532ms","remote":"127.0.0.1:38996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T18:45:01.731865Z","caller":"traceutil/trace.go:171","msg":"trace[1914959212] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"469.850919ms","start":"2026-05-12T18:45:01.261982Z","end":"2026-05-12T18:45:01.731833Z","steps":["trace[1914959212] 'process raft request' (duration: 469.730977ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:45:01.731831Z","caller":"traceutil/trace.go:171","msg":"trace[1884256274] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"469.906271ms","start":"2026-05-12T18:45:01.261898Z","end":"2026-05-12T18:45:01.731804Z","steps":["trace[1884256274] 'process raft request' (duration: 469.687436ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:45:01.731957Z","caller":"traceutil/trace.go:171","msg":"trace[636317124] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"453.832705ms","start":"2026-05-12T18:45:01.278041Z","end":"2026-05-12T18:45:01.731874Z","steps":["trace[636317124] 'process raft request' (duration: 453.719583ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:45:01.732004Z","caller":"traceutil/trace.go:171","msg":"trace[1847883903] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"453.693742ms","start":"2026-05-12T18:45:01.278261Z","end":"2026-05-12T18:45:01.731955Z","steps":["trace[1847883903] 'process raft request' (duration: 453.552359ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:45:01.732044Z","caller":"traceutil/trace.go:171","msg":"trace[1062918493] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"453.133819ms","start":"2026-05-12T18:45:01.278883Z","end":"2026-05-12T18:45:01.732017Z","steps":["trace[1062918493] 'process raft request' (duration: 452.990645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:45:01.732114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:45:01.278011Z","time spent":"454.02614ms","remote":"127.0.0.1:38996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T18:45:01.732132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:45:01.27887Z","time spent":"453.20293ms","remote":"127.0.0.1:38996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T18:45:01.732167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:45:01.278236Z","time spent":"453.845545ms","remote":"127.0.0.1:38996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T18:45:01.732084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:45:01.261976Z","time spent":"469.958462ms","remote":"127.0.0.1:38996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T18:45:01.73236Z","caller":"traceutil/trace.go:171","msg":"trace[651547287] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"447.018833ms","start":"2026-05-12T18:45:01.2853Z","end":"2026-05-12T18:45:01.732319Z","steps":["trace[651547287] 'process raft request' (duration: 446.671815ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:45:01.732386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:45:01.26187Z","time spent":"470.119775ms","remote":"127.0.0.1:38996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T18:45:01.732416Z","caller":"traceutil/trace.go:171","msg":"trace[1178575052] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"429.885732ms","start":"2026-05-12T18:45:01.302524Z","end":"2026-05-12T18:45:01.73241Z","steps":["trace[1178575052] 'process raft request' (duration: 429.673937ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:45:01.732482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:45:01.302505Z","time spent":"429.952513ms","remote":"127.0.0.1:38996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T18:45:01.732493Z","caller":"traceutil/trace.go:171","msg":"trace[1457056119] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"407.018335ms","start":"2026-05-12T18:45:01.325464Z","end":"2026-05-12T18:45:01.732482Z","steps":["trace[1457056119] 'process raft request' (duration: 406.80282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:45:01.732529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:45:01.325437Z","time spent":"407.074966ms","remote":"127.0.0.1:38996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T18:45:01.732561Z","caller":"traceutil/trace.go:171","msg":"trace[377586316] linearizableReadLoop","detail":"{readStateIndex:498; appliedIndex:487; }","duration":"332.614703ms","start":"2026-05-12T18:45:01.399925Z","end":"2026-05-12T18:45:01.73254Z","steps":["trace[377586316] 'read index received' (duration: 320.345239ms)","trace[377586316] 'applied index is now lower than readState.Index' (duration: 12.268254ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T18:45:01.732648Z","caller":"traceutil/trace.go:171","msg":"trace[88834507] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"306.656672ms","start":"2026-05-12T18:45:01.425978Z","end":"2026-05-12T18:45:01.732634Z","steps":["trace[88834507] 'process raft request' (duration: 306.486478ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:45:01.732274Z","caller":"traceutil/trace.go:171","msg":"trace[2007813608] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"452.949125ms","start":"2026-05-12T18:45:01.279182Z","end":"2026-05-12T18:45:01.732131Z","steps":["trace[2007813608] 'process raft request' (duration: 452.73804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:45:01.732542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:45:01.285265Z","time spent":"447.136135ms","remote":"127.0.0.1:38996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T18:45:01.732735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.807438ms","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-05-12T18:45:01.732806Z","caller":"traceutil/trace.go:171","msg":"trace[663464676] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:486; }","duration":"332.922471ms","start":"2026-05-12T18:45:01.399876Z","end":"2026-05-12T18:45:01.732798Z","steps":["trace[663464676] 'agreement among raft nodes before linearized reading' (duration: 332.770397ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:45:01.732828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:45:01.39986Z","time spent":"332.962972ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-12T18:45:01.732713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:45:01.425948Z","time spent":"306.738004ms","remote":"127.0.0.1:38996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T18:45:01.732364Z","caller":"traceutil/trace.go:171","msg":"trace[579366734] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"430.21525ms","start":"2026-05-12T18:45:01.302135Z","end":"2026-05-12T18:45:01.73235Z","steps":["trace[579366734] 'process raft request' (duration: 429.949213ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:45:01.733029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:45:01.27917Z","time spent":"453.799165ms","remote":"127.0.0.1:38996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T18:45:01.733045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:45:01.302113Z","time spent":"430.793984ms","remote":"127.0.0.1:38996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T18:45:01.740175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.035903ms","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-05-12T18:45:01.740258Z","caller":"traceutil/trace.go:171","msg":"trace[500936616] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:488; }","duration":"163.138335ms","start":"2026-05-12T18:45:01.577091Z","end":"2026-05-12T18:45:01.740229Z","steps":["trace[500936616] 'agreement among raft nodes before linearized reading' (duration: 162.973091ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:45:04.346667Z","caller":"traceutil/trace.go:171","msg":"trace[922906964] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"161.955007ms","start":"2026-05-12T18:45:04.184685Z","end":"2026-05-12T18:45:04.34664Z","steps":["trace[922906964] 'process raft request' (duration: 161.805034ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:45:04.804276Z","caller":"traceutil/trace.go:171","msg":"trace[1742320803] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"460.32144ms","start":"2026-05-12T18:45:04.343929Z","end":"2026-05-12T18:45:04.804251Z","steps":["trace[1742320803] 'process raft request' (duration: 460.055613ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:45:04.804721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:45:04.343904Z","time spent":"460.495164ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T18:45:04.815515Z","caller":"traceutil/trace.go:171","msg":"trace[1314735200] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"465.034742ms","start":"2026-05-12T18:45:04.350452Z","end":"2026-05-12T18:45:04.815487Z","steps":["trace[1314735200] 'process raft request' (duration: 464.907619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:45:04.815657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:45:04.350445Z","time spent":"465.146695ms","remote":"127.0.0.1:39020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1749,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T18:45:04.815665Z","caller":"traceutil/trace.go:171","msg":"trace[1334316888] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"464.394417ms","start":"2026-05-12T18:45:04.35124Z","end":"2026-05-12T18:45:04.815634Z","steps":["trace[1334316888] 'process raft request' (duration: 464.191732ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:45:04.815771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:45:04.351227Z","time spent":"464.49139ms","remote":"127.0.0.1:39118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":182,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T18:45:06.496355Z","caller":"traceutil/trace.go:171","msg":"trace[361290912] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"179.577639ms","start":"2026-05-12T18:45:06.316751Z","end":"2026-05-12T18:45:06.496329Z","steps":["trace[361290912] 'process raft request' (duration: 178.931174ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:45:08.735658Z","caller":"traceutil/trace.go:171","msg":"trace[1041297565] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"328.494625ms","start":"2026-05-12T18:45:08.407123Z","end":"2026-05-12T18:45:08.735617Z","steps":["trace[1041297565] 'process raft request' (duration: 327.823049ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:45:08.735842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:45:08.407092Z","time spent":"328.656528ms","remote":"127.0.0.1:39018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T18:45:09.156658Z","caller":"traceutil/trace.go:171","msg":"trace[856470199] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"404.541406ms","start":"2026-05-12T18:45:08.752092Z","end":"2026-05-12T18:45:09.156634Z","steps":["trace[856470199] 'process raft request' (duration: 404.352751ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:45:09.156784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:45:08.752063Z","time spent":"404.655678ms","remote":"127.0.0.1:39098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T18:45:10.47126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.099965ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-12T18:45:10.471659Z","caller":"traceutil/trace.go:171","msg":"trace[496861794] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"1.673859345s","start":"2026-05-12T18:45:08.797767Z","end":"2026-05-12T18:45:10.471626Z","steps":["trace[496861794] 'process raft request' (duration: 1.345109614s)","trace[496861794] 'compare' (duration: 327.805358ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T18:45:10.471665Z","caller":"traceutil/trace.go:171","msg":"trace[1100929891] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:608; }","duration":"679.358735ms","start":"2026-05-12T18:45:09.792275Z","end":"2026-05-12T18:45:10.471633Z","steps":["trace[1100929891] 'read index received' (duration: 350.570853ms)","trace[1100929891] 'applied index is now lower than readState.Index' (duration: 328.785032ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T18:45:10.471794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:45:08.797743Z","time spent":"1.673982838s","remote":"127.0.0.1:39144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T18:45:11.186666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.296145ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-12T18:45:11.18699Z","caller":"traceutil/trace.go:171","msg":"trace[1028116932] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:609; }","duration":"715.231234ms","start":"2026-05-12T18:45:10.471735Z","end":"2026-05-12T18:45:11.186966Z","steps":["trace[1028116932] 'read index received' (duration: 72.992µs)","trace[1028116932] 'applied index is now lower than readState.Index' (duration: 715.156042ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T18:45:11.187193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.288334755s","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-05-12T18:45:11.187262Z","caller":"traceutil/trace.go:171","msg":"trace[631926880] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:590; }","duration":"1.288404467s","start":"2026-05-12T18:45:09.898839Z","end":"2026-05-12T18:45:11.187244Z","steps":["trace[631926880] 'agreement among raft nodes before linearized reading' (duration: 1.288246863s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:45:11.18731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:45:09.89879Z","time spent":"1.288506929s","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-12T18:45:11.187346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.394683441s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-12T18:45:11.187367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.394823794s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T18:45:11.187417Z","caller":"traceutil/trace.go:171","msg":"trace[604933938] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:590; }","duration":"1.395165832s","start":"2026-05-12T18:45:09.792228Z","end":"2026-05-12T18:45:11.187394Z","steps":["trace[604933938] 'agreement among raft nodes before linearized reading' (duration: 679.534839ms)","trace[604933938] 'range keys from in-memory index tree' (duration: 715.06606ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T18:45:11.187482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:45:09.792206Z","time spent":"1.395260945s","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-12T18:45:11.187432Z","caller":"traceutil/trace.go:171","msg":"trace[1674263553] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:590; }","duration":"1.394927877s","start":"2026-05-12T18:45:09.792484Z","end":"2026-05-12T18:45:11.187412Z","steps":["trace[1674263553] 'agreement among raft nodes before linearized reading' (duration: 1.394814864s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:45:11.187579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.388896992s","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-05-12T18:45:11.187598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:45:09.792461Z","time spent":"1.395125142s","remote":"127.0.0.1:44962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-12T18:45:11.187613Z","caller":"traceutil/trace.go:171","msg":"trace[1069480320] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:590; }","duration":"1.388980594s","start":"2026-05-12T18:45:09.798623Z","end":"2026-05-12T18:45:11.187603Z","steps":["trace[1069480320] 'agreement among raft nodes before linearized reading' (duration: 1.388830751s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:45:11.187645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:45:09.798562Z","time spent":"1.389074066s","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-12T18:45:11.187634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.059412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-12T18:45:11.187691Z","caller":"traceutil/trace.go:171","msg":"trace[1062687944] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:590; }","duration":"713.142954ms","start":"2026-05-12T18:45:10.474533Z","end":"2026-05-12T18:45:11.187676Z","steps":["trace[1062687944] 'agreement among raft nodes before linearized reading' (duration: 713.046982ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:45:11.187734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:45:10.474517Z","time spent":"713.205095ms","remote":"127.0.0.1:39118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-05-12T18:45:11.187828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.395156752s","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-05-12T18:45:11.187967Z","caller":"traceutil/trace.go:171","msg":"trace[853243092] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:590; }","duration":"1.395322406s","start":"2026-05-12T18:45:09.792596Z","end":"2026-05-12T18:45:11.187918Z","steps":["trace[853243092] 'agreement among raft nodes before linearized reading' (duration: 1.394892596s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:45:11.188009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:45:09.792586Z","time spent":"1.395413849s","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-12T18:45:13.094894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.047497ms","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-05-12T18:45:13.094934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.001898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T18:45:13.094971Z","caller":"traceutil/trace.go:171","msg":"trace[259300989] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:624; }","duration":"151.167909ms","start":"2026-05-12T18:45:12.943787Z","end":"2026-05-12T18:45:13.094955Z","steps":["trace[259300989] 'range keys from in-memory index tree' (duration: 150.976334ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:45:13.09499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.468802ms","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":"info","ts":"2026-05-12T18:45:13.095Z","caller":"traceutil/trace.go:171","msg":"trace[1627958722] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:624; }","duration":"177.07774ms","start":"2026-05-12T18:45:12.917901Z","end":"2026-05-12T18:45:13.094979Z","steps":["trace[1627958722] 'count revisions from in-memory index tree' (duration: 176.929246ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:45:13.09503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.044699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T18:45:13.095109Z","caller":"traceutil/trace.go:171","msg":"trace[535254447] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:624; }","duration":"177.150891ms","start":"2026-05-12T18:45:12.917941Z","end":"2026-05-12T18:45:13.095092Z","steps":["trace[535254447] 'range keys from in-memory index tree' (duration: 177.010778ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:45:13.095115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.312923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T18:45:13.09516Z","caller":"traceutil/trace.go:171","msg":"trace[12709246] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:624; }","duration":"108.371465ms","start":"2026-05-12T18:45:12.986775Z","end":"2026-05-12T18:45:13.095146Z","steps":["trace[12709246] 'range keys from in-memory index tree' (duration: 108.282092ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:45:13.095059Z","caller":"traceutil/trace.go:171","msg":"trace[1047265426] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:624; }","duration":"150.542914ms","start":"2026-05-12T18:45:12.944492Z","end":"2026-05-12T18:45:13.095035Z","steps":["trace[1047265426] 'range keys from in-memory index tree' (duration: 150.434962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:45:13.09537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.550185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T18:45:13.09541Z","caller":"traceutil/trace.go:171","msg":"trace[867194812] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:624; }","duration":"107.615207ms","start":"2026-05-12T18:45:12.987784Z","end":"2026-05-12T18:45:13.095399Z","steps":["trace[867194812] 'range keys from in-memory index tree' (duration: 107.490233ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:45:13.094884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.278384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T18:45:13.095449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.487547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T18:45:13.095536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.17404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T18:45:13.095544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.879468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T18:45:13.09556Z","caller":"traceutil/trace.go:171","msg":"trace[1947849379] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:624; }","duration":"245.21069ms","start":"2026-05-12T18:45:12.850343Z","end":"2026-05-12T18:45:13.095554Z","steps":["trace[1947849379] 'range keys from in-memory index tree' (duration: 245.14862ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:45:13.095581Z","caller":"traceutil/trace.go:171","msg":"trace[898091797] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:624; }","duration":"192.922559ms","start":"2026-05-12T18:45:12.90265Z","end":"2026-05-12T18:45:13.095573Z","steps":["trace[898091797] 'range keys from in-memory index tree' (duration: 192.860757ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:45:13.095542Z","caller":"traceutil/trace.go:171","msg":"trace[1581895086] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:624; }","duration":"108.58887ms","start":"2026-05-12T18:45:12.98694Z","end":"2026-05-12T18:45:13.095529Z","steps":["trace[1581895086] 'count revisions from in-memory index tree' (duration: 108.428355ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:45:13.095543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.563224ms","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-05-12T18:45:13.095468Z","caller":"traceutil/trace.go:171","msg":"trace[1887763350] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:624; }","duration":"177.900799ms","start":"2026-05-12T18:45:12.917552Z","end":"2026-05-12T18:45:13.095453Z","steps":["trace[1887763350] 'range keys from in-memory index tree' (duration: 177.239783ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:45:13.095616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.79751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T18:45:13.095428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.536388ms","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-05-12T18:45:13.095683Z","caller":"traceutil/trace.go:171","msg":"trace[124764873] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:624; }","duration":"193.703757ms","start":"2026-05-12T18:45:12.901936Z","end":"2026-05-12T18:45:13.09564Z","steps":["trace[124764873] 'count revisions from in-memory index tree' (duration: 193.530443ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:45:13.095693Z","caller":"traceutil/trace.go:171","msg":"trace[1073745039] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:624; }","duration":"193.914942ms","start":"2026-05-12T18:45:12.901761Z","end":"2026-05-12T18:45:13.095676Z","steps":["trace[1073745039] 'range keys from in-memory index tree' (duration: 193.740389ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:45:13.095705Z","caller":"traceutil/trace.go:171","msg":"trace[2034767414] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:624; }","duration":"151.941158ms","start":"2026-05-12T18:45:12.943753Z","end":"2026-05-12T18:45:13.095694Z","steps":["trace[2034767414] 'count revisions from in-memory index tree' (duration: 151.479647ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:45:13.095493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.18191ms","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-05-12T18:45:13.09577Z","caller":"traceutil/trace.go:171","msg":"trace[2037018913] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:624; }","duration":"245.481417ms","start":"2026-05-12T18:45:12.85028Z","end":"2026-05-12T18:45:13.095761Z","steps":["trace[2037018913] 'count revisions from in-memory index tree' (duration: 245.095528ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:45:13.099419Z","caller":"traceutil/trace.go:171","msg":"trace[1874222291] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"105.621948ms","start":"2026-05-12T18:45:12.993778Z","end":"2026-05-12T18:45:13.0994Z","steps":["trace[1874222291] 'process raft request' (duration: 105.325071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:45:27.88856Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576315820303526240,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-12T18:45:28.016273Z","caller":"traceutil/trace.go:171","msg":"trace[1337910533] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"702.323143ms","start":"2026-05-12T18:45:27.313919Z","end":"2026-05-12T18:45:28.016243Z","steps":["trace[1337910533] 'process raft request' (duration: 702.17415ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:45:28.0165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:45:27.313899Z","time spent":"702.446116ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T18:45:28.281951Z","caller":"traceutil/trace.go:171","msg":"trace[2120298658] linearizableReadLoop","detail":"{readStateIndex:780; appliedIndex:778; }","duration":"894.142615ms","start":"2026-05-12T18:45:27.387786Z","end":"2026-05-12T18:45:28.281928Z","steps":["trace[2120298658] 'read index received' (duration: 628.296351ms)","trace[2120298658] 'applied index is now lower than readState.Index' (duration: 265.845394ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T18:45:28.282147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.648014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T18:45:28.282184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"893.000596ms","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-05-12T18:45:28.282227Z","caller":"traceutil/trace.go:171","msg":"trace[1806045825] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:755; }","duration":"893.090608ms","start":"2026-05-12T18:45:27.389126Z","end":"2026-05-12T18:45:28.282216Z","steps":["trace[1806045825] 'agreement among raft nodes before linearized reading' (duration: 892.936674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:45:28.282231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.855162ms","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-05-12T18:45:28.282232Z","caller":"traceutil/trace.go:171","msg":"trace[2003624454] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:755; }","duration":"489.743846ms","start":"2026-05-12T18:45:27.792469Z","end":"2026-05-12T18:45:28.282213Z","steps":["trace[2003624454] 'agreement among raft nodes before linearized reading' (duration: 489.607283ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:45:28.282264Z","caller":"traceutil/trace.go:171","msg":"trace[949970588] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:755; }","duration":"892.930894ms","start":"2026-05-12T18:45:27.389325Z","end":"2026-05-12T18:45:28.282256Z","steps":["trace[949970588] 'agreement among raft nodes before linearized reading' (duration: 892.818421ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:45:28.282273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:45:27.389112Z","time spent":"893.149899ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-12T18:45:28.282285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:45:27.389297Z","time spent":"892.983815ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-12T18:45:28.28228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:45:27.79241Z","time spent":"489.86146ms","remote":"127.0.0.1:44962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-12T18:45:28.282337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.075623ms","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-05-12T18:45:28.282444Z","caller":"traceutil/trace.go:171","msg":"trace[919218132] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:755; }","duration":"892.212566ms","start":"2026-05-12T18:45:27.390209Z","end":"2026-05-12T18:45:28.282422Z","steps":["trace[919218132] 'agreement among raft nodes before linearized reading' (duration: 891.932268ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:45:28.282501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:45:27.390198Z","time spent":"892.289068ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-12T18:45:28.282059Z","caller":"traceutil/trace.go:171","msg":"trace[269179320] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"456.129496ms","start":"2026-05-12T18:45:27.825877Z","end":"2026-05-12T18:45:28.282006Z","steps":["trace[269179320] 'process raft request' (duration: 455.90174ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:45:28.282783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:45:27.825841Z","time spent":"456.868555ms","remote":"127.0.0.1:39144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T18:45:28.282843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"895.029837ms","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-05-12T18:45:28.282924Z","caller":"traceutil/trace.go:171","msg":"trace[625428743] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:755; }","duration":"895.12997ms","start":"2026-05-12T18:45:27.387774Z","end":"2026-05-12T18:45:28.282904Z","steps":["trace[625428743] 'agreement among raft nodes before linearized reading' (duration: 894.851223ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:45:28.282973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:45:27.387731Z","time spent":"895.226432ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-12T18:45:28.931988Z","caller":"traceutil/trace.go:171","msg":"trace[31491719] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"108.929895ms","start":"2026-05-12T18:45:28.823032Z","end":"2026-05-12T18:45:28.931962Z","steps":["trace[31491719] 'process raft request' (duration: 108.766131ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:45:30.255342Z","caller":"traceutil/trace.go:171","msg":"trace[754286123] transaction","detail":"{read_only:false; number_of_response:1; response_revision:763; }","duration":"287.865821ms","start":"2026-05-12T18:45:29.967451Z","end":"2026-05-12T18:45:30.255316Z","steps":["trace[754286123] 'process raft request' (duration: 287.729667ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:45:34.936244Z","caller":"traceutil/trace.go:171","msg":"trace[1344597411] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"182.432535ms","start":"2026-05-12T18:45:34.753785Z","end":"2026-05-12T18:45:34.936217Z","steps":["trace[1344597411] 'process raft request' (duration: 182.303041ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:45:34.936278Z","caller":"traceutil/trace.go:171","msg":"trace[380684232] linearizableReadLoop","detail":"{readStateIndex:848; appliedIndex:848; }","duration":"144.415072ms","start":"2026-05-12T18:45:34.791828Z","end":"2026-05-12T18:45:34.936243Z","steps":["trace[380684232] 'read index received' (duration: 144.395442ms)","trace[380684232] 'applied index is now lower than readState.Index' (duration: 16.87µs)"],"step_count":2} {"level":"warn","ts":"2026-05-12T18:45:34.936436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.587907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T18:45:34.936475Z","caller":"traceutil/trace.go:171","msg":"trace[103326433] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:822; }","duration":"144.69387ms","start":"2026-05-12T18:45:34.791771Z","end":"2026-05-12T18:45:34.936465Z","steps":["trace[103326433] 'agreement among raft nodes before linearized reading' (duration: 144.583697ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:45:35.495698Z","caller":"traceutil/trace.go:171","msg":"trace[429477814] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"404.713506ms","start":"2026-05-12T18:45:35.090658Z","end":"2026-05-12T18:45:35.495371Z","steps":["trace[429477814] 'process raft request' (duration: 404.08808ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:45:35.495881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:45:35.09064Z","time spent":"405.149957ms","remote":"127.0.0.1:39090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4793,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T18:45:35.643679Z","caller":"traceutil/trace.go:171","msg":"trace[853198840] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"141.719754ms","start":"2026-05-12T18:45:35.501933Z","end":"2026-05-12T18:45:35.643653Z","steps":["trace[853198840] 'process raft request' (duration: 139.457187ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:45:35.653387Z","caller":"traceutil/trace.go:171","msg":"trace[905199029] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"149.004009ms","start":"2026-05-12T18:45:35.504365Z","end":"2026-05-12T18:45:35.653369Z","steps":["trace[905199029] 'process raft request' (duration: 148.955267ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:45:35.653407Z","caller":"traceutil/trace.go:171","msg":"trace[1079472799] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"150.419764ms","start":"2026-05-12T18:45:35.502969Z","end":"2026-05-12T18:45:35.653389Z","steps":["trace[1079472799] 'process raft request' (duration: 150.25735ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:45:37.070276Z","caller":"traceutil/trace.go:171","msg":"trace[272552410] linearizableReadLoop","detail":"{readStateIndex:874; appliedIndex:873; }","duration":"128.878579ms","start":"2026-05-12T18:45:36.941367Z","end":"2026-05-12T18:45:37.070246Z","steps":["trace[272552410] 'read index received' (duration: 128.724615ms)","trace[272552410] 'applied index is now lower than readState.Index' (duration: 152.874µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T18:45:37.07035Z","caller":"traceutil/trace.go:171","msg":"trace[2032088041] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"212.835782ms","start":"2026-05-12T18:45:36.857462Z","end":"2026-05-12T18:45:37.070297Z","steps":["trace[2032088041] 'process raft request' (duration: 212.635197ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:45:37.070492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.112415ms","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-05-12T18:45:37.070541Z","caller":"traceutil/trace.go:171","msg":"trace[681758417] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:846; }","duration":"129.176196ms","start":"2026-05-12T18:45:36.941352Z","end":"2026-05-12T18:45:37.070528Z","steps":["trace[681758417] 'agreement among raft nodes before linearized reading' (duration: 129.004732ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:45:38.583381Z","caller":"traceutil/trace.go:171","msg":"trace[972181893] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"162.300435ms","start":"2026-05-12T18:45:38.421055Z","end":"2026-05-12T18:45:38.583356Z","steps":["trace[972181893] 'process raft request' (duration: 162.209963ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:45:38.583563Z","caller":"traceutil/trace.go:171","msg":"trace[1887426879] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"162.748656ms","start":"2026-05-12T18:45:38.420803Z","end":"2026-05-12T18:45:38.583552Z","steps":["trace[1887426879] 'process raft request' (duration: 94.480279ms)","trace[1887426879] 'compare' (duration: 67.846416ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T18:45:38.583572Z","caller":"traceutil/trace.go:171","msg":"trace[1911947821] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"161.982927ms","start":"2026-05-12T18:45:38.421552Z","end":"2026-05-12T18:45:38.583535Z","steps":["trace[1911947821] 'process raft request' (duration: 161.766761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:45:44.880238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.765892ms","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":"info","ts":"2026-05-12T18:45:44.880338Z","caller":"traceutil/trace.go:171","msg":"trace[663097966] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:905; }","duration":"110.942477ms","start":"2026-05-12T18:45:44.769375Z","end":"2026-05-12T18:45:44.880318Z","steps":["trace[663097966] 'count revisions from in-memory index tree' (duration: 110.629028ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:45:59.439419Z","caller":"traceutil/trace.go:171","msg":"trace[652751098] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"194.12106ms","start":"2026-05-12T18:45:59.245272Z","end":"2026-05-12T18:45:59.439393Z","steps":["trace[652751098] 'process raft request' (duration: 134.983754ms)","trace[652751098] 'compare' (duration: 58.815907ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T18:46:00.750368Z","caller":"traceutil/trace.go:171","msg":"trace[632577074] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"110.945806ms","start":"2026-05-12T18:46:00.639402Z","end":"2026-05-12T18:46:00.750348Z","steps":["trace[632577074] 'process raft request' (duration: 110.778302ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:46:01.58558Z","caller":"traceutil/trace.go:171","msg":"trace[1362092793] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"138.313648ms","start":"2026-05-12T18:46:01.447243Z","end":"2026-05-12T18:46:01.585557Z","steps":["trace[1362092793] 'process raft request' (duration: 138.155054ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:46:03.479308Z","caller":"traceutil/trace.go:171","msg":"trace[845294136] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"222.864007ms","start":"2026-05-12T18:46:03.256425Z","end":"2026-05-12T18:46:03.479289Z","steps":["trace[845294136] 'process raft request' (duration: 222.707353ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:46:04.927479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.337359915s","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-05-12T18:46:04.927566Z","caller":"traceutil/trace.go:171","msg":"trace[1726672123] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:970; }","duration":"1.337456357s","start":"2026-05-12T18:46:03.590091Z","end":"2026-05-12T18:46:04.927548Z","steps":["trace[1726672123] 'range keys from in-memory index tree' (duration: 1.33718817s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:46:04.927607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:46:03.59007Z","time spent":"1.337527128s","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-12T18:46:04.927977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.987668ms","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-05-12T18:46:04.928048Z","caller":"traceutil/trace.go:171","msg":"trace[238648468] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:970; }","duration":"113.100631ms","start":"2026-05-12T18:46:04.814933Z","end":"2026-05-12T18:46:04.928033Z","steps":["trace[238648468] 'range keys from in-memory index tree' (duration: 112.815794ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:46:04.928357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.139168152s","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-05-12T18:46:04.928468Z","caller":"traceutil/trace.go:171","msg":"trace[1438877158] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:970; }","duration":"1.139281115s","start":"2026-05-12T18:46:03.789165Z","end":"2026-05-12T18:46:04.928446Z","steps":["trace[1438877158] 'range keys from in-memory index tree' (duration: 1.139154842s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:46:04.928522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.197428ms","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-05-12T18:46:04.928545Z","caller":"traceutil/trace.go:171","msg":"trace[196613372] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:970; }","duration":"163.263509ms","start":"2026-05-12T18:46:04.765275Z","end":"2026-05-12T18:46:04.928538Z","steps":["trace[196613372] 'range keys from in-memory index tree' (duration: 163.051004ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:46:04.928667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.1351418s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T18:46:04.928698Z","caller":"traceutil/trace.go:171","msg":"trace[1851848954] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:970; }","duration":"1.135231702s","start":"2026-05-12T18:46:03.793457Z","end":"2026-05-12T18:46:04.928688Z","steps":["trace[1851848954] 'range keys from in-memory index tree' (duration: 1.135022447s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:46:04.928729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:46:03.793435Z","time spent":"1.135286094s","remote":"127.0.0.1:44962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-12T18:46:23.344187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.196499ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T18:46:23.344309Z","caller":"traceutil/trace.go:171","msg":"trace[666508499] linearizableReadLoop","detail":"{readStateIndex:1071; appliedIndex:1069; }","duration":"300.667905ms","start":"2026-05-12T18:46:23.043625Z","end":"2026-05-12T18:46:23.344293Z","steps":["trace[666508499] 'read index received' (duration: 27.449204ms)","trace[666508499] 'applied index is now lower than readState.Index' (duration: 273.217501ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T18:46:23.34437Z","caller":"traceutil/trace.go:171","msg":"trace[405327394] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"314.113425ms","start":"2026-05-12T18:46:23.030218Z","end":"2026-05-12T18:46:23.344332Z","steps":["trace[405327394] 'process raft request' (duration: 114.401373ms)","trace[405327394] 'compare' (duration: 199.039745ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T18:46:23.344489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.86849ms","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-05-12T18:46:23.34452Z","caller":"traceutil/trace.go:171","msg":"trace[523676519] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1032; }","duration":"300.936621ms","start":"2026-05-12T18:46:23.043572Z","end":"2026-05-12T18:46:23.344509Z","steps":["trace[523676519] 'agreement among raft nodes before linearized reading' (duration: 300.818208ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:46:23.344552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:46:23.043554Z","time spent":"300.990333ms","remote":"127.0.0.1:39166","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-05-12T18:46:23.344551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.873815ms","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":"warn","ts":"2026-05-12T18:46:23.344558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:46:23.030203Z","time spent":"314.248808ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T18:46:23.344588Z","caller":"traceutil/trace.go:171","msg":"trace[1274808918] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1032; }","duration":"127.959497ms","start":"2026-05-12T18:46:23.216619Z","end":"2026-05-12T18:46:23.344578Z","steps":["trace[1274808918] 'agreement among raft nodes before linearized reading' (duration: 127.832754ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:46:23.344583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.474714ms","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-05-12T18:46:23.344652Z","caller":"traceutil/trace.go:171","msg":"trace[278118617] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1032; }","duration":"299.584827ms","start":"2026-05-12T18:46:23.045051Z","end":"2026-05-12T18:46:23.344636Z","steps":["trace[278118617] 'agreement among raft nodes before linearized reading' (duration: 299.375402ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:46:23.344693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.063478ms","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-05-12T18:46:23.344723Z","caller":"traceutil/trace.go:171","msg":"trace[1253869589] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1032; }","duration":"294.119859ms","start":"2026-05-12T18:46:23.050595Z","end":"2026-05-12T18:46:23.344715Z","steps":["trace[1253869589] 'agreement among raft nodes before linearized reading' (duration: 294.028667ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:46:23.72318Z","caller":"traceutil/trace.go:171","msg":"trace[295642980] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"374.962692ms","start":"2026-05-12T18:46:23.348182Z","end":"2026-05-12T18:46:23.723145Z","steps":["trace[295642980] 'process raft request' (duration: 360.23863ms)","trace[295642980] 'compare' (duration: 14.391034ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T18:46:23.723355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:46:23.348166Z","time spent":"375.100145ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T18:46:23.723485Z","caller":"traceutil/trace.go:171","msg":"trace[1531812744] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"374.807159ms","start":"2026-05-12T18:46:23.348668Z","end":"2026-05-12T18:46:23.723475Z","steps":["trace[1531812744] 'process raft request' (duration: 374.521792ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:46:23.723535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:46:23.348628Z","time spent":"374.881091ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T18:46:23.723744Z","caller":"traceutil/trace.go:171","msg":"trace[1709551243] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"374.762227ms","start":"2026-05-12T18:46:23.34897Z","end":"2026-05-12T18:46:23.723733Z","steps":["trace[1709551243] 'process raft request' (duration: 374.292215ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:46:23.723795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:46:23.34896Z","time spent":"374.807578ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T18:46:23.723876Z","caller":"traceutil/trace.go:171","msg":"trace[997915452] linearizableReadLoop","detail":"{readStateIndex:1074; appliedIndex:1071; }","duration":"155.622756ms","start":"2026-05-12T18:46:23.568023Z","end":"2026-05-12T18:46:23.723646Z","steps":["trace[997915452] 'read index received' (duration: 140.412301ms)","trace[997915452] 'applied index is now lower than readState.Index' (duration: 15.209775ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T18:46:23.724038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.064907ms","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-05-12T18:46:23.724099Z","caller":"traceutil/trace.go:171","msg":"trace[1698745198] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1036; }","duration":"156.161709ms","start":"2026-05-12T18:46:23.567927Z","end":"2026-05-12T18:46:23.724089Z","steps":["trace[1698745198] 'agreement among raft nodes before linearized reading' (duration: 155.971705ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:46:28.233853Z","caller":"traceutil/trace.go:171","msg":"trace[1010766069] linearizableReadLoop","detail":"{readStateIndex:1087; appliedIndex:1086; }","duration":"494.754013ms","start":"2026-05-12T18:46:27.739061Z","end":"2026-05-12T18:46:28.233815Z","steps":["trace[1010766069] 'read index received' (duration: 462.655031ms)","trace[1010766069] 'applied index is now lower than readState.Index' (duration: 32.097312ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T18:46:28.234044Z","caller":"traceutil/trace.go:171","msg":"trace[1578741402] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"501.308349ms","start":"2026-05-12T18:46:27.732706Z","end":"2026-05-12T18:46:28.234014Z","steps":["trace[1578741402] 'process raft request' (duration: 469.086214ms)","trace[1578741402] 'compare' (duration: 31.346363ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T18:46:28.234461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:46:27.732682Z","time spent":"501.684998ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T18:46:28.234575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.729353ms","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-05-12T18:46:28.234644Z","caller":"traceutil/trace.go:171","msg":"trace[1137316157] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1047; }","duration":"494.809685ms","start":"2026-05-12T18:46:27.739819Z","end":"2026-05-12T18:46:28.234629Z","steps":["trace[1137316157] 'agreement among raft nodes before linearized reading' (duration: 494.662361ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:46:28.234694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:46:27.739815Z","time spent":"494.868916ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-12T18:46:28.23467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.838875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T18:46:28.234743Z","caller":"traceutil/trace.go:171","msg":"trace[189240037] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1047; }","duration":"441.956458ms","start":"2026-05-12T18:46:27.79277Z","end":"2026-05-12T18:46:28.234727Z","steps":["trace[189240037] 'agreement among raft nodes before linearized reading' (duration: 441.844596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:46:28.234749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.152705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-12T18:46:28.234298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.217605ms","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-05-12T18:46:28.234793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:46:27.79275Z","time spent":"442.03479ms","remote":"127.0.0.1:44962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-12T18:46:28.234813Z","caller":"traceutil/trace.go:171","msg":"trace[632648196] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1047; }","duration":"495.743488ms","start":"2026-05-12T18:46:27.739057Z","end":"2026-05-12T18:46:28.2348Z","steps":["trace[632648196] 'agreement among raft nodes before linearized reading' (duration: 495.03512ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:46:28.234795Z","caller":"traceutil/trace.go:171","msg":"trace[278000345] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1047; }","duration":"329.253708ms","start":"2026-05-12T18:46:27.90553Z","end":"2026-05-12T18:46:28.234784Z","steps":["trace[278000345] 'agreement among raft nodes before linearized reading' (duration: 329.115434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:46:28.234863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:46:27.90548Z","time spent":"329.373871ms","remote":"127.0.0.1:39246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":53,"response size":31,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-05-12T18:46:28.234862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.774915ms","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-05-12T18:46:28.234859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:46:27.73903Z","time spent":"495.81827ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-12T18:46:28.234987Z","caller":"traceutil/trace.go:171","msg":"trace[62223748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1047; }","duration":"494.837505ms","start":"2026-05-12T18:46:27.740047Z","end":"2026-05-12T18:46:28.234885Z","steps":["trace[62223748] 'agreement among raft nodes before linearized reading' (duration: 494.567989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:46:28.235025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:46:27.740024Z","time spent":"494.98847ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-12T18:46:28.464321Z","caller":"traceutil/trace.go:171","msg":"trace[1007361438] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"225.33344ms","start":"2026-05-12T18:46:28.238955Z","end":"2026-05-12T18:46:28.464288Z","steps":["trace[1007361438] 'process raft request' (duration: 148.808524ms)","trace[1007361438] 'compare' (duration: 76.30896ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T18:46:28.465098Z","caller":"traceutil/trace.go:171","msg":"trace[1974678987] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"225.656667ms","start":"2026-05-12T18:46:28.23941Z","end":"2026-05-12T18:46:28.465067Z","steps":["trace[1974678987] 'process raft request' (duration: 224.822856ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:46:29.221689Z","caller":"traceutil/trace.go:171","msg":"trace[1382749942] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"127.941436ms","start":"2026-05-12T18:46:29.093729Z","end":"2026-05-12T18:46:29.221671Z","steps":["trace[1382749942] 'process raft request' (duration: 127.823533ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:46:29.6546Z","caller":"traceutil/trace.go:171","msg":"trace[389574836] linearizableReadLoop","detail":"{readStateIndex:1092; appliedIndex:1091; }","duration":"260.775416ms","start":"2026-05-12T18:46:29.393791Z","end":"2026-05-12T18:46:29.654567Z","steps":["trace[389574836] 'read index received' (duration: 260.56095ms)","trace[389574836] 'applied index is now lower than readState.Index' (duration: 212.966µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T18:46:29.654921Z","caller":"traceutil/trace.go:171","msg":"trace[598722010] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"422.089545ms","start":"2026-05-12T18:46:29.232801Z","end":"2026-05-12T18:46:29.65489Z","steps":["trace[598722010] 'process raft request' (duration: 421.621323ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:46:29.655079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:46:29.232782Z","time spent":"422.213919ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T18:46:29.655099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.303999ms","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-05-12T18:46:29.655436Z","caller":"traceutil/trace.go:171","msg":"trace[134997740] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1052; }","duration":"261.413152ms","start":"2026-05-12T18:46:29.393723Z","end":"2026-05-12T18:46:29.655137Z","steps":["trace[134997740] 'agreement among raft nodes before linearized reading' (duration: 260.964981ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:46:29.933545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.942324ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T18:46:29.933764Z","caller":"traceutil/trace.go:171","msg":"trace[727117775] linearizableReadLoop","detail":"{readStateIndex:1093; appliedIndex:1092; }","duration":"141.211582ms","start":"2026-05-12T18:46:29.792529Z","end":"2026-05-12T18:46:29.93374Z","steps":["trace[727117775] 'read index received' (duration: 66.012µs)","trace[727117775] 'applied index is now lower than readState.Index' (duration: 141.14346ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T18:46:29.933866Z","caller":"traceutil/trace.go:171","msg":"trace[411921984] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"274.356998ms","start":"2026-05-12T18:46:29.659488Z","end":"2026-05-12T18:46:29.933845Z","steps":["trace[411921984] 'process raft request' (duration: 100.971493ms)","trace[411921984] 'compare' (duration: 172.711328ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T18:46:29.933895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.367505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T18:46:29.934015Z","caller":"traceutil/trace.go:171","msg":"trace[1334095352] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1053; }","duration":"141.519999ms","start":"2026-05-12T18:46:29.79248Z","end":"2026-05-12T18:46:29.934Z","steps":["trace[1334095352] 'agreement among raft nodes before linearized reading' (duration: 141.364765ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:46:30.377948Z","caller":"traceutil/trace.go:171","msg":"trace[1906170316] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"137.926868ms","start":"2026-05-12T18:46:30.239999Z","end":"2026-05-12T18:46:30.377926Z","steps":["trace[1906170316] 'process raft request' (duration: 115.635744ms)","trace[1906170316] 'compare' (duration: 21.924045ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T18:46:30.742983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.175584ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-12T18:46:30.743323Z","caller":"traceutil/trace.go:171","msg":"trace[407888457] linearizableReadLoop","detail":"{readStateIndex:1095; appliedIndex:1093; }","duration":"417.286624ms","start":"2026-05-12T18:46:30.325786Z","end":"2026-05-12T18:46:30.743072Z","steps":["trace[407888457] 'read index received' (duration: 29.846015ms)","trace[407888457] 'applied index is now lower than readState.Index' (duration: 387.438749ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T18:46:30.743453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.656293ms","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-05-12T18:46:30.743483Z","caller":"traceutil/trace.go:171","msg":"trace[364955005] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1054; }","duration":"417.723154ms","start":"2026-05-12T18:46:30.325751Z","end":"2026-05-12T18:46:30.743475Z","steps":["trace[364955005] 'agreement among raft nodes before linearized reading' (duration: 417.59641ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:46:30.743505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:46:30.325729Z","time spent":"417.771225ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-12T18:46:30.743569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.573349ms","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-05-12T18:46:30.743581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.544788ms","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-05-12T18:46:30.743631Z","caller":"traceutil/trace.go:171","msg":"trace[1093974986] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1054; }","duration":"273.65567ms","start":"2026-05-12T18:46:30.46996Z","end":"2026-05-12T18:46:30.743615Z","steps":["trace[1093974986] 'agreement among raft nodes before linearized reading' (duration: 273.516437ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:46:30.743615Z","caller":"traceutil/trace.go:171","msg":"trace[51846570] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1054; }","duration":"273.64816ms","start":"2026-05-12T18:46:30.469954Z","end":"2026-05-12T18:46:30.743602Z","steps":["trace[51846570] 'agreement among raft nodes before linearized reading' (duration: 273.508276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:46:31.856005Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576315820303526926,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T18:46:32.356402Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576315820303526926,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T18:46:32.634299Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.404574616s","expected-duration":"1s"} {"level":"info","ts":"2026-05-12T18:46:32.634735Z","caller":"traceutil/trace.go:171","msg":"trace[1391580998] linearizableReadLoop","detail":"{readStateIndex:1099; appliedIndex:1098; }","duration":"1.279465446s","start":"2026-05-12T18:46:31.355246Z","end":"2026-05-12T18:46:32.634711Z","steps":["trace[1391580998] 'read index received' (duration: 1.279236661s)","trace[1391580998] 'applied index is now lower than readState.Index' (duration: 227.475µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T18:46:32.634861Z","caller":"traceutil/trace.go:171","msg":"trace[667274368] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"1.405215862s","start":"2026-05-12T18:46:31.229612Z","end":"2026-05-12T18:46:32.634828Z","steps":["trace[667274368] 'process raft request' (duration: 1.404882271s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:46:32.63489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.279619786s","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-05-12T18:46:32.634951Z","caller":"traceutil/trace.go:171","msg":"trace[1514342879] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1058; }","duration":"1.279741371s","start":"2026-05-12T18:46:31.355194Z","end":"2026-05-12T18:46:32.634935Z","steps":["trace[1514342879] 'agreement among raft nodes before linearized reading' (duration: 1.279611933s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:46:32.635014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:46:31.355175Z","time spent":"1.279822416s","remote":"127.0.0.1:51752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-12T18:46:32.635028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:46:31.229595Z","time spent":"1.40534636s","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T18:46:33.134834Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576315820303526929,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-12T18:46:33.290574Z","caller":"traceutil/trace.go:171","msg":"trace[1183571638] linearizableReadLoop","detail":"{readStateIndex:1100; appliedIndex:1099; }","duration":"655.732842ms","start":"2026-05-12T18:46:32.634786Z","end":"2026-05-12T18:46:33.290519Z","steps":["trace[1183571638] 'read index received' (duration: 580.516054ms)","trace[1183571638] 'applied index is now lower than readState.Index' (duration: 75.215508ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T18:46:33.290701Z","caller":"traceutil/trace.go:171","msg":"trace[1783328307] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"907.808575ms","start":"2026-05-12T18:46:32.38288Z","end":"2026-05-12T18:46:33.290689Z","steps":["trace[1783328307] 'process raft request' (duration: 832.440097ms)","trace[1783328307] 'compare' (duration: 74.656953ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T18:46:33.290809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:46:32.382849Z","time spent":"907.877849ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T18:46:33.291194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.353813662s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-12T18:46:33.29129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.744174ms","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-05-12T18:46:33.291377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.498296327s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T18:46:33.291441Z","caller":"traceutil/trace.go:171","msg":"trace[1440878861] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1059; }","duration":"1.498386351s","start":"2026-05-12T18:46:31.793037Z","end":"2026-05-12T18:46:33.291423Z","steps":["trace[1440878861] 'agreement among raft nodes before linearized reading' (duration: 1.498313667s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:46:33.291479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.655787ms","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-05-12T18:46:33.291499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:46:31.793018Z","time spent":"1.498469396s","remote":"127.0.0.1:44968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-12T18:46:33.291522Z","caller":"traceutil/trace.go:171","msg":"trace[126422607] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1059; }","duration":"473.719961ms","start":"2026-05-12T18:46:32.817793Z","end":"2026-05-12T18:46:33.291513Z","steps":["trace[126422607] 'agreement among raft nodes before linearized reading' (duration: 473.612844ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:46:33.291612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:46:32.817784Z","time spent":"473.770915ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-12T18:46:33.291375Z","caller":"traceutil/trace.go:171","msg":"trace[626189558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1059; }","duration":"485.899805ms","start":"2026-05-12T18:46:32.805453Z","end":"2026-05-12T18:46:33.291353Z","steps":["trace[626189558] 'agreement among raft nodes before linearized reading' (duration: 485.685761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:46:33.29176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:46:32.805436Z","time spent":"486.303111ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-12T18:46:33.291316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.978454ms","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-05-12T18:46:33.291851Z","caller":"traceutil/trace.go:171","msg":"trace[224111775] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1059; }","duration":"1.353894776s","start":"2026-05-12T18:46:31.937334Z","end":"2026-05-12T18:46:33.291229Z","steps":["trace[224111775] 'agreement among raft nodes before linearized reading' (duration: 1.353662571s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:46:33.291964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:46:31.937315Z","time spent":"1.354632272s","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-12T18:46:33.291934Z","caller":"traceutil/trace.go:171","msg":"trace[1987103274] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1059; }","duration":"473.592113ms","start":"2026-05-12T18:46:32.818301Z","end":"2026-05-12T18:46:33.291893Z","steps":["trace[1987103274] 'agreement among raft nodes before linearized reading' (duration: 472.886409ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:46:33.292203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:46:32.818293Z","time spent":"473.85531ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-12T18:46:33.800943Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576315820303526931,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T18:46:33.986892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.155497ms","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-05-12T18:46:33.987009Z","caller":"traceutil/trace.go:171","msg":"trace[792603938] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1059; }","duration":"198.296976ms","start":"2026-05-12T18:46:33.788694Z","end":"2026-05-12T18:46:33.986991Z","steps":["trace[792603938] 'range keys from in-memory index tree' (duration: 198.139306ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:46:33.987232Z","caller":"traceutil/trace.go:171","msg":"trace[507716119] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"690.240485ms","start":"2026-05-12T18:46:33.296975Z","end":"2026-05-12T18:46:33.987215Z","steps":["trace[507716119] 'process raft request' (duration: 690.196442ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:46:33.987273Z","caller":"traceutil/trace.go:171","msg":"trace[70799898] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"690.339171ms","start":"2026-05-12T18:46:33.296911Z","end":"2026-05-12T18:46:33.98725Z","steps":["trace[70799898] 'process raft request' (duration: 666.020326ms)","trace[70799898] 'compare' (duration: 24.106011ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T18:46:33.98735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:46:33.296866Z","time spent":"690.410836ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T18:46:33.987408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:46:33.296876Z","time spent":"690.455528ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T18:46:34.01014Z","caller":"traceutil/trace.go:171","msg":"trace[488058081] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"711.732339ms","start":"2026-05-12T18:46:33.298381Z","end":"2026-05-12T18:46:34.010114Z","steps":["trace[488058081] 'process raft request' (duration: 711.58648ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:46:34.01029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:46:33.298368Z","time spent":"711.846597ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T18:46:34.010541Z","caller":"traceutil/trace.go:171","msg":"trace[266477295] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"711.805894ms","start":"2026-05-12T18:46:33.298687Z","end":"2026-05-12T18:46:34.010493Z","steps":["trace[266477295] 'process raft request' (duration: 711.388958ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:46:34.010611Z","caller":"traceutil/trace.go:171","msg":"trace[1331845721] linearizableReadLoop","detail":"{readStateIndex:1104; appliedIndex:1100; }","duration":"710.040102ms","start":"2026-05-12T18:46:33.300317Z","end":"2026-05-12T18:46:34.010357Z","steps":["trace[1331845721] 'read index received' (duration: 662.722616ms)","trace[1331845721] 'applied index is now lower than readState.Index' (duration: 47.313275ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T18:46:34.010703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:46:33.298674Z","time spent":"711.945873ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T18:46:34.010709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.381644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T18:46:34.010759Z","caller":"traceutil/trace.go:171","msg":"trace[1226006520] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1063; }","duration":"710.455488ms","start":"2026-05-12T18:46:33.300289Z","end":"2026-05-12T18:46:34.010744Z","steps":["trace[1226006520] 'agreement among raft nodes before linearized reading' (duration: 710.363432ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:46:34.010792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:46:33.300276Z","time spent":"710.509132ms","remote":"127.0.0.1:44962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-12T18:46:34.010875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.148273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-12T18:46:34.010946Z","caller":"traceutil/trace.go:171","msg":"trace[1941037535] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1063; }","duration":"444.231488ms","start":"2026-05-12T18:46:33.566694Z","end":"2026-05-12T18:46:34.010925Z","steps":["trace[1941037535] 'agreement among raft nodes before linearized reading' (duration: 444.097939ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:46:34.011002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:46:33.566668Z","time spent":"444.318924ms","remote":"127.0.0.1:39332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-05-12T18:46:34.011272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.382566ms","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-05-12T18:46:34.01132Z","caller":"traceutil/trace.go:171","msg":"trace[1682215913] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1063; }","duration":"307.468601ms","start":"2026-05-12T18:46:33.70384Z","end":"2026-05-12T18:46:34.011308Z","steps":["trace[1682215913] 'agreement among raft nodes before linearized reading' (duration: 307.391786ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:46:34.011353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:46:33.703785Z","time spent":"307.559396ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-12T18:46:35.414045Z","caller":"traceutil/trace.go:171","msg":"trace[1059890536] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"321.487861ms","start":"2026-05-12T18:46:35.092531Z","end":"2026-05-12T18:46:35.414019Z","steps":["trace[1059890536] 'process raft request' (duration: 275.457878ms)","trace[1059890536] 'compare' (duration: 45.832461ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T18:46:35.414193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:46:35.092514Z","time spent":"321.603999ms","remote":"127.0.0.1:38972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T18:46:35.443069Z","caller":"traceutil/trace.go:171","msg":"trace[604291524] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"145.761299ms","start":"2026-05-12T18:46:35.297279Z","end":"2026-05-12T18:46:35.443041Z","steps":["trace[604291524] 'process raft request' (duration: 145.543955ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:46:35.867033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.022054ms","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-05-12T18:46:35.86713Z","caller":"traceutil/trace.go:171","msg":"trace[219054387] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1066; }","duration":"123.181244ms","start":"2026-05-12T18:46:35.743932Z","end":"2026-05-12T18:46:35.867113Z","steps":["trace[219054387] 'count revisions from in-memory index tree' (duration: 122.913677ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:46:36.139153Z","caller":"traceutil/trace.go:171","msg":"trace[1426680159] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"143.989246ms","start":"2026-05-12T18:46:35.99514Z","end":"2026-05-12T18:46:36.139129Z","steps":["trace[1426680159] 'process raft request' (duration: 143.847647ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:46:36.500078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.948584ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T18:46:36.500184Z","caller":"traceutil/trace.go:171","msg":"trace[1671735671] linearizableReadLoop","detail":"{readStateIndex:1110; appliedIndex:1108; }","duration":"485.579005ms","start":"2026-05-12T18:46:36.014587Z","end":"2026-05-12T18:46:36.500166Z","steps":["trace[1671735671] 'read index received' (duration: 124.458046ms)","trace[1671735671] 'applied index is now lower than readState.Index' (duration: 361.119578ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T18:46:36.500403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.766253ms","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-05-12T18:46:36.500443Z","caller":"traceutil/trace.go:171","msg":"trace[1109882392] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1068; }","duration":"484.822906ms","start":"2026-05-12T18:46:36.015608Z","end":"2026-05-12T18:46:36.500431Z","steps":["trace[1109882392] 'agreement among raft nodes before linearized reading' (duration: 484.669177ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:46:36.500479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:46:36.015599Z","time spent":"484.87171ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-12T18:46:36.500453Z","caller":"traceutil/trace.go:171","msg":"trace[1637523192] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"504.747971ms","start":"2026-05-12T18:46:35.995654Z","end":"2026-05-12T18:46:36.500402Z","steps":["trace[1637523192] 'process raft request' (duration: 178.409292ms)","trace[1637523192] 'compare' (duration: 325.766863ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T18:46:36.500523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.908995ms","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-05-12T18:46:36.500612Z","caller":"traceutil/trace.go:171","msg":"trace[1903465193] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1068; }","duration":"486.048724ms","start":"2026-05-12T18:46:36.01454Z","end":"2026-05-12T18:46:36.500589Z","steps":["trace[1903465193] 'agreement among raft nodes before linearized reading' (duration: 485.730934ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:46:36.500665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:46:36.01452Z","time spent":"486.132609ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-12T18:46:36.500655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:46:35.995641Z","time spent":"504.893031ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T18:46:36.871196Z","caller":"traceutil/trace.go:171","msg":"trace[316186647] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"364.320232ms","start":"2026-05-12T18:46:36.506849Z","end":"2026-05-12T18:46:36.871169Z","steps":["trace[316186647] 'process raft request' (duration: 364.093898ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:46:36.871352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:46:36.506825Z","time spent":"364.44743ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T18:46:36.91201Z","caller":"traceutil/trace.go:171","msg":"trace[379746014] linearizableReadLoop","detail":"{readStateIndex:1113; appliedIndex:1112; }","duration":"165.530134ms","start":"2026-05-12T18:46:36.746455Z","end":"2026-05-12T18:46:36.911985Z","steps":["trace[379746014] 'read index received' (duration: 125.166781ms)","trace[379746014] 'applied index is now lower than readState.Index' (duration: 40.362323ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T18:46:36.912132Z","caller":"traceutil/trace.go:171","msg":"trace[871521635] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"326.147507ms","start":"2026-05-12T18:46:36.58595Z","end":"2026-05-12T18:46:36.912097Z","steps":["trace[871521635] 'process raft request' (duration: 325.914652ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:46:36.912257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.791341ms","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-05-12T18:46:36.912292Z","caller":"traceutil/trace.go:171","msg":"trace[1579621169] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1071; }","duration":"165.877336ms","start":"2026-05-12T18:46:36.746403Z","end":"2026-05-12T18:46:36.91228Z","steps":["trace[1579621169] 'agreement among raft nodes before linearized reading' (duration: 165.732077ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:46:36.912288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:46:36.585926Z","time spent":"326.276535ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T18:46:36.912365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.003902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T18:46:36.912421Z","caller":"traceutil/trace.go:171","msg":"trace[2086446036] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1071; }","duration":"120.103909ms","start":"2026-05-12T18:46:36.792302Z","end":"2026-05-12T18:46:36.912406Z","steps":["trace[2086446036] 'agreement among raft nodes before linearized reading' (duration: 120.016514ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:46:37.14131Z","caller":"traceutil/trace.go:171","msg":"trace[1235934783] linearizableReadLoop","detail":"{readStateIndex:1114; appliedIndex:1113; }","duration":"217.967445ms","start":"2026-05-12T18:46:36.92332Z","end":"2026-05-12T18:46:37.141287Z","steps":["trace[1235934783] 'read index received' (duration: 201.551072ms)","trace[1235934783] 'applied index is now lower than readState.Index' (duration: 16.415253ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T18:46:37.141412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.078303ms","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-05-12T18:46:37.141437Z","caller":"traceutil/trace.go:171","msg":"trace[193088000] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1072; }","duration":"218.141107ms","start":"2026-05-12T18:46:36.923283Z","end":"2026-05-12T18:46:37.141424Z","steps":["trace[193088000] 'agreement among raft nodes before linearized reading' (duration: 218.078963ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:46:37.141512Z","caller":"traceutil/trace.go:171","msg":"trace[1622516132] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"224.219793ms","start":"2026-05-12T18:46:36.91725Z","end":"2026-05-12T18:46:37.14147Z","steps":["trace[1622516132] 'process raft request' (duration: 207.596017ms)","trace[1622516132] 'compare' (duration: 16.163647ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T18:46:50.395961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.093125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T18:46:50.39608Z","caller":"traceutil/trace.go:171","msg":"trace[121895948] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1163; }","duration":"604.265789ms","start":"2026-05-12T18:46:49.791786Z","end":"2026-05-12T18:46:50.396051Z","steps":["trace[121895948] 'range keys from in-memory index tree' (duration: 603.980093ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:46:50.396152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:46:49.791763Z","time spent":"604.376723ms","remote":"127.0.0.1:44962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-12T18:46:50.73499Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576315820303527169,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T18:46:51.14345Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.155261202s","expected-duration":"1s"} {"level":"info","ts":"2026-05-12T18:46:51.144102Z","caller":"traceutil/trace.go:171","msg":"trace[538084032] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"1.155979389s","start":"2026-05-12T18:46:49.988094Z","end":"2026-05-12T18:46:51.144073Z","steps":["trace[538084032] 'process raft request' (duration: 1.155593769s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:46:51.144242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:46:49.988069Z","time spent":"1.156102111s","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T18:46:51.151322Z","caller":"traceutil/trace.go:171","msg":"trace[468823923] linearizableReadLoop","detail":"{readStateIndex:1211; appliedIndex:1209; }","duration":"917.074166ms","start":"2026-05-12T18:46:50.234231Z","end":"2026-05-12T18:46:51.151305Z","steps":["trace[468823923] 'read index received' (duration: 909.384122ms)","trace[468823923] 'applied index is now lower than readState.Index' (duration: 7.688954ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T18:46:51.151505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"917.25849ms","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-05-12T18:46:51.151528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.058034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T18:46:51.151534Z","caller":"traceutil/trace.go:171","msg":"trace[309199101] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1164; }","duration":"917.332731ms","start":"2026-05-12T18:46:50.234194Z","end":"2026-05-12T18:46:51.151527Z","steps":["trace[309199101] 'agreement among raft nodes before linearized reading' (duration: 917.205078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:46:51.151562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:46:50.234177Z","time spent":"917.378333ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-12T18:46:51.151624Z","caller":"traceutil/trace.go:171","msg":"trace[1171131431] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1164; }","duration":"754.114075ms","start":"2026-05-12T18:46:50.39743Z","end":"2026-05-12T18:46:51.151545Z","steps":["trace[1171131431] 'agreement among raft nodes before linearized reading' (duration: 754.076544ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:46:51.151647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:46:50.397413Z","time spent":"754.229048ms","remote":"127.0.0.1:44968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-12T18:46:51.151801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.511737ms","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-05-12T18:46:51.15182Z","caller":"traceutil/trace.go:171","msg":"trace[631066253] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1164; }","duration":"214.532637ms","start":"2026-05-12T18:46:50.937282Z","end":"2026-05-12T18:46:51.151815Z","steps":["trace[631066253] 'agreement among raft nodes before linearized reading' (duration: 214.463276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:46:51.151854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.551108ms","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-05-12T18:46:51.151884Z","caller":"traceutil/trace.go:171","msg":"trace[1332332788] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1164; }","duration":"587.60525ms","start":"2026-05-12T18:46:50.564271Z","end":"2026-05-12T18:46:51.151876Z","steps":["trace[1332332788] 'agreement among raft nodes before linearized reading' (duration: 587.533258ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:46:51.151907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:46:50.564256Z","time spent":"587.646861ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-12T18:46:51.152039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.791875ms","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-05-12T18:46:51.152062Z","caller":"traceutil/trace.go:171","msg":"trace[1970039332] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1164; }","duration":"567.861836ms","start":"2026-05-12T18:46:50.584195Z","end":"2026-05-12T18:46:51.152057Z","steps":["trace[1970039332] 'agreement among raft nodes before linearized reading' (duration: 567.803505ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:46:51.152081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:46:50.584168Z","time spent":"567.907967ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-12T18:47:03.354045Z","caller":"traceutil/trace.go:171","msg":"trace[1817052883] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"101.921635ms","start":"2026-05-12T18:47:03.252087Z","end":"2026-05-12T18:47:03.354008Z","steps":["trace[1817052883] 'process raft request' (duration: 58.397051ms)","trace[1817052883] 'compare' (duration: 43.287728ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T18:47:03.354532Z","caller":"traceutil/trace.go:171","msg":"trace[822161738] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"102.285494ms","start":"2026-05-12T18:47:03.252233Z","end":"2026-05-12T18:47:03.354518Z","steps":["trace[822161738] 'process raft request' (duration: 101.69244ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:47:07.793608Z","caller":"traceutil/trace.go:171","msg":"trace[1887641591] linearizableReadLoop","detail":"{readStateIndex:1288; appliedIndex:1287; }","duration":"417.399866ms","start":"2026-05-12T18:47:07.37618Z","end":"2026-05-12T18:47:07.79358Z","steps":["trace[1887641591] 'read index received' (duration: 318.969824ms)","trace[1887641591] 'applied index is now lower than readState.Index' (duration: 98.428792ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T18:47:07.793686Z","caller":"traceutil/trace.go:171","msg":"trace[1862455752] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"420.410548ms","start":"2026-05-12T18:47:07.373249Z","end":"2026-05-12T18:47:07.79366Z","steps":["trace[1862455752] 'process raft request' (duration: 321.929365ms)","trace[1862455752] 'compare' (duration: 98.152445ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T18:47:07.793792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.60685ms","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-05-12T18:47:07.793797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:47:07.373236Z","time spent":"420.494189ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T18:47:07.793828Z","caller":"traceutil/trace.go:171","msg":"trace[1068243494] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1238; }","duration":"417.674262ms","start":"2026-05-12T18:47:07.376142Z","end":"2026-05-12T18:47:07.793816Z","steps":["trace[1068243494] 'agreement among raft nodes before linearized reading' (duration: 417.547979ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:47:07.793857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:47:07.376126Z","time spent":"417.724213ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-12T18:47:08.294634Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576315820303527335,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-12T18:47:08.657867Z","caller":"traceutil/trace.go:171","msg":"trace[2023732827] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"1.02975934s","start":"2026-05-12T18:47:07.628078Z","end":"2026-05-12T18:47:08.657837Z","steps":["trace[2023732827] 'process raft request' (duration: 975.120859ms)","trace[2023732827] 'compare' (duration: 54.17033ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T18:47:08.6579Z","caller":"traceutil/trace.go:171","msg":"trace[212193049] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"1.013670063s","start":"2026-05-12T18:47:07.6442Z","end":"2026-05-12T18:47:08.65787Z","steps":["trace[212193049] 'process raft request' (duration: 1.013580421s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:47:08.658002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:47:07.628046Z","time spent":"1.029881892s","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T18:47:08.658072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:47:07.644169Z","time spent":"1.013812837s","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T18:47:08.660542Z","caller":"traceutil/trace.go:171","msg":"trace[909114324] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"862.24516ms","start":"2026-05-12T18:47:07.798281Z","end":"2026-05-12T18:47:08.660526Z","steps":["trace[909114324] 'process raft request' (duration: 862.095666ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:47:08.660641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:47:07.798259Z","time spent":"862.329722ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T18:47:08.660707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"869.632557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T18:47:08.660776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.259629305s","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-05-12T18:47:08.66083Z","caller":"traceutil/trace.go:171","msg":"trace[1974188131] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1241; }","duration":"1.259705618s","start":"2026-05-12T18:47:07.401107Z","end":"2026-05-12T18:47:08.660812Z","steps":["trace[1974188131] 'agreement among raft nodes before linearized reading' (duration: 1.259503503s)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:47:08.660842Z","caller":"traceutil/trace.go:171","msg":"trace[1515145093] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1241; }","duration":"869.780251ms","start":"2026-05-12T18:47:07.791008Z","end":"2026-05-12T18:47:08.660789Z","steps":["trace[1515145093] 'agreement among raft nodes before linearized reading' (duration: 869.566626ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:47:08.660882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:47:07.401092Z","time spent":"1.259778039s","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-12T18:47:08.66094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:47:07.790979Z","time spent":"869.902114ms","remote":"127.0.0.1:44962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-12T18:47:08.660481Z","caller":"traceutil/trace.go:171","msg":"trace[124470080] linearizableReadLoop","detail":"{readStateIndex:1291; appliedIndex:1288; }","duration":"866.796909ms","start":"2026-05-12T18:47:07.793667Z","end":"2026-05-12T18:47:08.660464Z","steps":["trace[124470080] 'read index received' (duration: 809.778531ms)","trace[124470080] 'applied index is now lower than readState.Index' (duration: 57.017308ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T18:47:08.661218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.267027793s","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-05-12T18:47:08.661267Z","caller":"traceutil/trace.go:171","msg":"trace[1749358810] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1241; }","duration":"1.267101955s","start":"2026-05-12T18:47:07.394151Z","end":"2026-05-12T18:47:08.661253Z","steps":["trace[1749358810] 'agreement among raft nodes before linearized reading' (duration: 1.266948511s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:47:08.661296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.382255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T18:47:08.661327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:47:07.394139Z","time spent":"1.267161866s","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-12T18:47:08.661334Z","caller":"traceutil/trace.go:171","msg":"trace[2101627930] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1241; }","duration":"547.472277ms","start":"2026-05-12T18:47:08.11385Z","end":"2026-05-12T18:47:08.661322Z","steps":["trace[2101627930] 'agreement among raft nodes before linearized reading' (duration: 547.408615ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:47:08.661376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:47:08.113829Z","time spent":"547.534329ms","remote":"127.0.0.1:46464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-05-12T18:47:10.000003Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576315820303527348,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-12T18:47:10.060881Z","caller":"traceutil/trace.go:171","msg":"trace[2140763919] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"574.506896ms","start":"2026-05-12T18:47:09.48635Z","end":"2026-05-12T18:47:10.060857Z","steps":["trace[2140763919] 'process raft request' (duration: 574.315401ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:47:10.061035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:47:09.486307Z","time spent":"574.657729ms","remote":"127.0.0.1:39006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T18:47:10.712362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.710763ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-12T18:47:10.712571Z","caller":"traceutil/trace.go:171","msg":"trace[132767346] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"648.305415ms","start":"2026-05-12T18:47:10.064244Z","end":"2026-05-12T18:47:10.712549Z","steps":["trace[132767346] 'process raft request' (duration: 648.230373ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:47:10.712668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:47:10.064229Z","time spent":"648.395878ms","remote":"127.0.0.1:39006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T18:47:10.712932Z","caller":"traceutil/trace.go:171","msg":"trace[616318230] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"1.211790717s","start":"2026-05-12T18:47:09.50112Z","end":"2026-05-12T18:47:10.712911Z","steps":["trace[616318230] 'process raft request' (duration: 803.471769ms)","trace[616318230] 'compare' (duration: 407.448887ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T18:47:10.712998Z","caller":"traceutil/trace.go:171","msg":"trace[222563516] linearizableReadLoop","detail":"{readStateIndex:1299; appliedIndex:1298; }","duration":"1.213717122s","start":"2026-05-12T18:47:09.499258Z","end":"2026-05-12T18:47:10.712975Z","steps":["trace[222563516] 'read index received' (duration: 561.598835ms)","trace[222563516] 'applied index is now lower than readState.Index' (duration: 652.115917ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T18:47:10.713015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:47:09.501106Z","time spent":"1.211873589s","remote":"127.0.0.1:39006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T18:47:10.713237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.213964948s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-05-12T18:47:10.713283Z","caller":"traceutil/trace.go:171","msg":"trace[916306347] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1250; }","duration":"1.214086072s","start":"2026-05-12T18:47:09.499185Z","end":"2026-05-12T18:47:10.713271Z","steps":["trace[916306347] 'agreement among raft nodes before linearized reading' (duration: 1.213877947s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:47:10.713309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:47:09.49917Z","time spent":"1.214132942s","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":498,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-12T18:47:10.921282Z","caller":"traceutil/trace.go:171","msg":"trace[913322465] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"258.216396ms","start":"2026-05-12T18:47:10.663038Z","end":"2026-05-12T18:47:10.921255Z","steps":["trace[913322465] 'process raft request' (duration: 257.97592ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:47:10.92135Z","caller":"traceutil/trace.go:171","msg":"trace[1210335311] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"566.113324ms","start":"2026-05-12T18:47:10.355219Z","end":"2026-05-12T18:47:10.921333Z","steps":["trace[1210335311] 'process raft request' (duration: 565.670223ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:47:10.921384Z","caller":"traceutil/trace.go:171","msg":"trace[16168794] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"202.392627ms","start":"2026-05-12T18:47:10.718964Z","end":"2026-05-12T18:47:10.921357Z","steps":["trace[16168794] 'process raft request' (duration: 202.151571ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:47:10.921436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"981.659115ms","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-05-12T18:47:10.921506Z","caller":"traceutil/trace.go:171","msg":"trace[794239710] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1253; }","duration":"981.763837ms","start":"2026-05-12T18:47:09.939711Z","end":"2026-05-12T18:47:10.921475Z","steps":["trace[794239710] 'agreement among raft nodes before linearized reading' (duration: 981.626114ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:47:10.921544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:47:09.939692Z","time spent":"981.842519ms","remote":"127.0.0.1:51790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-05-12T18:47:10.921573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:47:10.355194Z","time spent":"566.248218ms","remote":"127.0.0.1:39090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T18:47:10.921587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.324178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T18:47:10.92164Z","caller":"traceutil/trace.go:171","msg":"trace[1064715484] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1253; }","duration":"243.410341ms","start":"2026-05-12T18:47:10.678215Z","end":"2026-05-12T18:47:10.921626Z","steps":["trace[1064715484] 'agreement among raft nodes before linearized reading' (duration: 243.302888ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:47:10.921733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.123905838s","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-05-12T18:47:10.921765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.855223ms","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-05-12T18:47:10.921804Z","caller":"traceutil/trace.go:171","msg":"trace[808561777] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1253; }","duration":"1.124030511s","start":"2026-05-12T18:47:09.797756Z","end":"2026-05-12T18:47:10.921787Z","steps":["trace[808561777] 'agreement among raft nodes before linearized reading' (duration: 1.123842566s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:47:10.921854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:47:09.797732Z","time spent":"1.124108952s","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-12T18:47:10.921188Z","caller":"traceutil/trace.go:171","msg":"trace[453252398] linearizableReadLoop","detail":"{readStateIndex:1303; appliedIndex:1300; }","duration":"208.100754ms","start":"2026-05-12T18:47:10.71306Z","end":"2026-05-12T18:47:10.921161Z","steps":["trace[453252398] 'read index received' (duration: 163.592556ms)","trace[453252398] 'applied index is now lower than readState.Index' (duration: 44.506968ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T18:47:10.921983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.13026323s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T18:47:10.922032Z","caller":"traceutil/trace.go:171","msg":"trace[1635762117] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1253; }","duration":"1.130346472s","start":"2026-05-12T18:47:09.791671Z","end":"2026-05-12T18:47:10.922018Z","steps":["trace[1635762117] 'agreement among raft nodes before linearized reading' (duration: 1.13026794s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:47:10.922063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:47:09.791649Z","time spent":"1.130406683s","remote":"127.0.0.1:44968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-12T18:47:10.921809Z","caller":"traceutil/trace.go:171","msg":"trace[1440588897] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1253; }","duration":"256.924995ms","start":"2026-05-12T18:47:10.66487Z","end":"2026-05-12T18:47:10.921795Z","steps":["trace[1440588897] 'agreement among raft nodes before linearized reading' (duration: 256.347921ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:47:10.92212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.874685ms","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-05-12T18:47:10.922145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.401725ms","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-05-12T18:47:10.922195Z","caller":"traceutil/trace.go:171","msg":"trace[874891692] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1253; }","duration":"174.490257ms","start":"2026-05-12T18:47:10.747691Z","end":"2026-05-12T18:47:10.922181Z","steps":["trace[874891692] 'agreement among raft nodes before linearized reading' (duration: 174.360724ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:47:10.922153Z","caller":"traceutil/trace.go:171","msg":"trace[1317963951] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1253; }","duration":"201.932255ms","start":"2026-05-12T18:47:10.720208Z","end":"2026-05-12T18:47:10.92214Z","steps":["trace[1317963951] 'agreement among raft nodes before linearized reading' (duration: 201.822133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:47:12.292202Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576315820303527366,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-12T18:47:12.464768Z","caller":"traceutil/trace.go:171","msg":"trace[478151402] linearizableReadLoop","detail":"{readStateIndex:1310; appliedIndex:1309; }","duration":"672.59373ms","start":"2026-05-12T18:47:11.792155Z","end":"2026-05-12T18:47:12.464748Z","steps":["trace[478151402] 'read index received' (duration: 672.436956ms)","trace[478151402] 'applied index is now lower than readState.Index' (duration: 156.064µs)"],"step_count":2} {"level":"warn","ts":"2026-05-12T18:47:12.464911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.746744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T18:47:12.464951Z","caller":"traceutil/trace.go:171","msg":"trace[1135395280] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1259; }","duration":"672.841285ms","start":"2026-05-12T18:47:11.792101Z","end":"2026-05-12T18:47:12.464943Z","steps":["trace[1135395280] 'agreement among raft nodes before linearized reading' (duration: 672.722812ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:47:12.46492Z","caller":"traceutil/trace.go:171","msg":"trace[1753460645] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"757.180209ms","start":"2026-05-12T18:47:11.707687Z","end":"2026-05-12T18:47:12.464868Z","steps":["trace[1753460645] 'process raft request' (duration: 756.928773ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:47:12.464977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:47:11.79208Z","time spent":"672.891667ms","remote":"127.0.0.1:44962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-12T18:47:12.465167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:47:11.70766Z","time spent":"757.394554ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T18:47:13.142167Z","caller":"traceutil/trace.go:171","msg":"trace[508344311] linearizableReadLoop","detail":"{readStateIndex:1312; appliedIndex:1311; }","duration":"141.095556ms","start":"2026-05-12T18:47:13.001047Z","end":"2026-05-12T18:47:13.142143Z","steps":["trace[508344311] 'read index received' (duration: 85.523122ms)","trace[508344311] 'applied index is now lower than readState.Index' (duration: 55.571204ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T18:47:13.142405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.907937ms","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-05-12T18:47:13.142441Z","caller":"traceutil/trace.go:171","msg":"trace[1309776773] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1261; }","duration":"139.989979ms","start":"2026-05-12T18:47:13.002441Z","end":"2026-05-12T18:47:13.142431Z","steps":["trace[1309776773] 'agreement among raft nodes before linearized reading' (duration: 139.833575ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:47:13.142441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.382382ms","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-05-12T18:47:13.142516Z","caller":"traceutil/trace.go:171","msg":"trace[324488505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1261; }","duration":"141.487255ms","start":"2026-05-12T18:47:13.001011Z","end":"2026-05-12T18:47:13.142499Z","steps":["trace[324488505] 'agreement among raft nodes before linearized reading' (duration: 141.243889ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:47:13.142515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.885327ms","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-05-12T18:47:13.142594Z","caller":"traceutil/trace.go:171","msg":"trace[332994764] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1261; }","duration":"140.001529ms","start":"2026-05-12T18:47:13.002574Z","end":"2026-05-12T18:47:13.142575Z","steps":["trace[332994764] 'agreement among raft nodes before linearized reading' (duration: 139.732553ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:47:13.142245Z","caller":"traceutil/trace.go:171","msg":"trace[1618762906] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"189.088607ms","start":"2026-05-12T18:47:12.95312Z","end":"2026-05-12T18:47:13.142209Z","steps":["trace[1618762906] 'process raft request' (duration: 133.481153ms)","trace[1618762906] 'compare' (duration: 55.333608ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T18:47:13.57128Z","caller":"traceutil/trace.go:171","msg":"trace[1896103640] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"423.438741ms","start":"2026-05-12T18:47:13.147829Z","end":"2026-05-12T18:47:13.571268Z","steps":["trace[1896103640] 'process raft request' (duration: 423.127614ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:47:13.571328Z","caller":"traceutil/trace.go:171","msg":"trace[1354329348] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"423.40803ms","start":"2026-05-12T18:47:13.147888Z","end":"2026-05-12T18:47:13.571296Z","steps":["trace[1354329348] 'process raft request' (duration: 423.247286ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:47:13.571376Z","caller":"traceutil/trace.go:171","msg":"trace[1640675863] linearizableReadLoop","detail":"{readStateIndex:1315; appliedIndex:1313; }","duration":"128.864963ms","start":"2026-05-12T18:47:13.442315Z","end":"2026-05-12T18:47:13.57118Z","steps":["trace[1640675863] 'read index received' (duration: 128.591826ms)","trace[1640675863] 'applied index is now lower than readState.Index' (duration: 272.307µs)"],"step_count":2} {"level":"warn","ts":"2026-05-12T18:47:13.571391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:47:13.14782Z","time spent":"423.514713ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T18:47:13.57147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.16947ms","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-05-12T18:47:13.571489Z","caller":"traceutil/trace.go:171","msg":"trace[536015968] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1264; }","duration":"129.221961ms","start":"2026-05-12T18:47:13.44226Z","end":"2026-05-12T18:47:13.571482Z","steps":["trace[536015968] 'agreement among raft nodes before linearized reading' (duration: 129.152509ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:47:13.571476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:47:13.147866Z","time spent":"423.529993ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T18:47:21.890188Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576315820303527501,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T18:47:22.743998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.969526ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T18:47:22.744121Z","caller":"traceutil/trace.go:171","msg":"trace[1735783089] linearizableReadLoop","detail":"{readStateIndex:1379; appliedIndex:1378; }","duration":"1.354839221s","start":"2026-05-12T18:47:21.389259Z","end":"2026-05-12T18:47:22.744098Z","steps":["trace[1735783089] 'read index received' (duration: 654.574428ms)","trace[1735783089] 'applied index is now lower than readState.Index' (duration: 700.262972ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T18:47:22.744408Z","caller":"traceutil/trace.go:171","msg":"trace[1445958878] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"1.540933306s","start":"2026-05-12T18:47:21.203455Z","end":"2026-05-12T18:47:22.744388Z","steps":["trace[1445958878] 'process raft request' (duration: 840.451477ms)","trace[1445958878] 'compare' (duration: 699.322401ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T18:47:22.744501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:47:21.203426Z","time spent":"1.541029178s","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T18:47:22.74462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.146701215s","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-05-12T18:47:22.744689Z","caller":"traceutil/trace.go:171","msg":"trace[1440881451] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1326; }","duration":"1.146816028s","start":"2026-05-12T18:47:21.597854Z","end":"2026-05-12T18:47:22.74467Z","steps":["trace[1440881451] 'agreement among raft nodes before linearized reading' (duration: 1.146606003s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:47:22.744718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.985112ms","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-05-12T18:47:22.744787Z","caller":"traceutil/trace.go:171","msg":"trace[1748455971] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1326; }","duration":"919.095645ms","start":"2026-05-12T18:47:21.825671Z","end":"2026-05-12T18:47:22.744767Z","steps":["trace[1748455971] 'agreement among raft nodes before linearized reading' (duration: 918.981992ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:47:22.744829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:47:21.825652Z","time spent":"919.166736ms","remote":"127.0.0.1:51874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-05-12T18:47:22.74482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.137002783s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-12T18:47:22.74484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.139346059s","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-05-12T18:47:22.744878Z","caller":"traceutil/trace.go:171","msg":"trace[257098435] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1326; }","duration":"1.139423841s","start":"2026-05-12T18:47:21.605445Z","end":"2026-05-12T18:47:22.744869Z","steps":["trace[257098435] 'agreement among raft nodes before linearized reading' (duration: 1.139320129s)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:47:22.744882Z","caller":"traceutil/trace.go:171","msg":"trace[1741123246] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1326; }","duration":"1.137109356s","start":"2026-05-12T18:47:21.607756Z","end":"2026-05-12T18:47:22.744866Z","steps":["trace[1741123246] 'agreement among raft nodes before linearized reading' (duration: 1.136900871s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:47:22.744912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:47:21.605423Z","time spent":"1.139480663s","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-12T18:47:22.744927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:47:21.607699Z","time spent":"1.137217368s","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-12T18:47:22.745057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"952.780632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T18:47:22.744738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:47:21.597835Z","time spent":"1.146892691s","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-12T18:47:22.745095Z","caller":"traceutil/trace.go:171","msg":"trace[1285996673] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1326; }","duration":"952.815283ms","start":"2026-05-12T18:47:21.792267Z","end":"2026-05-12T18:47:22.745082Z","steps":["trace[1285996673] 'agreement among raft nodes before linearized reading' (duration: 952.752302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:47:22.74511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.355862535s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-05-12T18:47:22.745133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:47:21.792213Z","time spent":"952.911136ms","remote":"127.0.0.1:44962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-12T18:47:22.745142Z","caller":"traceutil/trace.go:171","msg":"trace[519942070] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1326; }","duration":"1.355938366s","start":"2026-05-12T18:47:21.389194Z","end":"2026-05-12T18:47:22.745133Z","steps":["trace[519942070] 'agreement among raft nodes before linearized reading' (duration: 1.355818103s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:47:22.745172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:47:21.389167Z","time spent":"1.355998718s","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-05-12T18:47:22.745293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.827079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-05-12T18:47:22.745381Z","caller":"traceutil/trace.go:171","msg":"trace[1725444605] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1326; }","duration":"463.965503ms","start":"2026-05-12T18:47:22.281393Z","end":"2026-05-12T18:47:22.745359Z","steps":["trace[1725444605] 'agreement among raft nodes before linearized reading' (duration: 463.715167ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:47:22.745448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:47:22.281372Z","time spent":"464.057805ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-12T18:47:24.697273Z","caller":"traceutil/trace.go:171","msg":"trace[181258157] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"104.863797ms","start":"2026-05-12T18:47:24.592379Z","end":"2026-05-12T18:47:24.697243Z","steps":["trace[181258157] 'process raft request' (duration: 104.708083ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:47:41.046563Z","caller":"traceutil/trace.go:171","msg":"trace[976823272] linearizableReadLoop","detail":"{readStateIndex:1467; appliedIndex:1465; }","duration":"211.447542ms","start":"2026-05-12T18:47:40.835086Z","end":"2026-05-12T18:47:41.046533Z","steps":["trace[976823272] 'read index received' (duration: 89.411174ms)","trace[976823272] 'applied index is now lower than readState.Index' (duration: 122.034928ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T18:47:41.046603Z","caller":"traceutil/trace.go:171","msg":"trace[774014304] transaction","detail":"{read_only:false; response_revision:1410; number_of_response:1; }","duration":"214.908935ms","start":"2026-05-12T18:47:40.831659Z","end":"2026-05-12T18:47:41.046568Z","steps":["trace[774014304] 'process raft request' (duration: 132.374958ms)","trace[774014304] 'compare' (duration: 82.306052ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T18:47:41.046824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.718579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-05-12T18:47:41.046891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.500328ms","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-05-12T18:47:41.046959Z","caller":"traceutil/trace.go:171","msg":"trace[34263763] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1410; }","duration":"200.621201ms","start":"2026-05-12T18:47:40.846321Z","end":"2026-05-12T18:47:41.046943Z","steps":["trace[34263763] 'agreement among raft nodes before linearized reading' (duration: 200.412506ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:47:41.046903Z","caller":"traceutil/trace.go:171","msg":"trace[552663194] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1410; }","duration":"211.816961ms","start":"2026-05-12T18:47:40.835067Z","end":"2026-05-12T18:47:41.046883Z","steps":["trace[552663194] 'agreement among raft nodes before linearized reading' (duration: 211.640687ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:47:41.047327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.884107ms","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-05-12T18:47:41.047371Z","caller":"traceutil/trace.go:171","msg":"trace[1291456475] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1410; }","duration":"190.945878ms","start":"2026-05-12T18:47:40.856415Z","end":"2026-05-12T18:47:41.047361Z","steps":["trace[1291456475] 'agreement among raft nodes before linearized reading' (duration: 190.847466ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:47:41.162753Z","caller":"traceutil/trace.go:171","msg":"trace[692614478] transaction","detail":"{read_only:false; response_revision:1414; number_of_response:1; }","duration":"109.155109ms","start":"2026-05-12T18:47:41.053575Z","end":"2026-05-12T18:47:41.16273Z","steps":["trace[692614478] 'process raft request' (duration: 109.094917ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:47:41.162769Z","caller":"traceutil/trace.go:171","msg":"trace[442609049] transaction","detail":"{read_only:false; response_revision:1412; number_of_response:1; }","duration":"110.192504ms","start":"2026-05-12T18:47:41.052553Z","end":"2026-05-12T18:47:41.162746Z","steps":["trace[442609049] 'process raft request' (duration: 101.000003ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:47:41.162809Z","caller":"traceutil/trace.go:171","msg":"trace[1907983095] transaction","detail":"{read_only:false; response_revision:1413; number_of_response:1; }","duration":"110.082761ms","start":"2026-05-12T18:47:41.052699Z","end":"2026-05-12T18:47:41.162782Z","steps":["trace[1907983095] 'process raft request' (duration: 109.928987ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:47:41.16304Z","caller":"traceutil/trace.go:171","msg":"trace[554550258] transaction","detail":"{read_only:false; response_revision:1416; number_of_response:1; }","duration":"108.466972ms","start":"2026-05-12T18:47:41.054552Z","end":"2026-05-12T18:47:41.163019Z","steps":["trace[554550258] 'process raft request' (duration: 108.36865ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:47:41.163057Z","caller":"traceutil/trace.go:171","msg":"trace[830811396] transaction","detail":"{read_only:false; response_revision:1415; number_of_response:1; }","duration":"108.923434ms","start":"2026-05-12T18:47:41.05409Z","end":"2026-05-12T18:47:41.163013Z","steps":["trace[830811396] 'process raft request' (duration: 108.609766ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:47:44.84989Z","caller":"traceutil/trace.go:171","msg":"trace[934949715] transaction","detail":"{read_only:false; response_revision:1428; number_of_response:1; }","duration":"115.676016ms","start":"2026-05-12T18:47:44.734192Z","end":"2026-05-12T18:47:44.849868Z","steps":["trace[934949715] 'process raft request' (duration: 115.42374ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:48:06.432888Z","caller":"traceutil/trace.go:171","msg":"trace[1900538547] transaction","detail":"{read_only:false; response_revision:1530; number_of_response:1; }","duration":"132.542502ms","start":"2026-05-12T18:48:06.300316Z","end":"2026-05-12T18:48:06.432859Z","steps":["trace[1900538547] 'process raft request' (duration: 128.239858ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:48:17.515827Z","caller":"traceutil/trace.go:171","msg":"trace[480536820] linearizableReadLoop","detail":"{readStateIndex:1642; appliedIndex:1641; }","duration":"113.901333ms","start":"2026-05-12T18:48:17.401897Z","end":"2026-05-12T18:48:17.515798Z","steps":["trace[480536820] 'read index received' (duration: 29.675484ms)","trace[480536820] 'applied index is now lower than readState.Index' (duration: 84.224958ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T18:48:17.515894Z","caller":"traceutil/trace.go:171","msg":"trace[733096463] transaction","detail":"{read_only:false; response_revision:1578; number_of_response:1; }","duration":"156.634772ms","start":"2026-05-12T18:48:17.359227Z","end":"2026-05-12T18:48:17.515862Z","steps":["trace[733096463] 'process raft request' (duration: 72.353793ms)","trace[733096463] 'compare' (duration: 84.101495ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T18:48:17.516227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.308593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-12T18:48:17.516337Z","caller":"traceutil/trace.go:171","msg":"trace[1353443397] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1578; }","duration":"114.453826ms","start":"2026-05-12T18:48:17.401844Z","end":"2026-05-12T18:48:17.516298Z","steps":["trace[1353443397] 'agreement among raft nodes before linearized reading' (duration: 114.093887ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:48:17.836613Z","caller":"traceutil/trace.go:171","msg":"trace[1389434418] transaction","detail":"{read_only:false; response_revision:1580; number_of_response:1; }","duration":"315.735243ms","start":"2026-05-12T18:48:17.52081Z","end":"2026-05-12T18:48:17.836545Z","steps":["trace[1389434418] 'process raft request' (duration: 315.515658ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:48:17.836988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:48:17.52079Z","time spent":"315.959578ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T18:48:17.836991Z","caller":"traceutil/trace.go:171","msg":"trace[1602643234] transaction","detail":"{read_only:false; response_revision:1581; number_of_response:1; }","duration":"315.719152ms","start":"2026-05-12T18:48:17.521239Z","end":"2026-05-12T18:48:17.836958Z","steps":["trace[1602643234] 'process raft request' (duration: 315.273712ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:48:17.837131Z","caller":"traceutil/trace.go:171","msg":"trace[247811105] linearizableReadLoop","detail":"{readStateIndex:1646; appliedIndex:1642; }","duration":"271.366775ms","start":"2026-05-12T18:48:17.565742Z","end":"2026-05-12T18:48:17.837109Z","steps":["trace[247811105] 'read index received' (duration: 38.916328ms)","trace[247811105] 'applied index is now lower than readState.Index' (duration: 232.449547ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T18:48:17.837161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:48:17.521228Z","time spent":"315.840036ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T18:48:17.837351Z","caller":"traceutil/trace.go:171","msg":"trace[803493511] transaction","detail":"{read_only:false; response_revision:1582; number_of_response:1; }","duration":"315.638511ms","start":"2026-05-12T18:48:17.521508Z","end":"2026-05-12T18:48:17.837146Z","steps":["trace[803493511] 'process raft request' (duration: 315.405545ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:48:17.837412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.643752ms","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-05-12T18:48:17.837583Z","caller":"traceutil/trace.go:171","msg":"trace[632142733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1582; }","duration":"271.853227ms","start":"2026-05-12T18:48:17.565713Z","end":"2026-05-12T18:48:17.837566Z","steps":["trace[632142733] 'agreement among raft nodes before linearized reading' (duration: 271.54983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:48:17.838518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T18:48:17.521472Z","time spent":"315.964509ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T18:48:18.114573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.810092ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T18:48:18.114681Z","caller":"traceutil/trace.go:171","msg":"trace[1093993012] linearizableReadLoop","detail":"{readStateIndex:1647; appliedIndex:1646; }","duration":"194.607466ms","start":"2026-05-12T18:48:17.920057Z","end":"2026-05-12T18:48:18.114664Z","steps":["trace[1093993012] 'read index received' (duration: 33.539238ms)","trace[1093993012] 'applied index is now lower than readState.Index' (duration: 161.066858ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T18:48:18.114856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.80385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-05-12T18:48:18.114891Z","caller":"traceutil/trace.go:171","msg":"trace[1901719132] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1583; }","duration":"194.876842ms","start":"2026-05-12T18:48:17.920002Z","end":"2026-05-12T18:48:18.114879Z","steps":["trace[1901719132] 'agreement among raft nodes before linearized reading' (duration: 194.727189ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:48:18.114868Z","caller":"traceutil/trace.go:171","msg":"trace[1820112621] transaction","detail":"{read_only:false; response_revision:1583; number_of_response:1; }","duration":"273.424773ms","start":"2026-05-12T18:48:17.841417Z","end":"2026-05-12T18:48:18.114842Z","steps":["trace[1820112621] 'process raft request' (duration: 112.264953ms)","trace[1820112621] 'compare' (duration: 160.628817ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T18:49:04.165176Z","caller":"traceutil/trace.go:171","msg":"trace[1481730637] transaction","detail":"{read_only:false; response_revision:1790; number_of_response:1; }","duration":"101.410294ms","start":"2026-05-12T18:49:04.063713Z","end":"2026-05-12T18:49:04.165123Z","steps":["trace[1481730637] 'process raft request' (duration: 101.24781ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:50:08.82049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.356744ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T18:50:08.820676Z","caller":"traceutil/trace.go:171","msg":"trace[149463734] transaction","detail":"{read_only:false; response_revision:2089; number_of_response:1; }","duration":"175.921482ms","start":"2026-05-12T18:50:08.644726Z","end":"2026-05-12T18:50:08.820647Z","steps":["trace[149463734] 'process raft request' (duration: 56.300012ms)","trace[149463734] 'compare' (duration: 119.106178ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T18:50:08.820954Z","caller":"traceutil/trace.go:171","msg":"trace[607744815] transaction","detail":"{read_only:false; response_revision:2090; number_of_response:1; }","duration":"174.11013ms","start":"2026-05-12T18:50:08.646824Z","end":"2026-05-12T18:50:08.820934Z","steps":["trace[607744815] 'process raft request' (duration: 173.927736ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:50:26.703141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.798264ms","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-05-12T18:50:26.703219Z","caller":"traceutil/trace.go:171","msg":"trace[327313568] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2172; }","duration":"135.923807ms","start":"2026-05-12T18:50:26.567276Z","end":"2026-05-12T18:50:26.7032Z","steps":["trace[327313568] 'range keys from in-memory index tree' (duration: 135.619529ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:50:36.018821Z","caller":"traceutil/trace.go:171","msg":"trace[498131271] transaction","detail":"{read_only:false; response_revision:2234; number_of_response:1; }","duration":"111.511838ms","start":"2026-05-12T18:50:35.907273Z","end":"2026-05-12T18:50:36.018785Z","steps":["trace[498131271] 'process raft request' (duration: 111.344584ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T18:50:48.66831Z","caller":"traceutil/trace.go:171","msg":"trace[797123281] linearizableReadLoop","detail":"{readStateIndex:2395; appliedIndex:2394; }","duration":"195.892005ms","start":"2026-05-12T18:50:48.4724Z","end":"2026-05-12T18:50:48.668292Z","steps":["trace[797123281] 'read index received' (duration: 195.634769ms)","trace[797123281] 'applied index is now lower than readState.Index' (duration: 256.376µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T18:50:48.668361Z","caller":"traceutil/trace.go:171","msg":"trace[690078303] transaction","detail":"{read_only:false; response_revision:2300; number_of_response:1; }","duration":"284.089841ms","start":"2026-05-12T18:50:48.384245Z","end":"2026-05-12T18:50:48.668335Z","steps":["trace[690078303] 'process raft request' (duration: 283.880746ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T18:50:48.668519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.106281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-12T18:50:48.668545Z","caller":"traceutil/trace.go:171","msg":"trace[1451365599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2300; }","duration":"196.187972ms","start":"2026-05-12T18:50:48.47235Z","end":"2026-05-12T18:50:48.668538Z","steps":["trace[1451365599] 'agreement among raft nodes before linearized reading' (duration: 196.067249ms)"],"step_count":1}