{"level":"warn","ts":"2026-04-23T09:55:08.662938Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-23T09:55:08.663055Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.89: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.89:2380","--initial-cluster=instance=https://199.204.45.89:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.89:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.89:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-23T09:55:08.663116Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-23T09:55:08.66313Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-04-23T09:55:08.663148Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-23T09:55:08.663802Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"]} {"level":"info","ts":"2026-04-23T09:55:08.663877Z","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.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.89:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-23T09:55:08.67361Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.555453ms"} {"level":"info","ts":"2026-04-23T09:55:08.692278Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c3b8446219deb8c1","cluster-id":"fe02a46d8aa9751f"} {"level":"info","ts":"2026-04-23T09:55:08.692354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=()"} {"level":"info","ts":"2026-04-23T09:55:08.692376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 0"} {"level":"info","ts":"2026-04-23T09:55:08.692391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c3b8446219deb8c1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-23T09:55:08.6924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 1"} {"level":"info","ts":"2026-04-23T09:55:08.692446Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"warn","ts":"2026-04-23T09:55:08.709444Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-23T09:55:08.713283Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-23T09:55:08.717939Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-23T09:55:08.720793Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c3b8446219deb8c1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-23T09:55:08.721009Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c3b8446219deb8c1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-23T09:55:08.721158Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-23T09:55:08.7213Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-23T09:55:08.72139Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-23T09:55:08.727288Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"info","ts":"2026-04-23T09:55:08.727565Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","added-peer-id":"c3b8446219deb8c1","added-peer-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-04-23T09:55:08.730651Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-23T09:55:08.730715Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-04-23T09:55:08.730846Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-04-23T09:55:08.73182Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c3b8446219deb8c1","initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-23T09:55:08.731869Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-23T09:55:08.793452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 is starting a new election at term 1"} {"level":"info","ts":"2026-04-23T09:55:08.793494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-23T09:55:08.793511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgPreVoteResp from c3b8446219deb8c1 at term 1"} {"level":"info","ts":"2026-04-23T09:55:08.793523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became candidate at term 2"} {"level":"info","ts":"2026-04-23T09:55:08.793531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgVoteResp from c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-04-23T09:55:08.793541Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became leader at term 2"} {"level":"info","ts":"2026-04-23T09:55:08.793549Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c3b8446219deb8c1 elected leader c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-04-23T09:55:08.796574Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T09:55:08.796792Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-23T09:55:08.796908Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-23T09:55:08.796865Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-23T09:55:08.796957Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-23T09:55:08.796449Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c3b8446219deb8c1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.89:2379]}","request-path":"/0/members/c3b8446219deb8c1/attributes","cluster-id":"fe02a46d8aa9751f","publish-timeout":"7s"} {"level":"info","ts":"2026-04-23T09:55:08.797811Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T09:55:08.797897Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T09:55:08.797919Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T09:55:08.799111Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-23T09:55:08.799865Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.89:2379"} {"level":"warn","ts":"2026-04-23T09:55:10.915654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.787586ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-23T09:55:10.916231Z","caller":"traceutil/trace.go:171","msg":"trace[641654477] transaction","detail":"{read_only:false; response_revision:48; number_of_response:1; }","duration":"285.807621ms","start":"2026-04-23T09:55:10.630407Z","end":"2026-04-23T09:55:10.916215Z","steps":["trace[641654477] 'process raft request' (duration: 285.512309ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:10.916039Z","caller":"traceutil/trace.go:171","msg":"trace[1342293517] linearizableReadLoop","detail":"{readStateIndex:52; appliedIndex:50; }","duration":"179.924385ms","start":"2026-04-23T09:55:10.736088Z","end":"2026-04-23T09:55:10.916012Z","steps":["trace[1342293517] 'read index received' (duration: 33.049102ms)","trace[1342293517] 'applied index is now lower than readState.Index' (duration: 146.874123ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T09:55:10.916074Z","caller":"traceutil/trace.go:171","msg":"trace[885717656] transaction","detail":"{read_only:false; response_revision:47; number_of_response:1; }","duration":"290.596567ms","start":"2026-04-23T09:55:10.62541Z","end":"2026-04-23T09:55:10.916007Z","steps":["trace[885717656] 'process raft request' (duration: 143.710074ms)","trace[885717656] 'compare' (duration: 145.503505ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T09:55:10.916485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.299276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-23T09:55:10.91659Z","caller":"traceutil/trace.go:171","msg":"trace[1304887840] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:48; }","duration":"180.559858ms","start":"2026-04-23T09:55:10.736006Z","end":"2026-04-23T09:55:10.916566Z","steps":["trace[1304887840] 'agreement among raft nodes before linearized reading' (duration: 180.284636ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:14.351552Z","caller":"traceutil/trace.go:171","msg":"trace[2137985735] transaction","detail":"{read_only:false; response_revision:212; number_of_response:1; }","duration":"562.008447ms","start":"2026-04-23T09:55:13.789523Z","end":"2026-04-23T09:55:14.351531Z","steps":["trace[2137985735] 'process raft request' (duration: 561.851787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:14.351876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:55:13.789502Z","time spent":"562.122219ms","remote":"127.0.0.1:42016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4002,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T09:55:14.646059Z","caller":"traceutil/trace.go:171","msg":"trace[1164282371] linearizableReadLoop","detail":"{readStateIndex:219; appliedIndex:216; }","duration":"384.517445ms","start":"2026-04-23T09:55:14.261477Z","end":"2026-04-23T09:55:14.645995Z","steps":["trace[1164282371] 'read index received' (duration: 88.454949ms)","trace[1164282371] 'applied index is now lower than readState.Index' (duration: 296.061366ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T09:55:14.646211Z","caller":"traceutil/trace.go:171","msg":"trace[335146847] transaction","detail":"{read_only:false; response_revision:214; number_of_response:1; }","duration":"466.146178ms","start":"2026-04-23T09:55:14.18005Z","end":"2026-04-23T09:55:14.646196Z","steps":["trace[335146847] 'process raft request' (duration: 465.865557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:14.646334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:55:14.180028Z","time spent":"466.214698ms","remote":"127.0.0.1:42020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5877,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T09:55:14.646446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.934038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T09:55:14.646528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.629068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-j4w71d\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T09:55:14.646689Z","caller":"traceutil/trace.go:171","msg":"trace[853396339] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-j4w71d; range_end:; response_count:0; response_revision:214; }","duration":"290.822618ms","start":"2026-04-23T09:55:14.355836Z","end":"2026-04-23T09:55:14.646658Z","steps":["trace[853396339] 'agreement among raft nodes before linearized reading' (duration: 290.476907ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:14.646079Z","caller":"traceutil/trace.go:171","msg":"trace[1168327521] transaction","detail":"{read_only:false; response_revision:213; number_of_response:1; }","duration":"666.704226ms","start":"2026-04-23T09:55:13.979339Z","end":"2026-04-23T09:55:14.646043Z","steps":["trace[1168327521] 'process raft request' (duration: 665.51178ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:14.647373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:55:13.979304Z","time spent":"667.972463ms","remote":"127.0.0.1:42020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T09:55:14.646715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.960329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T09:55:14.648439Z","caller":"traceutil/trace.go:171","msg":"trace[1274624670] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:214; }","duration":"151.690919ms","start":"2026-04-23T09:55:14.496708Z","end":"2026-04-23T09:55:14.648399Z","steps":["trace[1274624670] 'agreement among raft nodes before linearized reading' (duration: 149.942299ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:14.646596Z","caller":"traceutil/trace.go:171","msg":"trace[151194626] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:214; }","duration":"385.12754ms","start":"2026-04-23T09:55:14.261436Z","end":"2026-04-23T09:55:14.646563Z","steps":["trace[151194626] 'agreement among raft nodes before linearized reading' (duration: 384.899229ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:14.648618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:55:14.261414Z","time spent":"387.177301ms","remote":"127.0.0.1:42102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-23T09:55:15.344875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.145442ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T09:55:15.345348Z","caller":"traceutil/trace.go:171","msg":"trace[214494093] transaction","detail":"{read_only:false; response_revision:222; number_of_response:1; }","duration":"601.455056ms","start":"2026-04-23T09:55:14.743863Z","end":"2026-04-23T09:55:15.345318Z","steps":["trace[214494093] 'process raft request' (duration: 601.396875ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:15.345432Z","caller":"traceutil/trace.go:171","msg":"trace[353626489] transaction","detail":"{read_only:false; response_revision:220; number_of_response:1; }","duration":"604.280372ms","start":"2026-04-23T09:55:14.741142Z","end":"2026-04-23T09:55:15.345422Z","steps":["trace[353626489] 'process raft request' (duration: 604.035311ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:15.345538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:55:14.741069Z","time spent":"604.401732ms","remote":"127.0.0.1:41910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":705,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T09:55:15.345561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:55:14.743844Z","time spent":"601.606076ms","remote":"127.0.0.1:42102","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-04-23T09:55:15.345728Z","caller":"traceutil/trace.go:171","msg":"trace[510116303] transaction","detail":"{read_only:false; response_revision:219; number_of_response:1; }","duration":"668.924888ms","start":"2026-04-23T09:55:14.676793Z","end":"2026-04-23T09:55:15.345718Z","steps":["trace[510116303] 'process raft request' (duration: 668.299025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:15.345787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:55:14.676769Z","time spent":"668.99222ms","remote":"127.0.0.1:42164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":290,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T09:55:15.345843Z","caller":"traceutil/trace.go:171","msg":"trace[204241118] linearizableReadLoop","detail":"{readStateIndex:225; appliedIndex:223; }","duration":"605.75958ms","start":"2026-04-23T09:55:14.740065Z","end":"2026-04-23T09:55:15.345825Z","steps":["trace[204241118] 'read index received' (duration: 16.677963ms)","trace[204241118] 'applied index is now lower than readState.Index' (duration: 589.079997ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T09:55:15.346001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.313021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T09:55:15.346037Z","caller":"traceutil/trace.go:171","msg":"trace[1585826782] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:222; }","duration":"669.372321ms","start":"2026-04-23T09:55:14.676655Z","end":"2026-04-23T09:55:15.346027Z","steps":["trace[1585826782] 'agreement among raft nodes before linearized reading' (duration: 669.280471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:15.34607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:55:14.676648Z","time spent":"669.414571ms","remote":"127.0.0.1:41840","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-04-23T09:55:15.346088Z","caller":"traceutil/trace.go:171","msg":"trace[1315999476] transaction","detail":"{read_only:false; response_revision:221; number_of_response:1; }","duration":"604.862444ms","start":"2026-04-23T09:55:14.741197Z","end":"2026-04-23T09:55:15.34606Z","steps":["trace[1315999476] 'process raft request' (duration: 604.02365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:15.346109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.201608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T09:55:15.346178Z","caller":"traceutil/trace.go:171","msg":"trace[142137807] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:222; }","duration":"562.299029ms","start":"2026-04-23T09:55:14.783858Z","end":"2026-04-23T09:55:15.346157Z","steps":["trace[142137807] 'agreement among raft nodes before linearized reading' (duration: 562.198458ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:15.346225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:55:14.74117Z","time spent":"604.985926ms","remote":"127.0.0.1:41910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":753,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T09:55:15.34624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:55:14.783841Z","time spent":"562.385939ms","remote":"127.0.0.1:42102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-23T09:55:15.345363Z","caller":"traceutil/trace.go:171","msg":"trace[959037972] transaction","detail":"{read_only:false; response_revision:218; number_of_response:1; }","duration":"668.883349ms","start":"2026-04-23T09:55:14.676447Z","end":"2026-04-23T09:55:15.34533Z","steps":["trace[959037972] 'process raft request' (duration: 80.169273ms)","trace[959037972] 'compare' (duration: 588.033901ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T09:55:15.346489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:55:14.676409Z","time spent":"670.036295ms","remote":"127.0.0.1:42102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T09:55:15.622133Z","caller":"traceutil/trace.go:171","msg":"trace[756281166] transaction","detail":"{read_only:false; response_revision:227; number_of_response:1; }","duration":"184.57926ms","start":"2026-04-23T09:55:15.437526Z","end":"2026-04-23T09:55:15.622105Z","steps":["trace[756281166] 'process raft request' (duration: 165.200353ms)","trace[756281166] 'compare' (duration: 19.215336ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T09:55:15.622462Z","caller":"traceutil/trace.go:171","msg":"trace[390548547] transaction","detail":"{read_only:false; response_revision:229; number_of_response:1; }","duration":"184.067498ms","start":"2026-04-23T09:55:15.438385Z","end":"2026-04-23T09:55:15.622453Z","steps":["trace[390548547] 'process raft request' (duration: 184.022548ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:15.622818Z","caller":"traceutil/trace.go:171","msg":"trace[481813567] transaction","detail":"{read_only:false; response_revision:228; number_of_response:1; }","duration":"184.974273ms","start":"2026-04-23T09:55:15.437795Z","end":"2026-04-23T09:55:15.622769Z","steps":["trace[481813567] 'process raft request' (duration: 184.559621ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:15.994129Z","caller":"traceutil/trace.go:171","msg":"trace[156050068] transaction","detail":"{read_only:false; response_revision:233; number_of_response:1; }","duration":"344.307074ms","start":"2026-04-23T09:55:15.64979Z","end":"2026-04-23T09:55:15.994097Z","steps":["trace[156050068] 'process raft request' (duration: 343.958072ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:15.993981Z","caller":"traceutil/trace.go:171","msg":"trace[1162447607] linearizableReadLoop","detail":"{readStateIndex:239; appliedIndex:238; }","duration":"338.720993ms","start":"2026-04-23T09:55:15.65524Z","end":"2026-04-23T09:55:15.993961Z","steps":["trace[1162447607] 'read index received' (duration: 338.591073ms)","trace[1162447607] 'applied index is now lower than readState.Index' (duration: 129.03µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T09:55:15.994283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:55:15.649771Z","time spent":"344.436125ms","remote":"127.0.0.1:42152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":356,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T09:55:15.994298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.020595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T09:55:15.994558Z","caller":"traceutil/trace.go:171","msg":"trace[646976412] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:0; response_revision:233; }","duration":"339.348056ms","start":"2026-04-23T09:55:15.655198Z","end":"2026-04-23T09:55:15.994546Z","steps":["trace[646976412] 'agreement among raft nodes before linearized reading' (duration: 338.988895ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:15.994607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:55:15.655185Z","time spent":"339.407396ms","remote":"127.0.0.1:42034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-04-23T09:55:21.531883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.176065ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T09:55:21.532195Z","caller":"traceutil/trace.go:171","msg":"trace[487135032] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"155.644282ms","start":"2026-04-23T09:55:21.376507Z","end":"2026-04-23T09:55:21.532151Z","steps":["trace[487135032] 'process raft request' (duration: 34.394861ms)","trace[487135032] 'compare' (duration: 119.964994ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T09:55:21.532308Z","caller":"traceutil/trace.go:171","msg":"trace[750519728] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"155.790922ms","start":"2026-04-23T09:55:21.376506Z","end":"2026-04-23T09:55:21.532297Z","steps":["trace[750519728] 'process raft request' (duration: 155.575021ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:21.532884Z","caller":"traceutil/trace.go:171","msg":"trace[1368669892] linearizableReadLoop","detail":"{readStateIndex:367; appliedIndex:364; }","duration":"101.935054ms","start":"2026-04-23T09:55:21.430924Z","end":"2026-04-23T09:55:21.532859Z","steps":["trace[1368669892] 'read index received' (duration: 44.282885ms)","trace[1368669892] 'applied index is now lower than readState.Index' (duration: 57.650279ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T09:55:21.53306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.131485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-23T09:55:21.533794Z","caller":"traceutil/trace.go:171","msg":"trace[1510607624] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:354; }","duration":"102.90223ms","start":"2026-04-23T09:55:21.430872Z","end":"2026-04-23T09:55:21.533774Z","steps":["trace[1510607624] 'agreement among raft nodes before linearized reading' (duration: 102.120855ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:21.552683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.51336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-qfzhq\" ","response":"range_response_count:1 size:4462"} {"level":"info","ts":"2026-04-23T09:55:21.552795Z","caller":"traceutil/trace.go:171","msg":"trace[6529999] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-qfzhq; range_end:; response_count:1; response_revision:355; }","duration":"106.67168ms","start":"2026-04-23T09:55:21.446108Z","end":"2026-04-23T09:55:21.55278Z","steps":["trace[6529999] 'agreement among raft nodes before linearized reading' (duration: 106.494769ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:22.637077Z","caller":"traceutil/trace.go:171","msg":"trace[1915549344] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"103.853704ms","start":"2026-04-23T09:55:22.533204Z","end":"2026-04-23T09:55:22.637057Z","steps":["trace[1915549344] 'process raft request' (duration: 68.61116ms)","trace[1915549344] 'compare' (duration: 35.138804ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T09:55:22.637824Z","caller":"traceutil/trace.go:171","msg":"trace[220446848] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"103.14498ms","start":"2026-04-23T09:55:22.534671Z","end":"2026-04-23T09:55:22.637816Z","steps":["trace[220446848] 'process raft request' (duration: 103.11306ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:22.637965Z","caller":"traceutil/trace.go:171","msg":"trace[656790230] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"104.390077ms","start":"2026-04-23T09:55:22.533562Z","end":"2026-04-23T09:55:22.637952Z","steps":["trace[656790230] 'process raft request' (duration: 104.185056ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:22.835691Z","caller":"traceutil/trace.go:171","msg":"trace[1861490584] linearizableReadLoop","detail":"{readStateIndex:388; appliedIndex:387; }","duration":"118.121144ms","start":"2026-04-23T09:55:22.717542Z","end":"2026-04-23T09:55:22.835663Z","steps":["trace[1861490584] 'read index received' (duration: 44.982579ms)","trace[1861490584] 'applied index is now lower than readState.Index' (duration: 73.137435ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T09:55:22.835788Z","caller":"traceutil/trace.go:171","msg":"trace[1759571826] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"118.408565ms","start":"2026-04-23T09:55:22.717337Z","end":"2026-04-23T09:55:22.835745Z","steps":["trace[1759571826] 'process raft request' (duration: 45.17738ms)","trace[1759571826] 'compare' (duration: 73.021143ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T09:55:22.835864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.294575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-67659f764b\" ","response":"range_response_count:1 size:3810"} {"level":"info","ts":"2026-04-23T09:55:22.835898Z","caller":"traceutil/trace.go:171","msg":"trace[1237664038] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-67659f764b; range_end:; response_count:1; response_revision:374; }","duration":"118.364955ms","start":"2026-04-23T09:55:22.717522Z","end":"2026-04-23T09:55:22.835887Z","steps":["trace[1237664038] 'agreement among raft nodes before linearized reading' (duration: 118.269935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:23.305887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.483085ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T09:55:23.306024Z","caller":"traceutil/trace.go:171","msg":"trace[720937498] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"582.136678ms","start":"2026-04-23T09:55:22.723855Z","end":"2026-04-23T09:55:23.305991Z","steps":["trace[720937498] 'process raft request' (duration: 293.429412ms)","trace[720937498] 'compare' (duration: 288.316154ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T09:55:23.306094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:55:22.723836Z","time spent":"582.22868ms","remote":"127.0.0.1:42286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3795,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T09:55:23.45402Z","caller":"traceutil/trace.go:171","msg":"trace[598802114] transaction","detail":"{read_only:false; number_of_response:1; response_revision:375; }","duration":"616.553709ms","start":"2026-04-23T09:55:22.837429Z","end":"2026-04-23T09:55:23.453982Z","steps":["trace[598802114] 'process raft request' (duration: 599.909347ms)","trace[598802114] 'compare' (duration: 16.449241ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T09:55:23.454506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:55:22.837402Z","time spent":"616.951321ms","remote":"127.0.0.1:42020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":55,"response count":0,"response size":4063,"request content":"compare: success: > failure: >"} {"level":"info","ts":"2026-04-23T09:55:23.454959Z","caller":"traceutil/trace.go:171","msg":"trace[169248468] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"615.02675ms","start":"2026-04-23T09:55:22.839914Z","end":"2026-04-23T09:55:23.454941Z","steps":["trace[169248468] 'process raft request' (duration: 614.460817ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:23.455003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:55:22.839886Z","time spent":"615.09001ms","remote":"127.0.0.1:41910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":721,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T09:55:23.455187Z","caller":"traceutil/trace.go:171","msg":"trace[709686376] linearizableReadLoop","detail":"{readStateIndex:392; appliedIndex:389; }","duration":"148.36567ms","start":"2026-04-23T09:55:23.306751Z","end":"2026-04-23T09:55:23.455117Z","steps":["trace[709686376] 'read index received' (duration: 130.634472ms)","trace[709686376] 'applied index is now lower than readState.Index' (duration: 17.729528ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T09:55:23.455239Z","caller":"traceutil/trace.go:171","msg":"trace[731848193] transaction","detail":"{read_only:false; number_of_response:1; response_revision:376; }","duration":"612.218646ms","start":"2026-04-23T09:55:22.84301Z","end":"2026-04-23T09:55:23.455228Z","steps":["trace[731848193] 'process raft request' (duration: 611.730063ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:23.455287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:55:22.842986Z","time spent":"612.280926ms","remote":"127.0.0.1:42286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":56,"response count":0,"response size":3845,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T09:55:23.456342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.531517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T09:55:23.456434Z","caller":"traceutil/trace.go:171","msg":"trace[493468481] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:376; }","duration":"149.678418ms","start":"2026-04-23T09:55:23.306718Z","end":"2026-04-23T09:55:23.456396Z","steps":["trace[493468481] 'agreement among raft nodes before linearized reading' (duration: 148.533922ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:23.459665Z","caller":"traceutil/trace.go:171","msg":"trace[986778680] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"147.317474ms","start":"2026-04-23T09:55:23.312336Z","end":"2026-04-23T09:55:23.459653Z","steps":["trace[986778680] 'process raft request' (duration: 146.952962ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:23.459519Z","caller":"traceutil/trace.go:171","msg":"trace[61650805] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"149.566767ms","start":"2026-04-23T09:55:23.309932Z","end":"2026-04-23T09:55:23.459499Z","steps":["trace[61650805] 'process raft request' (duration: 149.266336ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:27.141931Z","caller":"traceutil/trace.go:171","msg":"trace[1405220941] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"186.921154ms","start":"2026-04-23T09:55:26.954989Z","end":"2026-04-23T09:55:27.14191Z","steps":["trace[1405220941] 'process raft request' (duration: 186.733243ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:27.274176Z","caller":"traceutil/trace.go:171","msg":"trace[371315229] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"119.471401ms","start":"2026-04-23T09:55:27.154678Z","end":"2026-04-23T09:55:27.274149Z","steps":["trace[371315229] 'process raft request' (duration: 115.64154ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:33.843074Z","caller":"traceutil/trace.go:171","msg":"trace[1897593773] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"200.581063ms","start":"2026-04-23T09:55:33.642468Z","end":"2026-04-23T09:55:33.84305Z","steps":["trace[1897593773] 'process raft request' (duration: 200.445698ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:33.854777Z","caller":"traceutil/trace.go:171","msg":"trace[815882544] linearizableReadLoop","detail":"{readStateIndex:439; appliedIndex:437; }","duration":"200.47234ms","start":"2026-04-23T09:55:33.654285Z","end":"2026-04-23T09:55:33.854757Z","steps":["trace[815882544] 'read index received' (duration: 188.123761ms)","trace[815882544] 'applied index is now lower than readState.Index' (duration: 12.347389ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T09:55:33.854857Z","caller":"traceutil/trace.go:171","msg":"trace[368142762] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"209.535139ms","start":"2026-04-23T09:55:33.645307Z","end":"2026-04-23T09:55:33.854843Z","steps":["trace[368142762] 'process raft request' (duration: 209.339662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:33.854921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.607664ms","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-04-23T09:55:33.854956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.513254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-23T09:55:33.854973Z","caller":"traceutil/trace.go:171","msg":"trace[789957875] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:421; }","duration":"200.709508ms","start":"2026-04-23T09:55:33.654254Z","end":"2026-04-23T09:55:33.854964Z","steps":["trace[789957875] 'agreement among raft nodes before linearized reading' (duration: 200.612325ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:33.854992Z","caller":"traceutil/trace.go:171","msg":"trace[978807501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:421; }","duration":"107.553656ms","start":"2026-04-23T09:55:33.747426Z","end":"2026-04-23T09:55:33.85498Z","steps":["trace[978807501] 'agreement among raft nodes before linearized reading' (duration: 107.493854ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:38.056926Z","caller":"traceutil/trace.go:171","msg":"trace[1120684299] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"289.764641ms","start":"2026-04-23T09:55:37.767121Z","end":"2026-04-23T09:55:38.056886Z","steps":["trace[1120684299] 'process raft request' (duration: 289.588785ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:38.057033Z","caller":"traceutil/trace.go:171","msg":"trace[165020054] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"260.271191ms","start":"2026-04-23T09:55:37.796714Z","end":"2026-04-23T09:55:38.056985Z","steps":["trace[165020054] 'process raft request' (duration: 260.113835ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:38.057048Z","caller":"traceutil/trace.go:171","msg":"trace[301960364] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"260.216809ms","start":"2026-04-23T09:55:37.796805Z","end":"2026-04-23T09:55:38.057022Z","steps":["trace[301960364] 'process raft request' (duration: 260.056883ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:38.057074Z","caller":"traceutil/trace.go:171","msg":"trace[3832815] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"273.961818ms","start":"2026-04-23T09:55:37.783059Z","end":"2026-04-23T09:55:38.057021Z","steps":["trace[3832815] 'process raft request' (duration: 273.691888ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:38.057193Z","caller":"traceutil/trace.go:171","msg":"trace[125708854] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"260.279841ms","start":"2026-04-23T09:55:37.796903Z","end":"2026-04-23T09:55:38.057183Z","steps":["trace[125708854] 'process raft request' (duration: 260.096164ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:38.057221Z","caller":"traceutil/trace.go:171","msg":"trace[558955192] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"243.905256ms","start":"2026-04-23T09:55:37.813308Z","end":"2026-04-23T09:55:38.057214Z","steps":["trace[558955192] 'process raft request' (duration: 243.72995ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:38.057238Z","caller":"traceutil/trace.go:171","msg":"trace[1393974283] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"260.374154ms","start":"2026-04-23T09:55:37.796858Z","end":"2026-04-23T09:55:38.057233Z","steps":["trace[1393974283] 'process raft request' (duration: 260.100425ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:38.05726Z","caller":"traceutil/trace.go:171","msg":"trace[386489986] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"294.147741ms","start":"2026-04-23T09:55:37.763102Z","end":"2026-04-23T09:55:38.05725Z","steps":["trace[386489986] 'process raft request' (duration: 293.479747ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:38.057277Z","caller":"traceutil/trace.go:171","msg":"trace[2082040205] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"238.028303ms","start":"2026-04-23T09:55:37.819243Z","end":"2026-04-23T09:55:38.057272Z","steps":["trace[2082040205] 'process raft request' (duration: 237.872927ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:38.057354Z","caller":"traceutil/trace.go:171","msg":"trace[1701884357] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"217.311071ms","start":"2026-04-23T09:55:37.840012Z","end":"2026-04-23T09:55:38.057323Z","steps":["trace[1701884357] 'process raft request' (duration: 217.181716ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:38.056992Z","caller":"traceutil/trace.go:171","msg":"trace[441534646] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"260.288952ms","start":"2026-04-23T09:55:37.796644Z","end":"2026-04-23T09:55:38.056933Z","steps":["trace[441534646] 'process raft request' (duration: 260.141957ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:38.073161Z","caller":"traceutil/trace.go:171","msg":"trace[1679109828] linearizableReadLoop","detail":"{readStateIndex:493; appliedIndex:481; }","duration":"214.748137ms","start":"2026-04-23T09:55:37.858398Z","end":"2026-04-23T09:55:38.073146Z","steps":["trace[1679109828] 'read index received' (duration: 185.028058ms)","trace[1679109828] 'applied index is now lower than readState.Index' (duration: 29.719469ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T09:55:38.073226Z","caller":"traceutil/trace.go:171","msg":"trace[1391953818] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"137.524244ms","start":"2026-04-23T09:55:37.935696Z","end":"2026-04-23T09:55:38.07322Z","steps":["trace[1391953818] 'process raft request' (duration: 121.608406ms)","trace[1391953818] 'compare' (duration: 15.735941ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T09:55:38.073355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.964516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-23T09:55:38.073378Z","caller":"traceutil/trace.go:171","msg":"trace[1937834529] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:475; }","duration":"215.042879ms","start":"2026-04-23T09:55:37.858328Z","end":"2026-04-23T09:55:38.073371Z","steps":["trace[1937834529] 'agreement among raft nodes before linearized reading' (duration: 214.971856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:38.079903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.042865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-23T09:55:38.079951Z","caller":"traceutil/trace.go:171","msg":"trace[232476261] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:479; }","duration":"205.128448ms","start":"2026-04-23T09:55:37.874812Z","end":"2026-04-23T09:55:38.079941Z","steps":["trace[232476261] 'agreement among raft nodes before linearized reading' (duration: 205.012264ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:38.080314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.05447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-23T09:55:38.080334Z","caller":"traceutil/trace.go:171","msg":"trace[1021821901] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:479; }","duration":"193.087872ms","start":"2026-04-23T09:55:37.887242Z","end":"2026-04-23T09:55:38.08033Z","steps":["trace[1021821901] 'agreement among raft nodes before linearized reading' (duration: 193.04136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:38.08041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.19033ms","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-04-23T09:55:38.080426Z","caller":"traceutil/trace.go:171","msg":"trace[133705689] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:479; }","duration":"205.226112ms","start":"2026-04-23T09:55:37.875196Z","end":"2026-04-23T09:55:38.080422Z","steps":["trace[133705689] 'agreement among raft nodes before linearized reading' (duration: 205.18469ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:40.617747Z","caller":"traceutil/trace.go:171","msg":"trace[443748598] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"211.437528ms","start":"2026-04-23T09:55:40.406282Z","end":"2026-04-23T09:55:40.61772Z","steps":["trace[443748598] 'process raft request' (duration: 211.067805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:41.562933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.756946ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-23T09:55:41.563088Z","caller":"traceutil/trace.go:171","msg":"trace[1094801670] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"199.155402ms","start":"2026-04-23T09:55:41.363908Z","end":"2026-04-23T09:55:41.563063Z","steps":["trace[1094801670] 'process raft request' (duration: 35.196309ms)","trace[1094801670] 'compare' (duration: 163.572869ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T09:55:41.590233Z","caller":"traceutil/trace.go:171","msg":"trace[1179811158] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"144.391613ms","start":"2026-04-23T09:55:41.445784Z","end":"2026-04-23T09:55:41.590176Z","steps":["trace[1179811158] 'process raft request' (duration: 144.150014ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:43.496819Z","caller":"traceutil/trace.go:171","msg":"trace[730776166] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:586; }","duration":"191.499933ms","start":"2026-04-23T09:55:43.305295Z","end":"2026-04-23T09:55:43.496795Z","steps":["trace[730776166] 'read index received' (duration: 189.021883ms)","trace[730776166] 'applied index is now lower than readState.Index' (duration: 2.47716ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T09:55:43.496994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.673839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T09:55:43.49703Z","caller":"traceutil/trace.go:171","msg":"trace[706599420] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:563; }","duration":"191.751492ms","start":"2026-04-23T09:55:43.305267Z","end":"2026-04-23T09:55:43.497018Z","steps":["trace[706599420] 'agreement among raft nodes before linearized reading' (duration: 191.623238ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:43.497013Z","caller":"traceutil/trace.go:171","msg":"trace[425755884] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"403.247282ms","start":"2026-04-23T09:55:43.093727Z","end":"2026-04-23T09:55:43.496974Z","steps":["trace[425755884] 'process raft request' (duration: 401.297571ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:43.49771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:55:43.093693Z","time spent":"403.431189ms","remote":"127.0.0.1:41924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036117,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T09:55:44.280798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.607613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-04-23T09:55:44.280799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.41625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-23T09:55:44.280858Z","caller":"traceutil/trace.go:171","msg":"trace[1263235762] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:564; }","duration":"176.693816ms","start":"2026-04-23T09:55:44.10415Z","end":"2026-04-23T09:55:44.280844Z","steps":["trace[1263235762] 'range keys from in-memory index tree' (duration: 176.490108ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:44.280883Z","caller":"traceutil/trace.go:171","msg":"trace[552337402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:564; }","duration":"134.550336ms","start":"2026-04-23T09:55:44.14632Z","end":"2026-04-23T09:55:44.28087Z","steps":["trace[552337402] 'range keys from in-memory index tree' (duration: 134.266765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:44.280802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.390249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-23T09:55:44.28094Z","caller":"traceutil/trace.go:171","msg":"trace[542229493] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:564; }","duration":"172.571226ms","start":"2026-04-23T09:55:44.108353Z","end":"2026-04-23T09:55:44.280924Z","steps":["trace[542229493] 'range keys from in-memory index tree' (duration: 172.322517ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:44.280997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.986946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-23T09:55:44.28113Z","caller":"traceutil/trace.go:171","msg":"trace[663506735] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:564; }","duration":"169.132371ms","start":"2026-04-23T09:55:44.111969Z","end":"2026-04-23T09:55:44.281102Z","steps":["trace[663506735] 'range keys from in-memory index tree' (duration: 168.850311ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:45.045381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:55:44.625979Z","time spent":"419.393858ms","remote":"127.0.0.1:60620","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-23T09:55:45.048011Z","caller":"traceutil/trace.go:171","msg":"trace[2080803482] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"563.073545ms","start":"2026-04-23T09:55:44.484915Z","end":"2026-04-23T09:55:45.047988Z","steps":["trace[2080803482] 'process raft request' (duration: 562.517115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:45.048142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:55:44.484887Z","time spent":"563.185979ms","remote":"127.0.0.1:41924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036153,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T09:55:45.644934Z","caller":"traceutil/trace.go:171","msg":"trace[1783676703] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"112.853187ms","start":"2026-04-23T09:55:45.532057Z","end":"2026-04-23T09:55:45.64491Z","steps":["trace[1783676703] 'process raft request' (duration: 112.715892ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:45.755185Z","caller":"traceutil/trace.go:171","msg":"trace[1072849238] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"154.101225ms","start":"2026-04-23T09:55:45.60106Z","end":"2026-04-23T09:55:45.755162Z","steps":["trace[1072849238] 'process raft request' (duration: 145.314217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:46.008341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.051783ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-23T09:55:46.00844Z","caller":"traceutil/trace.go:171","msg":"trace[852332313] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"192.300882ms","start":"2026-04-23T09:55:45.816121Z","end":"2026-04-23T09:55:46.008422Z","steps":["trace[852332313] 'process raft request' (duration: 81.102704ms)","trace[852332313] 'compare' (duration: 110.765973ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T09:55:46.692192Z","caller":"traceutil/trace.go:171","msg":"trace[602146770] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:607; }","duration":"168.671294ms","start":"2026-04-23T09:55:46.523496Z","end":"2026-04-23T09:55:46.692167Z","steps":["trace[602146770] 'read index received' (duration: 167.892356ms)","trace[602146770] 'applied index is now lower than readState.Index' (duration: 777.807µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T09:55:46.692284Z","caller":"traceutil/trace.go:171","msg":"trace[1632103410] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"254.992898ms","start":"2026-04-23T09:55:46.437253Z","end":"2026-04-23T09:55:46.692246Z","steps":["trace[1632103410] 'process raft request' (duration: 254.342905ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:46.692506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.985815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-98zzk\" ","response":"range_response_count:1 size:17243"} {"level":"info","ts":"2026-04-23T09:55:46.692584Z","caller":"traceutil/trace.go:171","msg":"trace[353444862] range","detail":"{range_begin:/registry/pods/kube-system/cilium-98zzk; range_end:; response_count:1; response_revision:584; }","duration":"169.10879ms","start":"2026-04-23T09:55:46.523456Z","end":"2026-04-23T09:55:46.692565Z","steps":["trace[353444862] 'agreement among raft nodes before linearized reading' (duration: 168.807769ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:46.73204Z","caller":"traceutil/trace.go:171","msg":"trace[1741801803] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"207.534216ms","start":"2026-04-23T09:55:46.524488Z","end":"2026-04-23T09:55:46.732022Z","steps":["trace[1741801803] 'process raft request' (duration: 207.437383ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:51.116583Z","caller":"traceutil/trace.go:171","msg":"trace[1601165633] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:678; }","duration":"194.028595ms","start":"2026-04-23T09:55:50.922521Z","end":"2026-04-23T09:55:51.11655Z","steps":["trace[1601165633] 'read index received' (duration: 193.133122ms)","trace[1601165633] 'applied index is now lower than readState.Index' (duration: 894.283µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T09:55:51.120751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.151815ms","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-04-23T09:55:51.120843Z","caller":"traceutil/trace.go:171","msg":"trace[2070350093] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:654; }","duration":"198.28367ms","start":"2026-04-23T09:55:50.92254Z","end":"2026-04-23T09:55:51.120823Z","steps":["trace[2070350093] 'agreement among raft nodes before linearized reading' (duration: 198.055481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:51.12114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.615351ms","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":"info","ts":"2026-04-23T09:55:51.116879Z","caller":"traceutil/trace.go:171","msg":"trace[1590908175] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"220.776766ms","start":"2026-04-23T09:55:50.896094Z","end":"2026-04-23T09:55:51.116871Z","steps":["trace[1590908175] 'process raft request' (duration: 219.608895ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:51.121241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.998109ms","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-04-23T09:55:51.121256Z","caller":"traceutil/trace.go:171","msg":"trace[129648850] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:654; }","duration":"198.049181ms","start":"2026-04-23T09:55:50.923203Z","end":"2026-04-23T09:55:51.121252Z","steps":["trace[129648850] 'agreement among raft nodes before linearized reading' (duration: 198.02474ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:51.121177Z","caller":"traceutil/trace.go:171","msg":"trace[957051219] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:654; }","duration":"198.679363ms","start":"2026-04-23T09:55:50.92249Z","end":"2026-04-23T09:55:51.12117Z","steps":["trace[957051219] 'agreement among raft nodes before linearized reading' (duration: 198.628391ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:51.450789Z","caller":"traceutil/trace.go:171","msg":"trace[1665972099] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:679; }","duration":"243.453319ms","start":"2026-04-23T09:55:51.207317Z","end":"2026-04-23T09:55:51.45077Z","steps":["trace[1665972099] 'read index received' (duration: 243.358226ms)","trace[1665972099] 'applied index is now lower than readState.Index' (duration: 94.143µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T09:55:51.45085Z","caller":"traceutil/trace.go:171","msg":"trace[1312911876] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"325.319072ms","start":"2026-04-23T09:55:51.125504Z","end":"2026-04-23T09:55:51.450823Z","steps":["trace[1312911876] 'process raft request' (duration: 325.117675ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:51.450962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.624886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T09:55:51.451073Z","caller":"traceutil/trace.go:171","msg":"trace[1457482546] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:655; }","duration":"243.775071ms","start":"2026-04-23T09:55:51.207288Z","end":"2026-04-23T09:55:51.451063Z","steps":["trace[1457482546] 'agreement among raft nodes before linearized reading' (duration: 243.615865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:51.451109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:55:51.125489Z","time spent":"325.503578ms","remote":"127.0.0.1:41880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4309,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T09:55:51.548563Z","caller":"traceutil/trace.go:171","msg":"trace[243230667] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"242.644471ms","start":"2026-04-23T09:55:51.305887Z","end":"2026-04-23T09:55:51.548531Z","steps":["trace[243230667] 'process raft request' (duration: 242.478285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:51.548698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.573882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T09:55:51.548769Z","caller":"traceutil/trace.go:171","msg":"trace[1197848597] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:656; }","duration":"214.668935ms","start":"2026-04-23T09:55:51.334085Z","end":"2026-04-23T09:55:51.548754Z","steps":["trace[1197848597] 'agreement among raft nodes before linearized reading' (duration: 214.450837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:51.548811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.549175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T09:55:51.548881Z","caller":"traceutil/trace.go:171","msg":"trace[741063407] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:656; }","duration":"153.639379ms","start":"2026-04-23T09:55:51.395227Z","end":"2026-04-23T09:55:51.548866Z","steps":["trace[741063407] 'agreement among raft nodes before linearized reading' (duration: 153.523715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:51.548916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.649766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T09:55:51.549024Z","caller":"traceutil/trace.go:171","msg":"trace[1094171752] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:656; }","duration":"246.821502ms","start":"2026-04-23T09:55:51.302179Z","end":"2026-04-23T09:55:51.549Z","steps":["trace[1094171752] 'agreement among raft nodes before linearized reading' (duration: 246.581703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:51.54904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.566645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T09:55:51.549072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.547946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T09:55:51.549079Z","caller":"traceutil/trace.go:171","msg":"trace[265305519] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:656; }","duration":"183.614587ms","start":"2026-04-23T09:55:51.365451Z","end":"2026-04-23T09:55:51.549066Z","steps":["trace[265305519] 'agreement among raft nodes before linearized reading' (duration: 183.558145ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:51.549104Z","caller":"traceutil/trace.go:171","msg":"trace[2144447542] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:656; }","duration":"153.590487ms","start":"2026-04-23T09:55:51.395505Z","end":"2026-04-23T09:55:51.549095Z","steps":["trace[2144447542] 'agreement among raft nodes before linearized reading' (duration: 153.537765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:51.549184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.658455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T09:55:51.549101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.98944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T09:55:51.549326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.724438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T09:55:51.549362Z","caller":"traceutil/trace.go:171","msg":"trace[1850740594] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:656; }","duration":"168.351852ms","start":"2026-04-23T09:55:51.380991Z","end":"2026-04-23T09:55:51.549343Z","steps":["trace[1850740594] 'agreement among raft nodes before linearized reading' (duration: 167.697799ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:51.54938Z","caller":"traceutil/trace.go:171","msg":"trace[60595342] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:656; }","duration":"184.79155ms","start":"2026-04-23T09:55:51.364579Z","end":"2026-04-23T09:55:51.54937Z","steps":["trace[60595342] 'agreement among raft nodes before linearized reading' (duration: 184.715057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:51.549384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.530985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T09:55:51.549399Z","caller":"traceutil/trace.go:171","msg":"trace[193567235] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:656; }","duration":"230.955816ms","start":"2026-04-23T09:55:51.31838Z","end":"2026-04-23T09:55:51.549336Z","steps":["trace[193567235] 'agreement among raft nodes before linearized reading' (duration: 230.50197ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:51.549428Z","caller":"traceutil/trace.go:171","msg":"trace[1184888168] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:656; }","duration":"245.584017ms","start":"2026-04-23T09:55:51.303832Z","end":"2026-04-23T09:55:51.549416Z","steps":["trace[1184888168] 'agreement among raft nodes before linearized reading' (duration: 245.522774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:51.549484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.786153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T09:55:51.549521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.940155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T09:55:51.549527Z","caller":"traceutil/trace.go:171","msg":"trace[1007187494] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:656; }","duration":"167.838854ms","start":"2026-04-23T09:55:51.381678Z","end":"2026-04-23T09:55:51.549517Z","steps":["trace[1007187494] 'agreement among raft nodes before linearized reading' (duration: 167.780162ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:51.549554Z","caller":"traceutil/trace.go:171","msg":"trace[2081874507] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:656; }","duration":"184.988587ms","start":"2026-04-23T09:55:51.364556Z","end":"2026-04-23T09:55:51.549544Z","steps":["trace[2081874507] 'agreement among raft nodes before linearized reading' (duration: 184.935305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:51.549599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.009859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T09:55:51.54961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.525819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T09:55:51.54964Z","caller":"traceutil/trace.go:171","msg":"trace[1632937597] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:656; }","duration":"247.06809ms","start":"2026-04-23T09:55:51.302563Z","end":"2026-04-23T09:55:51.549631Z","steps":["trace[1632937597] 'agreement among raft nodes before linearized reading' (duration: 247.005218ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:51.54967Z","caller":"traceutil/trace.go:171","msg":"trace[161731897] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:656; }","duration":"168.589871ms","start":"2026-04-23T09:55:51.381057Z","end":"2026-04-23T09:55:51.549647Z","steps":["trace[161731897] 'agreement among raft nodes before linearized reading' (duration: 168.508878ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:51.549692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.043196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T09:55:51.549722Z","caller":"traceutil/trace.go:171","msg":"trace[2104333068] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:656; }","duration":"202.082557ms","start":"2026-04-23T09:55:51.34763Z","end":"2026-04-23T09:55:51.549713Z","steps":["trace[2104333068] 'agreement among raft nodes before linearized reading' (duration: 202.035876ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:51.549791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.553809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T09:55:51.549815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.350071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T09:55:51.549831Z","caller":"traceutil/trace.go:171","msg":"trace[796992860] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:656; }","duration":"247.60291ms","start":"2026-04-23T09:55:51.302218Z","end":"2026-04-23T09:55:51.549821Z","steps":["trace[796992860] 'agreement among raft nodes before linearized reading' (duration: 247.545168ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:51.54968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.153496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-04-23T09:55:51.549875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.896498ms","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-04-23T09:55:51.54989Z","caller":"traceutil/trace.go:171","msg":"trace[1449035551] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:656; }","duration":"165.365915ms","start":"2026-04-23T09:55:51.384509Z","end":"2026-04-23T09:55:51.549875Z","steps":["trace[1449035551] 'agreement among raft nodes before linearized reading' (duration: 165.129126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:51.549903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.835778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T09:55:51.54991Z","caller":"traceutil/trace.go:171","msg":"trace[1265605461] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:656; }","duration":"202.940099ms","start":"2026-04-23T09:55:51.346961Z","end":"2026-04-23T09:55:51.549902Z","steps":["trace[1265605461] 'agreement among raft nodes before linearized reading' (duration: 202.889847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:51.549238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.968461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T09:55:51.54993Z","caller":"traceutil/trace.go:171","msg":"trace[1407364978] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:656; }","duration":"215.8769ms","start":"2026-04-23T09:55:51.334046Z","end":"2026-04-23T09:55:51.549923Z","steps":["trace[1407364978] 'agreement among raft nodes before linearized reading' (duration: 215.822128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:51.549816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.316598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T09:55:51.549497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.776258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T09:55:51.550085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.977057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T09:55:51.550094Z","caller":"traceutil/trace.go:171","msg":"trace[1524136892] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:656; }","duration":"264.590067ms","start":"2026-04-23T09:55:51.285488Z","end":"2026-04-23T09:55:51.550078Z","steps":["trace[1524136892] 'agreement among raft nodes before linearized reading' (duration: 263.743616ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:51.550115Z","caller":"traceutil/trace.go:171","msg":"trace[2038266105] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:656; }","duration":"231.030828ms","start":"2026-04-23T09:55:51.319076Z","end":"2026-04-23T09:55:51.550107Z","steps":["trace[2038266105] 'agreement among raft nodes before linearized reading' (duration: 230.974586ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:51.549848Z","caller":"traceutil/trace.go:171","msg":"trace[1811607785] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:656; }","duration":"231.393093ms","start":"2026-04-23T09:55:51.318445Z","end":"2026-04-23T09:55:51.549838Z","steps":["trace[1811607785] 'agreement among raft nodes before linearized reading' (duration: 231.345881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:51.550251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.290871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T09:55:51.550304Z","caller":"traceutil/trace.go:171","msg":"trace[1093341524] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:656; }","duration":"203.359314ms","start":"2026-04-23T09:55:51.34693Z","end":"2026-04-23T09:55:51.550289Z","steps":["trace[1093341524] 'agreement among raft nodes before linearized reading' (duration: 203.280571ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:51.550265Z","caller":"traceutil/trace.go:171","msg":"trace[353146850] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:656; }","duration":"264.608868ms","start":"2026-04-23T09:55:51.285453Z","end":"2026-04-23T09:55:51.550062Z","steps":["trace[353146850] 'agreement among raft nodes before linearized reading' (duration: 264.225073ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:51.55054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.421149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T09:55:51.55059Z","caller":"traceutil/trace.go:171","msg":"trace[131579895] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:656; }","duration":"216.481821ms","start":"2026-04-23T09:55:51.334094Z","end":"2026-04-23T09:55:51.550576Z","steps":["trace[131579895] 'agreement among raft nodes before linearized reading' (duration: 216.405528ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:51.550566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.113063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T09:55:51.550708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.88251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T09:55:51.550747Z","caller":"traceutil/trace.go:171","msg":"trace[991092401] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:656; }","duration":"285.926892ms","start":"2026-04-23T09:55:51.264811Z","end":"2026-04-23T09:55:51.550738Z","steps":["trace[991092401] 'agreement among raft nodes before linearized reading' (duration: 285.87863ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:51.550739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.330016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T09:55:51.55079Z","caller":"traceutil/trace.go:171","msg":"trace[1469823726] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:656; }","duration":"343.422219ms","start":"2026-04-23T09:55:51.207353Z","end":"2026-04-23T09:55:51.550775Z","steps":["trace[1469823726] 'agreement among raft nodes before linearized reading' (duration: 343.340356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:51.550847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:55:51.207325Z","time spent":"343.505902ms","remote":"127.0.0.1:59854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-23T09:55:51.550901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.326836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T09:55:51.550804Z","caller":"traceutil/trace.go:171","msg":"trace[824148454] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:656; }","duration":"285.296189ms","start":"2026-04-23T09:55:51.265423Z","end":"2026-04-23T09:55:51.550719Z","steps":["trace[824148454] 'agreement among raft nodes before linearized reading' (duration: 285.093692ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:51.549958Z","caller":"traceutil/trace.go:171","msg":"trace[829218365] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:656; }","duration":"154.690467ms","start":"2026-04-23T09:55:51.395251Z","end":"2026-04-23T09:55:51.549941Z","steps":["trace[829218365] 'agreement among raft nodes before linearized reading' (duration: 153.96506ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:55:51.550936Z","caller":"traceutil/trace.go:171","msg":"trace[428376466] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:656; }","duration":"286.382217ms","start":"2026-04-23T09:55:51.264546Z","end":"2026-04-23T09:55:51.550928Z","steps":["trace[428376466] 'agreement among raft nodes before linearized reading' (duration: 286.322795ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:51.550667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.243683ms","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-04-23T09:55:51.551631Z","caller":"traceutil/trace.go:171","msg":"trace[289214200] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:656; }","duration":"343.888656ms","start":"2026-04-23T09:55:51.207375Z","end":"2026-04-23T09:55:51.551264Z","steps":["trace[289214200] 'agreement among raft nodes before linearized reading' (duration: 343.259073ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:55:51.551755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:55:51.207349Z","time spent":"344.333782ms","remote":"127.0.0.1:59854","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-04-23T09:56:02.683627Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095394663012903,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T09:56:03.184744Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095394663012903,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T09:56:03.685628Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095394663012903,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T09:56:04.185781Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095394663012903,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T09:56:04.307035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000540499s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-23T09:56:04.307127Z","caller":"traceutil/trace.go:171","msg":"trace[1339220939] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000688345s","start":"2026-04-23T09:56:02.306419Z","end":"2026-04-23T09:56:04.307107Z","steps":["trace[1339220939] 'agreement among raft nodes before linearized reading' (duration: 2.000580201s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:56:04.307179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:56:02.306356Z","time spent":"2.000812819s","remote":"127.0.0.1:41840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/23 09:56:04 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-23T09:56:04.686369Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095394663012903,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T09:56:05.186894Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095394663012903,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T09:56:05.447362Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.568774842s","expected-duration":"1s"} {"level":"info","ts":"2026-04-23T09:56:05.447783Z","caller":"traceutil/trace.go:171","msg":"trace[799841764] linearizableReadLoop","detail":"{readStateIndex:761; appliedIndex:760; }","duration":"3.264324108s","start":"2026-04-23T09:56:02.183384Z","end":"2026-04-23T09:56:05.447708Z","steps":["trace[799841764] 'read index received' (duration: 3.264116649s)","trace[799841764] 'applied index is now lower than readState.Index' (duration: 206.059µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T09:56:05.447841Z","caller":"traceutil/trace.go:171","msg":"trace[548244553] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"3.569382384s","start":"2026-04-23T09:56:01.878423Z","end":"2026-04-23T09:56:05.447805Z","steps":["trace[548244553] 'process raft request' (duration: 3.569130534s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:56:05.448079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.1407026s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T09:56:05.448106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.059258052s","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-04-23T09:56:05.44813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.960724594s","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-04-23T09:56:05.44814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.900414644s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-23T09:56:05.448154Z","caller":"traceutil/trace.go:171","msg":"trace[1771407968] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:733; }","duration":"1.140826744s","start":"2026-04-23T09:56:04.307307Z","end":"2026-04-23T09:56:05.448134Z","steps":["trace[1771407968] 'agreement among raft nodes before linearized reading' (duration: 1.140693679s)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:56:05.448167Z","caller":"traceutil/trace.go:171","msg":"trace[23884532] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:733; }","duration":"3.059358545s","start":"2026-04-23T09:56:02.388793Z","end":"2026-04-23T09:56:05.448151Z","steps":["trace[23884532] 'agreement among raft nodes before linearized reading' (duration: 3.05921051s)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:56:05.448182Z","caller":"traceutil/trace.go:171","msg":"trace[110563293] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:733; }","duration":"2.960824088s","start":"2026-04-23T09:56:02.48734Z","end":"2026-04-23T09:56:05.448165Z","steps":["trace[110563293] 'agreement among raft nodes before linearized reading' (duration: 2.96061043s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:56:05.448202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:56:02.388774Z","time spent":"3.059422567s","remote":"127.0.0.1:42102","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-04-23T09:56:05.448194Z","caller":"traceutil/trace.go:171","msg":"trace[714280423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:733; }","duration":"2.900502288s","start":"2026-04-23T09:56:02.547678Z","end":"2026-04-23T09:56:05.448181Z","steps":["trace[714280423] 'agreement among raft nodes before linearized reading' (duration: 2.900252069s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:56:05.448207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:56:04.307283Z","time spent":"1.140912747s","remote":"127.0.0.1:41830","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-04-23T09:56:05.448228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:56:02.487319Z","time spent":"2.960898581s","remote":"127.0.0.1:42102","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-04-23T09:56:05.448235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:56:02.547641Z","time spent":"2.90058538s","remote":"127.0.0.1:42102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-23T09:56:05.448562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.088613008s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.89\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-23T09:56:05.44867Z","caller":"traceutil/trace.go:171","msg":"trace[1215020732] range","detail":"{range_begin:/registry/masterleases/199.204.45.89; range_end:; response_count:1; response_revision:733; }","duration":"3.088752982s","start":"2026-04-23T09:56:02.359905Z","end":"2026-04-23T09:56:05.448658Z","steps":["trace[1215020732] 'agreement among raft nodes before linearized reading' (duration: 3.087998506s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:56:05.448703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:56:02.35989Z","time spent":"3.088805054s","remote":"127.0.0.1:41868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.89\" "} {"level":"warn","ts":"2026-04-23T09:56:05.448707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.265322304s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-04-23T09:56:05.448754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.898286647s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-23T09:56:05.448764Z","caller":"traceutil/trace.go:171","msg":"trace[94408986] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:733; }","duration":"3.265419687s","start":"2026-04-23T09:56:02.183331Z","end":"2026-04-23T09:56:05.448751Z","steps":["trace[94408986] 'agreement among raft nodes before linearized reading' (duration: 3.264548436s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:56:05.448756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:56:01.878373Z","time spent":"3.570312757s","remote":"127.0.0.1:42102","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-04-23T09:56:05.448806Z","caller":"traceutil/trace.go:171","msg":"trace[1366455651] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:733; }","duration":"2.898930059s","start":"2026-04-23T09:56:02.549859Z","end":"2026-04-23T09:56:05.448789Z","steps":["trace[1366455651] 'agreement among raft nodes before linearized reading' (duration: 2.898224424s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:56:05.448809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:56:02.183291Z","time spent":"3.26550777s","remote":"127.0.0.1:42102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-23T09:56:05.448853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:56:02.549848Z","time spent":"2.898983902s","remote":"127.0.0.1:42102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-23T09:56:05.951377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.105245ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-04-23T09:56:05.951458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:56:05.450732Z","time spent":"500.722811ms","remote":"127.0.0.1:41868","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-23T09:56:05.955692Z","caller":"traceutil/trace.go:171","msg":"trace[1257190619] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"500.71609ms","start":"2026-04-23T09:56:05.454957Z","end":"2026-04-23T09:56:05.955673Z","steps":["trace[1257190619] 'process raft request' (duration: 500.653208ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:56:05.95576Z","caller":"traceutil/trace.go:171","msg":"trace[1597590749] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"500.913167ms","start":"2026-04-23T09:56:05.454824Z","end":"2026-04-23T09:56:05.955738Z","steps":["trace[1597590749] 'process raft request' (duration: 500.670449ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:56:05.955874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:56:05.454804Z","time spent":"501.006531ms","remote":"127.0.0.1:42102","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-04-23T09:56:05.955883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:56:05.454949Z","time spent":"500.837835ms","remote":"127.0.0.1:42102","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-04-23T09:56:05.955989Z","caller":"traceutil/trace.go:171","msg":"trace[244538432] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"500.966711ms","start":"2026-04-23T09:56:05.455008Z","end":"2026-04-23T09:56:05.955975Z","steps":["trace[244538432] 'process raft request' (duration: 500.635318ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:56:05.956023Z","caller":"traceutil/trace.go:171","msg":"trace[168783602] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"500.93641ms","start":"2026-04-23T09:56:05.455066Z","end":"2026-04-23T09:56:05.956003Z","steps":["trace[168783602] 'process raft request' (duration: 500.804764ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:56:05.956056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:56:05.455001Z","time spent":"501.010462ms","remote":"127.0.0.1:42102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T09:56:05.956133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:56:05.455046Z","time spent":"501.017672ms","remote":"127.0.0.1:42102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T09:56:05.956228Z","caller":"traceutil/trace.go:171","msg":"trace[514300967] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"500.924209ms","start":"2026-04-23T09:56:05.455287Z","end":"2026-04-23T09:56:05.956211Z","steps":["trace[514300967] 'process raft request' (duration: 500.68181ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:56:05.956303Z","caller":"traceutil/trace.go:171","msg":"trace[2117692002] linearizableReadLoop","detail":"{readStateIndex:768; appliedIndex:761; }","duration":"497.467274ms","start":"2026-04-23T09:56:05.458823Z","end":"2026-04-23T09:56:05.95629Z","steps":["trace[2117692002] 'read index received' (duration: 346.44522ms)","trace[2117692002] 'applied index is now lower than readState.Index' (duration: 151.021304ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T09:56:05.956329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:56:05.455277Z","time spent":"500.997541ms","remote":"127.0.0.1:42102","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-04-23T09:56:05.956386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.560007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T09:56:05.956404Z","caller":"traceutil/trace.go:171","msg":"trace[2133888852] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:739; }","duration":"497.589758ms","start":"2026-04-23T09:56:05.458808Z","end":"2026-04-23T09:56:05.956398Z","steps":["trace[2133888852] 'agreement among raft nodes before linearized reading' (duration: 497.530696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:56:05.956422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:56:05.458802Z","time spent":"497.616258ms","remote":"127.0.0.1:41840","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-04-23T09:56:05.956468Z","caller":"traceutil/trace.go:171","msg":"trace[702295531] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"501.059474ms","start":"2026-04-23T09:56:05.455397Z","end":"2026-04-23T09:56:05.956456Z","steps":["trace[702295531] 'process raft request' (duration: 500.758263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:56:05.956528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:56:05.455378Z","time spent":"501.119495ms","remote":"127.0.0.1:41910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T09:56:27.973002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.383614ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-23T09:56:28.32991Z","caller":"traceutil/trace.go:171","msg":"trace[1544263568] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"210.373789ms","start":"2026-04-23T09:56:28.119512Z","end":"2026-04-23T09:56:28.329886Z","steps":["trace[1544263568] 'process raft request' (duration: 210.316298ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:56:28.329994Z","caller":"traceutil/trace.go:171","msg":"trace[1364801271] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"210.568342ms","start":"2026-04-23T09:56:28.119387Z","end":"2026-04-23T09:56:28.329956Z","steps":["trace[1364801271] 'process raft request' (duration: 155.006336ms)","trace[1364801271] 'compare' (duration: 55.244901ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T09:56:30.480599Z","caller":"traceutil/trace.go:171","msg":"trace[352565959] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"142.701009ms","start":"2026-04-23T09:56:30.337872Z","end":"2026-04-23T09:56:30.480573Z","steps":["trace[352565959] 'process raft request' (duration: 142.542717ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:56:30.504447Z","caller":"traceutil/trace.go:171","msg":"trace[648824930] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"166.566607ms","start":"2026-04-23T09:56:30.337851Z","end":"2026-04-23T09:56:30.504417Z","steps":["trace[648824930] 'process raft request' (duration: 166.418045ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:56:36.271677Z","caller":"traceutil/trace.go:171","msg":"trace[739220047] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"101.97394ms","start":"2026-04-23T09:56:36.169676Z","end":"2026-04-23T09:56:36.27165Z","steps":["trace[739220047] 'process raft request' (duration: 101.911427ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:56:36.27177Z","caller":"traceutil/trace.go:171","msg":"trace[263314461] linearizableReadLoop","detail":"{readStateIndex:980; appliedIndex:979; }","duration":"105.735558ms","start":"2026-04-23T09:56:36.166007Z","end":"2026-04-23T09:56:36.271743Z","steps":["trace[263314461] 'read index received' (duration: 53.207547ms)","trace[263314461] 'applied index is now lower than readState.Index' (duration: 52.523071ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T09:56:36.271771Z","caller":"traceutil/trace.go:171","msg":"trace[233669512] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"107.615661ms","start":"2026-04-23T09:56:36.16408Z","end":"2026-04-23T09:56:36.271695Z","steps":["trace[233669512] 'process raft request' (duration: 55.08176ms)","trace[233669512] 'compare' (duration: 52.26976ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T09:56:36.271958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.32533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-23T09:56:36.272073Z","caller":"traceutil/trace.go:171","msg":"trace[1792316817] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:944; }","duration":"107.392703ms","start":"2026-04-23T09:56:36.164586Z","end":"2026-04-23T09:56:36.271979Z","steps":["trace[1792316817] 'agreement among raft nodes before linearized reading' (duration: 107.239077ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:56:36.678694Z","caller":"traceutil/trace.go:171","msg":"trace[1234900675] linearizableReadLoop","detail":"{readStateIndex:984; appliedIndex:983; }","duration":"111.14948ms","start":"2026-04-23T09:56:36.567518Z","end":"2026-04-23T09:56:36.678667Z","steps":["trace[1234900675] 'read index received' (duration: 14.627333ms)","trace[1234900675] 'applied index is now lower than readState.Index' (duration: 96.521106ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T09:56:36.678785Z","caller":"traceutil/trace.go:171","msg":"trace[1913768360] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"129.01508ms","start":"2026-04-23T09:56:36.549728Z","end":"2026-04-23T09:56:36.678743Z","steps":["trace[1913768360] 'process raft request' (duration: 32.452043ms)","trace[1913768360] 'compare' (duration: 96.37174ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T09:56:36.678821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.285925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T09:56:36.67887Z","caller":"traceutil/trace.go:171","msg":"trace[1436149213] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:947; }","duration":"111.397749ms","start":"2026-04-23T09:56:36.567462Z","end":"2026-04-23T09:56:36.67886Z","steps":["trace[1436149213] 'agreement among raft nodes before linearized reading' (duration: 111.302125ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:56:38.036848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.925685ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-23T09:56:38.045811Z","caller":"traceutil/trace.go:171","msg":"trace[23706348] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"122.220065ms","start":"2026-04-23T09:56:37.923544Z","end":"2026-04-23T09:56:38.045764Z","steps":["trace[23706348] 'process raft request' (duration: 121.750265ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:56:38.32778Z","caller":"traceutil/trace.go:171","msg":"trace[1935431311] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"114.025833ms","start":"2026-04-23T09:56:38.21373Z","end":"2026-04-23T09:56:38.327756Z","steps":["trace[1935431311] 'process raft request' (duration: 113.820955ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:56:38.409432Z","caller":"traceutil/trace.go:171","msg":"trace[1342030374] linearizableReadLoop","detail":"{readStateIndex:988; appliedIndex:987; }","duration":"133.700204ms","start":"2026-04-23T09:56:38.275711Z","end":"2026-04-23T09:56:38.409411Z","steps":["trace[1342030374] 'read index received' (duration: 52.00134ms)","trace[1342030374] 'applied index is now lower than readState.Index' (duration: 81.697724ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T09:56:38.40975Z","caller":"traceutil/trace.go:171","msg":"trace[1778243320] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"146.000127ms","start":"2026-04-23T09:56:38.263727Z","end":"2026-04-23T09:56:38.409733Z","steps":["trace[1778243320] 'process raft request' (duration: 145.512208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:56:38.409832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.072659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-23T09:56:38.409877Z","caller":"traceutil/trace.go:171","msg":"trace[1924551117] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:950; }","duration":"134.202684ms","start":"2026-04-23T09:56:38.275663Z","end":"2026-04-23T09:56:38.409866Z","steps":["trace[1924551117] 'agreement among raft nodes before linearized reading' (duration: 133.936863ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:56:38.40984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.533571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T09:56:38.41006Z","caller":"traceutil/trace.go:171","msg":"trace[994997209] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:950; }","duration":"103.829933ms","start":"2026-04-23T09:56:38.306204Z","end":"2026-04-23T09:56:38.410034Z","steps":["trace[994997209] 'agreement among raft nodes before linearized reading' (duration: 103.465499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:56:38.409958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.805037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-23T09:56:38.410277Z","caller":"traceutil/trace.go:171","msg":"trace[1143559534] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:950; }","duration":"123.162861ms","start":"2026-04-23T09:56:38.287097Z","end":"2026-04-23T09:56:38.41026Z","steps":["trace[1143559534] 'agreement among raft nodes before linearized reading' (duration: 122.502514ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:56:40.164466Z","caller":"traceutil/trace.go:171","msg":"trace[2125907722] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:992; }","duration":"126.067245ms","start":"2026-04-23T09:56:40.03838Z","end":"2026-04-23T09:56:40.164447Z","steps":["trace[2125907722] 'read index received' (duration: 125.95217ms)","trace[2125907722] 'applied index is now lower than readState.Index' (duration: 114.435µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T09:56:40.164569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.1833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T09:56:40.164591Z","caller":"traceutil/trace.go:171","msg":"trace[1722231995] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:955; }","duration":"126.250742ms","start":"2026-04-23T09:56:40.038333Z","end":"2026-04-23T09:56:40.164583Z","steps":["trace[1722231995] 'agreement among raft nodes before linearized reading' (duration: 126.185229ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:56:40.164794Z","caller":"traceutil/trace.go:171","msg":"trace[781023417] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"149.354599ms","start":"2026-04-23T09:56:40.01541Z","end":"2026-04-23T09:56:40.164765Z","steps":["trace[781023417] 'process raft request' (duration: 148.928193ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:56:40.165655Z","caller":"traceutil/trace.go:171","msg":"trace[1278135983] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"115.503651ms","start":"2026-04-23T09:56:40.05012Z","end":"2026-04-23T09:56:40.165623Z","steps":["trace[1278135983] 'process raft request' (duration: 115.353045ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:56:56.525302Z","caller":"traceutil/trace.go:171","msg":"trace[183808556] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"224.481105ms","start":"2026-04-23T09:56:56.300796Z","end":"2026-04-23T09:56:56.525277Z","steps":["trace[183808556] 'process raft request' (duration: 224.326699ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:57:11.044507Z","caller":"traceutil/trace.go:171","msg":"trace[240438975] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"146.432092ms","start":"2026-04-23T09:57:10.898015Z","end":"2026-04-23T09:57:11.044447Z","steps":["trace[240438975] 'process raft request' (duration: 120.32871ms)","trace[240438975] 'compare' (duration: 25.826805ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T09:57:25.401713Z","caller":"traceutil/trace.go:171","msg":"trace[505079173] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"211.116048ms","start":"2026-04-23T09:57:25.190555Z","end":"2026-04-23T09:57:25.401671Z","steps":["trace[505079173] 'process raft request' (duration: 210.843943ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:57:39.224105Z","caller":"traceutil/trace.go:171","msg":"trace[931856537] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"170.89956ms","start":"2026-04-23T09:57:39.053183Z","end":"2026-04-23T09:57:39.224083Z","steps":["trace[931856537] 'process raft request' (duration: 158.777573ms)","trace[931856537] 'compare' (duration: 11.927573ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T09:58:53.453674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.089355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T09:58:53.453778Z","caller":"traceutil/trace.go:171","msg":"trace[1134746325] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1395; }","duration":"147.249748ms","start":"2026-04-23T09:58:53.306508Z","end":"2026-04-23T09:58:53.453758Z","steps":["trace[1134746325] 'range keys from in-memory index tree' (duration: 147.001813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:58:53.694582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.62121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-23T09:58:53.694658Z","caller":"traceutil/trace.go:171","msg":"trace[574199230] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1396; }","duration":"113.748552ms","start":"2026-04-23T09:58:53.580893Z","end":"2026-04-23T09:58:53.694641Z","steps":["trace[574199230] 'range keys from in-memory index tree' (duration: 113.481117ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:58:55.387991Z","caller":"traceutil/trace.go:171","msg":"trace[670928275] transaction","detail":"{read_only:false; response_revision:1401; number_of_response:1; }","duration":"105.075855ms","start":"2026-04-23T09:58:55.282876Z","end":"2026-04-23T09:58:55.387952Z","steps":["trace[670928275] 'process raft request' (duration: 104.737367ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:58:55.805725Z","caller":"traceutil/trace.go:171","msg":"trace[352489653] transaction","detail":"{read_only:false; response_revision:1402; number_of_response:1; }","duration":"178.020848ms","start":"2026-04-23T09:58:55.627678Z","end":"2026-04-23T09:58:55.805698Z","steps":["trace[352489653] 'process raft request' (duration: 177.738983ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:58:55.943667Z","caller":"traceutil/trace.go:171","msg":"trace[1859378407] transaction","detail":"{read_only:false; response_revision:1405; number_of_response:1; }","duration":"130.568891ms","start":"2026-04-23T09:58:55.813079Z","end":"2026-04-23T09:58:55.943648Z","steps":["trace[1859378407] 'process raft request' (duration: 130.408688ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:58:55.943573Z","caller":"traceutil/trace.go:171","msg":"trace[2114167772] transaction","detail":"{read_only:false; response_revision:1404; number_of_response:1; }","duration":"131.452219ms","start":"2026-04-23T09:58:55.812088Z","end":"2026-04-23T09:58:55.943541Z","steps":["trace[2114167772] 'process raft request' (duration: 131.292646ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:59:40.518288Z","caller":"traceutil/trace.go:171","msg":"trace[963652293] linearizableReadLoop","detail":"{readStateIndex:1621; appliedIndex:1620; }","duration":"481.407186ms","start":"2026-04-23T09:59:40.036851Z","end":"2026-04-23T09:59:40.518259Z","steps":["trace[963652293] 'read index received' (duration: 481.240983ms)","trace[963652293] 'applied index is now lower than readState.Index' (duration: 164.983µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T09:59:40.518347Z","caller":"traceutil/trace.go:171","msg":"trace[781537570] transaction","detail":"{read_only:false; response_revision:1547; number_of_response:1; }","duration":"490.704088ms","start":"2026-04-23T09:59:40.02761Z","end":"2026-04-23T09:59:40.518314Z","steps":["trace[781537570] 'process raft request' (duration: 490.470394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:59:40.518465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.58452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T09:59:40.518519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:59:40.027585Z","time spent":"490.844451ms","remote":"127.0.0.1:42102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T09:59:40.518552Z","caller":"traceutil/trace.go:171","msg":"trace[1532373869] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1547; }","duration":"481.702473ms","start":"2026-04-23T09:59:40.036826Z","end":"2026-04-23T09:59:40.518528Z","steps":["trace[1532373869] 'agreement among raft nodes before linearized reading' (duration: 481.540829ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:59:40.518553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.923311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T09:59:40.518575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.772844ms","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-04-23T09:59:40.518581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.163191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-23T09:59:40.518599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:59:40.036815Z","time spent":"481.776094ms","remote":"127.0.0.1:41840","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-04-23T09:59:40.518623Z","caller":"traceutil/trace.go:171","msg":"trace[1201944959] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1547; }","duration":"268.248702ms","start":"2026-04-23T09:59:40.250365Z","end":"2026-04-23T09:59:40.518614Z","steps":["trace[1201944959] 'agreement among raft nodes before linearized reading' (duration: 268.077229ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:59:40.51862Z","caller":"traceutil/trace.go:171","msg":"trace[1010240539] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1547; }","duration":"213.030154ms","start":"2026-04-23T09:59:40.305573Z","end":"2026-04-23T09:59:40.518603Z","steps":["trace[1010240539] 'agreement among raft nodes before linearized reading' (duration: 212.918881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:59:40.518651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.807616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-23T09:59:40.51862Z","caller":"traceutil/trace.go:171","msg":"trace[98556591] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1547; }","duration":"374.848096ms","start":"2026-04-23T09:59:40.143759Z","end":"2026-04-23T09:59:40.518607Z","steps":["trace[98556591] 'agreement among raft nodes before linearized reading' (duration: 374.685013ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:59:40.518704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:59:40.143743Z","time spent":"374.954377ms","remote":"127.0.0.1:42102","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-04-23T09:59:40.518707Z","caller":"traceutil/trace.go:171","msg":"trace[1649640395] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1547; }","duration":"369.881478ms","start":"2026-04-23T09:59:40.14881Z","end":"2026-04-23T09:59:40.518692Z","steps":["trace[1649640395] 'agreement among raft nodes before linearized reading' (duration: 369.694915ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:59:40.518729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.872496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-04-23T09:59:40.518745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:59:40.148802Z","time spent":"369.934619ms","remote":"127.0.0.1:42102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-23T09:59:40.518771Z","caller":"traceutil/trace.go:171","msg":"trace[618271120] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1547; }","duration":"374.951687ms","start":"2026-04-23T09:59:40.143807Z","end":"2026-04-23T09:59:40.518758Z","steps":["trace[618271120] 'agreement among raft nodes before linearized reading' (duration: 374.839205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:59:40.518816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:59:40.143794Z","time spent":"375.009088ms","remote":"127.0.0.1:42102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-23T09:59:41.419399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.371408ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T09:59:41.419801Z","caller":"traceutil/trace.go:171","msg":"trace[1138394844] transaction","detail":"{read_only:false; response_revision:1548; number_of_response:1; }","duration":"896.398706ms","start":"2026-04-23T09:59:40.523381Z","end":"2026-04-23T09:59:41.41978Z","steps":["trace[1138394844] 'process raft request' (duration: 133.558769ms)","trace[1138394844] 'compare' (duration: 761.582592ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T09:59:41.419873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:59:40.52336Z","time spent":"896.479048ms","remote":"127.0.0.1:42102","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-04-23T09:59:41.793572Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.136874396s","expected-duration":"1s"} {"level":"info","ts":"2026-04-23T09:59:41.807767Z","caller":"traceutil/trace.go:171","msg":"trace[1879154199] transaction","detail":"{read_only:false; response_revision:1549; number_of_response:1; }","duration":"1.284316766s","start":"2026-04-23T09:59:40.523387Z","end":"2026-04-23T09:59:41.807704Z","steps":["trace[1879154199] 'process raft request' (duration: 1.270352943s)","trace[1879154199] 'compare' (duration: 13.722259ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T09:59:41.807956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:59:40.523361Z","time spent":"1.28448058s","remote":"127.0.0.1:42102","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-04-23T09:59:41.808212Z","caller":"traceutil/trace.go:171","msg":"trace[198837367] linearizableReadLoop","detail":"{readStateIndex:1625; appliedIndex:1622; }","duration":"503.411008ms","start":"2026-04-23T09:59:41.304779Z","end":"2026-04-23T09:59:41.80819Z","steps":["trace[198837367] 'read index received' (duration: 488.971695ms)","trace[198837367] 'applied index is now lower than readState.Index' (duration: 14.438482ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T09:59:41.808401Z","caller":"traceutil/trace.go:171","msg":"trace[1154889193] transaction","detail":"{read_only:false; response_revision:1550; number_of_response:1; }","duration":"1.28499479s","start":"2026-04-23T09:59:40.523394Z","end":"2026-04-23T09:59:41.808389Z","steps":["trace[1154889193] 'process raft request' (duration: 1.284247625s)"],"step_count":1} {"level":"info","ts":"2026-04-23T09:59:41.808414Z","caller":"traceutil/trace.go:171","msg":"trace[91888785] transaction","detail":"{read_only:false; response_revision:1551; number_of_response:1; }","duration":"1.284833747s","start":"2026-04-23T09:59:40.523561Z","end":"2026-04-23T09:59:41.808395Z","steps":["trace[91888785] 'process raft request' (duration: 1.284407959s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:59:41.808539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:59:40.523552Z","time spent":"1.284919359s","remote":"127.0.0.1:42102","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-04-23T09:59:41.808646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.873227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T09:59:41.808538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:59:40.523373Z","time spent":"1.285078092s","remote":"127.0.0.1:42102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T09:59:41.808703Z","caller":"traceutil/trace.go:171","msg":"trace[1660519642] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1551; }","duration":"503.966228ms","start":"2026-04-23T09:59:41.304726Z","end":"2026-04-23T09:59:41.808693Z","steps":["trace[1660519642] 'agreement among raft nodes before linearized reading' (duration: 503.866556ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:59:41.808789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T09:59:41.304704Z","time spent":"504.075811ms","remote":"127.0.0.1:41840","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-04-23T09:59:41.813854Z","caller":"traceutil/trace.go:171","msg":"trace[535241386] transaction","detail":"{read_only:false; response_revision:1552; number_of_response:1; }","duration":"249.004325ms","start":"2026-04-23T09:59:41.564826Z","end":"2026-04-23T09:59:41.81383Z","steps":["trace[535241386] 'process raft request' (duration: 248.669688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T09:59:41.814329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.72332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T09:59:41.814392Z","caller":"traceutil/trace.go:171","msg":"trace[661191388] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1553; }","duration":"247.830473ms","start":"2026-04-23T09:59:41.566546Z","end":"2026-04-23T09:59:41.814377Z","steps":["trace[661191388] 'agreement among raft nodes before linearized reading' (duration: 247.740601ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:00:04.019102Z","caller":"traceutil/trace.go:171","msg":"trace[340474051] transaction","detail":"{read_only:false; response_revision:1622; number_of_response:1; }","duration":"496.320439ms","start":"2026-04-23T10:00:03.522756Z","end":"2026-04-23T10:00:04.019076Z","steps":["trace[340474051] 'process raft request' (duration: 496.177516ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:00:04.019259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:00:03.522731Z","time spent":"496.452371ms","remote":"127.0.0.1:42102","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-04-23T10:00:04.094706Z","caller":"traceutil/trace.go:171","msg":"trace[695513554] linearizableReadLoop","detail":"{readStateIndex:1702; appliedIndex:1700; }","duration":"183.795435ms","start":"2026-04-23T10:00:03.91088Z","end":"2026-04-23T10:00:04.094676Z","steps":["trace[695513554] 'read index received' (duration: 108.047932ms)","trace[695513554] 'applied index is now lower than readState.Index' (duration: 75.746103ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T10:00:04.094893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.000339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-23T10:00:04.094956Z","caller":"traceutil/trace.go:171","msg":"trace[1119159570] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1623; }","duration":"184.105951ms","start":"2026-04-23T10:00:03.910836Z","end":"2026-04-23T10:00:04.094942Z","steps":["trace[1119159570] 'agreement among raft nodes before linearized reading' (duration: 183.948578ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:00:04.094776Z","caller":"traceutil/trace.go:171","msg":"trace[999525594] transaction","detail":"{read_only:false; response_revision:1623; number_of_response:1; }","duration":"193.166765ms","start":"2026-04-23T10:00:03.901567Z","end":"2026-04-23T10:00:04.094734Z","steps":["trace[999525594] 'process raft request' (duration: 180.93077ms)","trace[999525594] 'compare' (duration: 11.92718ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T10:00:04.095104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.839697ms","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-04-23T10:00:04.095152Z","caller":"traceutil/trace.go:171","msg":"trace[79606237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1623; }","duration":"170.982869ms","start":"2026-04-23T10:00:03.924157Z","end":"2026-04-23T10:00:04.09514Z","steps":["trace[79606237] 'agreement among raft nodes before linearized reading' (duration: 170.758535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:00:04.095354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.576474ms","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-04-23T10:00:04.095386Z","caller":"traceutil/trace.go:171","msg":"trace[23102126] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1623; }","duration":"180.642875ms","start":"2026-04-23T10:00:03.914735Z","end":"2026-04-23T10:00:04.095378Z","steps":["trace[23102126] 'agreement among raft nodes before linearized reading' (duration: 180.146135ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:00:04.787623Z","caller":"traceutil/trace.go:171","msg":"trace[1232464366] transaction","detail":"{read_only:false; response_revision:1627; number_of_response:1; }","duration":"169.217906ms","start":"2026-04-23T10:00:04.618377Z","end":"2026-04-23T10:00:04.787595Z","steps":["trace[1232464366] 'process raft request' (duration: 169.076394ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:00:06.401025Z","caller":"traceutil/trace.go:171","msg":"trace[1453277809] transaction","detail":"{read_only:false; response_revision:1628; number_of_response:1; }","duration":"373.357491ms","start":"2026-04-23T10:00:06.027641Z","end":"2026-04-23T10:00:06.400999Z","steps":["trace[1453277809] 'process raft request' (duration: 373.205208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:00:06.401207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:00:06.027623Z","time spent":"373.490723ms","remote":"127.0.0.1:42102","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-04-23T10:00:06.960288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.688232ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T10:00:06.960424Z","caller":"traceutil/trace.go:171","msg":"trace[1075242199] linearizableReadLoop","detail":"{readStateIndex:1708; appliedIndex:1706; }","duration":"836.638077ms","start":"2026-04-23T10:00:06.123764Z","end":"2026-04-23T10:00:06.960402Z","steps":["trace[1075242199] 'read index received' (duration: 277.149226ms)","trace[1075242199] 'applied index is now lower than readState.Index' (duration: 559.486651ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T10:00:06.960457Z","caller":"traceutil/trace.go:171","msg":"trace[97302051] transaction","detail":"{read_only:false; response_revision:1629; number_of_response:1; }","duration":"860.24197ms","start":"2026-04-23T10:00:06.100185Z","end":"2026-04-23T10:00:06.960427Z","steps":["trace[97302051] 'process raft request' (duration: 394.286842ms)","trace[97302051] 'compare' (duration: 465.240533ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T10:00:06.96061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:00:06.100159Z","time spent":"860.371352ms","remote":"127.0.0.1:42102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T10:00:06.960791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.854808ms","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-04-23T10:00:06.960814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.914899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-23T10:00:06.960866Z","caller":"traceutil/trace.go:171","msg":"trace[1436845726] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1629; }","duration":"827.96818ms","start":"2026-04-23T10:00:06.13288Z","end":"2026-04-23T10:00:06.960849Z","steps":["trace[1436845726] 'agreement among raft nodes before linearized reading' (duration: 827.779857ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:00:06.9608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.502541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-23T10:00:06.960871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.062895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-23T10:00:06.960914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:00:06.132861Z","time spent":"828.040971ms","remote":"127.0.0.1:42102","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-04-23T10:00:06.960922Z","caller":"traceutil/trace.go:171","msg":"trace[856614369] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1629; }","duration":"827.630823ms","start":"2026-04-23T10:00:06.133271Z","end":"2026-04-23T10:00:06.960902Z","steps":["trace[856614369] 'agreement among raft nodes before linearized reading' (duration: 827.42998ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:00:06.960874Z","caller":"traceutil/trace.go:171","msg":"trace[831857982] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1629; }","duration":"169.023682ms","start":"2026-04-23T10:00:06.791834Z","end":"2026-04-23T10:00:06.960858Z","steps":["trace[831857982] 'agreement among raft nodes before linearized reading' (duration: 168.827139ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:00:06.960978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:00:06.133266Z","time spent":"827.698695ms","remote":"127.0.0.1:42102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-23T10:00:06.960932Z","caller":"traceutil/trace.go:171","msg":"trace[1594195690] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1629; }","duration":"837.214377ms","start":"2026-04-23T10:00:06.123697Z","end":"2026-04-23T10:00:06.960912Z","steps":["trace[1594195690] 'agreement among raft nodes before linearized reading' (duration: 836.82077ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:00:06.961085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:00:06.123676Z","time spent":"837.373501ms","remote":"127.0.0.1:42102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-23T10:00:07.532307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.990478ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T10:00:07.532461Z","caller":"traceutil/trace.go:171","msg":"trace[588423613] transaction","detail":"{read_only:false; response_revision:1631; number_of_response:1; }","duration":"567.391402ms","start":"2026-04-23T10:00:06.965056Z","end":"2026-04-23T10:00:07.532447Z","steps":["trace[588423613] 'process raft request' (duration: 567.321711ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:00:07.532473Z","caller":"traceutil/trace.go:171","msg":"trace[1790709123] transaction","detail":"{read_only:false; response_revision:1630; number_of_response:1; }","duration":"567.389192ms","start":"2026-04-23T10:00:06.96505Z","end":"2026-04-23T10:00:07.53244Z","steps":["trace[1790709123] 'process raft request' (duration: 407.20212ms)","trace[1790709123] 'compare' (duration: 159.902137ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T10:00:07.532505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:00:06.965044Z","time spent":"567.440883ms","remote":"127.0.0.1:42102","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-04-23T10:00:07.532607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:00:06.965031Z","time spent":"567.504875ms","remote":"127.0.0.1:42102","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-04-23T10:00:07.532639Z","caller":"traceutil/trace.go:171","msg":"trace[1509734413] linearizableReadLoop","detail":"{readStateIndex:1712; appliedIndex:1708; }","duration":"227.620646ms","start":"2026-04-23T10:00:07.304992Z","end":"2026-04-23T10:00:07.532612Z","steps":["trace[1509734413] 'read index received' (duration: 67.366892ms)","trace[1509734413] 'applied index is now lower than readState.Index' (duration: 160.252154ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T10:00:07.532735Z","caller":"traceutil/trace.go:171","msg":"trace[2042974857] transaction","detail":"{read_only:false; response_revision:1632; number_of_response:1; }","duration":"567.570726ms","start":"2026-04-23T10:00:06.965153Z","end":"2026-04-23T10:00:07.532724Z","steps":["trace[2042974857] 'process raft request' (duration: 567.26896ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:00:07.532781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:00:06.965124Z","time spent":"567.630788ms","remote":"127.0.0.1:42102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T10:00:07.532797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.792869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:00:07.532836Z","caller":"traceutil/trace.go:171","msg":"trace[858952504] transaction","detail":"{read_only:false; response_revision:1633; number_of_response:1; }","duration":"567.617457ms","start":"2026-04-23T10:00:06.965209Z","end":"2026-04-23T10:00:07.532826Z","steps":["trace[858952504] 'process raft request' (duration: 567.30076ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:00:07.532853Z","caller":"traceutil/trace.go:171","msg":"trace[941636087] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1633; }","duration":"227.84696ms","start":"2026-04-23T10:00:07.304979Z","end":"2026-04-23T10:00:07.532826Z","steps":["trace[941636087] 'agreement among raft nodes before linearized reading' (duration: 227.726798ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:00:07.532878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:00:06.965199Z","time spent":"567.657428ms","remote":"127.0.0.1:42102","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-04-23T10:00:21.321122Z","caller":"traceutil/trace.go:171","msg":"trace[1892357331] transaction","detail":"{read_only:false; response_revision:1725; number_of_response:1; }","duration":"817.049621ms","start":"2026-04-23T10:00:20.504036Z","end":"2026-04-23T10:00:21.321086Z","steps":["trace[1892357331] 'process raft request' (duration: 816.880157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:00:21.321295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:00:20.504024Z","time spent":"817.191314ms","remote":"127.0.0.1:42102","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-04-23T10:00:21.33795Z","caller":"traceutil/trace.go:171","msg":"trace[765362808] transaction","detail":"{read_only:false; response_revision:1727; number_of_response:1; }","duration":"280.558242ms","start":"2026-04-23T10:00:21.057366Z","end":"2026-04-23T10:00:21.337924Z","steps":["trace[765362808] 'process raft request' (duration: 280.511741ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:00:21.338065Z","caller":"traceutil/trace.go:171","msg":"trace[298277632] transaction","detail":"{read_only:false; response_revision:1726; number_of_response:1; }","duration":"338.556394ms","start":"2026-04-23T10:00:20.999458Z","end":"2026-04-23T10:00:21.338014Z","steps":["trace[298277632] 'process raft request' (duration: 338.285048ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:00:21.338274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:00:20.999439Z","time spent":"338.722407ms","remote":"127.0.0.1:42102","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-04-23T10:00:33.796707Z","caller":"traceutil/trace.go:171","msg":"trace[1487406609] linearizableReadLoop","detail":"{readStateIndex:1856; appliedIndex:1855; }","duration":"125.626779ms","start":"2026-04-23T10:00:33.671054Z","end":"2026-04-23T10:00:33.796681Z","steps":["trace[1487406609] 'read index received' (duration: 125.429646ms)","trace[1487406609] 'applied index is now lower than readState.Index' (duration: 195.723µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T10:00:33.796761Z","caller":"traceutil/trace.go:171","msg":"trace[751434362] transaction","detail":"{read_only:false; response_revision:1769; number_of_response:1; }","duration":"128.455164ms","start":"2026-04-23T10:00:33.668283Z","end":"2026-04-23T10:00:33.796738Z","steps":["trace[751434362] 'process raft request' (duration: 128.258521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:00:33.796922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.861764ms","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-04-23T10:00:33.796961Z","caller":"traceutil/trace.go:171","msg":"trace[89642749] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1769; }","duration":"125.952405ms","start":"2026-04-23T10:00:33.670998Z","end":"2026-04-23T10:00:33.796951Z","steps":["trace[89642749] 'agreement among raft nodes before linearized reading' (duration: 125.802583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:00:33.797031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.435246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-23T10:00:33.796953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.900354ms","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-04-23T10:00:33.797072Z","caller":"traceutil/trace.go:171","msg":"trace[2013076656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1769; }","duration":"113.486886ms","start":"2026-04-23T10:00:33.683573Z","end":"2026-04-23T10:00:33.79706Z","steps":["trace[2013076656] 'agreement among raft nodes before linearized reading' (duration: 113.405705ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:00:33.797078Z","caller":"traceutil/trace.go:171","msg":"trace[890283964] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1769; }","duration":"114.041167ms","start":"2026-04-23T10:00:33.683024Z","end":"2026-04-23T10:00:33.797066Z","steps":["trace[890283964] 'agreement among raft nodes before linearized reading' (duration: 113.848634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:00:34.106988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.8156ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T10:00:34.107128Z","caller":"traceutil/trace.go:171","msg":"trace[434143560] transaction","detail":"{read_only:false; response_revision:1770; number_of_response:1; }","duration":"305.652323ms","start":"2026-04-23T10:00:33.801449Z","end":"2026-04-23T10:00:34.107102Z","steps":["trace[434143560] 'process raft request' (duration: 188.637019ms)","trace[434143560] 'compare' (duration: 116.688927ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T10:00:34.107228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:00:33.801395Z","time spent":"305.784556ms","remote":"127.0.0.1:42102","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-04-23T10:00:34.154356Z","caller":"traceutil/trace.go:171","msg":"trace[1169479531] transaction","detail":"{read_only:false; response_revision:1771; number_of_response:1; }","duration":"352.93102ms","start":"2026-04-23T10:00:33.801405Z","end":"2026-04-23T10:00:34.154336Z","steps":["trace[1169479531] 'process raft request' (duration: 352.670135ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:00:34.154334Z","caller":"traceutil/trace.go:171","msg":"trace[942267432] transaction","detail":"{read_only:false; response_revision:1772; number_of_response:1; }","duration":"352.433709ms","start":"2026-04-23T10:00:33.801871Z","end":"2026-04-23T10:00:34.154305Z","steps":["trace[942267432] 'process raft request' (duration: 352.363758ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:00:34.154524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:00:33.80138Z","time spent":"353.044211ms","remote":"127.0.0.1:42102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T10:00:34.154612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:00:33.801858Z","time spent":"352.695224ms","remote":"127.0.0.1:42102","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-04-23T10:00:35.039693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.717203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T10:00:35.039801Z","caller":"traceutil/trace.go:171","msg":"trace[137271580] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1772; }","duration":"537.885857ms","start":"2026-04-23T10:00:34.501893Z","end":"2026-04-23T10:00:35.039779Z","steps":["trace[137271580] 'count revisions from in-memory index tree' (duration: 537.607501ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:00:35.039846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:00:34.501867Z","time spent":"537.966678ms","remote":"127.0.0.1:42280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":31,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-04-23T10:00:35.764567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.148471ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T10:00:35.764677Z","caller":"traceutil/trace.go:171","msg":"trace[1874044086] linearizableReadLoop","detail":"{readStateIndex:1861; appliedIndex:1859; }","duration":"358.688239ms","start":"2026-04-23T10:00:35.405972Z","end":"2026-04-23T10:00:35.76466Z","steps":["trace[1874044086] 'read index received' (duration: 51.734752ms)","trace[1874044086] 'applied index is now lower than readState.Index' (duration: 306.951977ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T10:00:35.764772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.800532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:00:35.764808Z","caller":"traceutil/trace.go:171","msg":"trace[174687529] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1774; }","duration":"358.866133ms","start":"2026-04-23T10:00:35.405933Z","end":"2026-04-23T10:00:35.764799Z","steps":["trace[174687529] 'agreement among raft nodes before linearized reading' (duration: 358.774571ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:00:35.764851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:00:35.405919Z","time spent":"358.918234ms","remote":"127.0.0.1:59784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-23T10:00:35.765081Z","caller":"traceutil/trace.go:171","msg":"trace[1320052721] transaction","detail":"{read_only:false; response_revision:1774; number_of_response:1; }","duration":"360.166178ms","start":"2026-04-23T10:00:35.404893Z","end":"2026-04-23T10:00:35.765059Z","steps":["trace[1320052721] 'process raft request' (duration: 248.421225ms)","trace[1320052721] 'compare' (duration: 110.05716ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T10:00:35.765179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:00:35.404867Z","time spent":"360.262441ms","remote":"127.0.0.1:42102","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-04-23T10:00:36.333948Z","caller":"traceutil/trace.go:171","msg":"trace[642577820] transaction","detail":"{read_only:false; response_revision:1777; number_of_response:1; }","duration":"170.116043ms","start":"2026-04-23T10:00:36.16361Z","end":"2026-04-23T10:00:36.333726Z","steps":["trace[642577820] 'process raft request' (duration: 147.600832ms)","trace[642577820] 'compare' (duration: 22.182875ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T10:00:36.355489Z","caller":"traceutil/trace.go:171","msg":"trace[1019331754] transaction","detail":"{read_only:false; response_revision:1778; number_of_response:1; }","duration":"190.82497ms","start":"2026-04-23T10:00:36.164615Z","end":"2026-04-23T10:00:36.35544Z","steps":["trace[1019331754] 'process raft request' (duration: 190.225729ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:00:37.66714Z","caller":"traceutil/trace.go:171","msg":"trace[580964012] transaction","detail":"{read_only:false; response_revision:1779; number_of_response:1; }","duration":"201.336072ms","start":"2026-04-23T10:00:37.465786Z","end":"2026-04-23T10:00:37.667122Z","steps":["trace[580964012] 'process raft request' (duration: 201.21612ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:00:38.021333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.320394ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-23T10:00:38.021517Z","caller":"traceutil/trace.go:171","msg":"trace[714514254] linearizableReadLoop","detail":"{readStateIndex:1868; appliedIndex:1866; }","duration":"183.090551ms","start":"2026-04-23T10:00:37.838402Z","end":"2026-04-23T10:00:38.021492Z","steps":["trace[714514254] 'read index received' (duration: 177.27562ms)","trace[714514254] 'applied index is now lower than readState.Index' (duration: 5.814181ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T10:00:38.021632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.225614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-23T10:00:38.021656Z","caller":"traceutil/trace.go:171","msg":"trace[407333206] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1780; }","duration":"183.302775ms","start":"2026-04-23T10:00:37.838345Z","end":"2026-04-23T10:00:38.021647Z","steps":["trace[407333206] 'agreement among raft nodes before linearized reading' (duration: 183.198393ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:00:38.021858Z","caller":"traceutil/trace.go:171","msg":"trace[2110737727] transaction","detail":"{read_only:false; response_revision:1780; number_of_response:1; }","duration":"251.627216ms","start":"2026-04-23T10:00:37.770224Z","end":"2026-04-23T10:00:38.021851Z","steps":["trace[2110737727] 'process raft request' (duration: 251.186597ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:00:44.239317Z","caller":"traceutil/trace.go:171","msg":"trace[188882603] linearizableReadLoop","detail":"{readStateIndex:1932; appliedIndex:1931; }","duration":"185.368325ms","start":"2026-04-23T10:00:44.053926Z","end":"2026-04-23T10:00:44.239294Z","steps":["trace[188882603] 'read index received' (duration: 185.11614ms)","trace[188882603] 'applied index is now lower than readState.Index' (duration: 251.065µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T10:00:44.239579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.636929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-23T10:00:44.239629Z","caller":"traceutil/trace.go:171","msg":"trace[1292318378] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1843; }","duration":"185.745152ms","start":"2026-04-23T10:00:44.053871Z","end":"2026-04-23T10:00:44.239616Z","steps":["trace[1292318378] 'agreement among raft nodes before linearized reading' (duration: 185.551258ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:00:44.239872Z","caller":"traceutil/trace.go:171","msg":"trace[278751514] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"197.827814ms","start":"2026-04-23T10:00:44.041854Z","end":"2026-04-23T10:00:44.239682Z","steps":["trace[278751514] 'process raft request' (duration: 197.247713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:00:44.240161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.593012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1948"} {"level":"info","ts":"2026-04-23T10:00:44.240694Z","caller":"traceutil/trace.go:171","msg":"trace[2100295746] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1843; }","duration":"109.98946ms","start":"2026-04-23T10:00:44.130519Z","end":"2026-04-23T10:00:44.240508Z","steps":["trace[2100295746] 'agreement among raft nodes before linearized reading' (duration: 108.988981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:00:44.752416Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095394663015624,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-23T10:00:44.907517Z","caller":"traceutil/trace.go:171","msg":"trace[2077229646] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"663.24579ms","start":"2026-04-23T10:00:44.244243Z","end":"2026-04-23T10:00:44.907489Z","steps":["trace[2077229646] 'process raft request' (duration: 663.083947ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:00:44.90777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:00:44.244224Z","time spent":"663.371002ms","remote":"127.0.0.1:42102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T10:00:44.958424Z","caller":"traceutil/trace.go:171","msg":"trace[660047408] linearizableReadLoop","detail":"{readStateIndex:1934; appliedIndex:1932; }","duration":"706.565123ms","start":"2026-04-23T10:00:44.251827Z","end":"2026-04-23T10:00:44.958392Z","steps":["trace[660047408] 'read index received' (duration: 655.448232ms)","trace[660047408] 'applied index is now lower than readState.Index' (duration: 51.115381ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T10:00:44.958749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.893219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-23T10:00:44.958987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.34696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T10:00:44.959025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.060001ms","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-04-23T10:00:44.959047Z","caller":"traceutil/trace.go:171","msg":"trace[1867008601] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1845; }","duration":"482.432642ms","start":"2026-04-23T10:00:44.476598Z","end":"2026-04-23T10:00:44.95903Z","steps":["trace[1867008601] 'agreement among raft nodes before linearized reading' (duration: 482.360531ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:00:44.959072Z","caller":"traceutil/trace.go:171","msg":"trace[1172970028] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1845; }","duration":"573.123192ms","start":"2026-04-23T10:00:44.385934Z","end":"2026-04-23T10:00:44.959057Z","steps":["trace[1172970028] 'agreement among raft nodes before linearized reading' (duration: 573.00803ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:00:44.959026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.241646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-23T10:00:44.959091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:00:44.47658Z","time spent":"482.501513ms","remote":"127.0.0.1:59958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-23T10:00:44.959124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:00:44.385925Z","time spent":"573.189173ms","remote":"127.0.0.1:42102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-23T10:00:44.958822Z","caller":"traceutil/trace.go:171","msg":"trace[287723634] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"712.554647ms","start":"2026-04-23T10:00:44.246217Z","end":"2026-04-23T10:00:44.958772Z","steps":["trace[287723634] 'process raft request' (duration: 711.984246ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:00:44.959135Z","caller":"traceutil/trace.go:171","msg":"trace[601544983] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1845; }","duration":"523.350558ms","start":"2026-04-23T10:00:44.435763Z","end":"2026-04-23T10:00:44.959113Z","steps":["trace[601544983] 'agreement among raft nodes before linearized reading' (duration: 523.141494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:00:44.959311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:00:44.2462Z","time spent":"713.026966ms","remote":"127.0.0.1:42102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T10:00:44.959378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:00:44.43567Z","time spent":"523.620143ms","remote":"127.0.0.1:42258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":31,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-04-23T10:00:44.958877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.10909ms","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-04-23T10:00:44.959492Z","caller":"traceutil/trace.go:171","msg":"trace[923796769] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1845; }","duration":"429.776232ms","start":"2026-04-23T10:00:44.529695Z","end":"2026-04-23T10:00:44.959471Z","steps":["trace[923796769] 'agreement among raft nodes before linearized reading' (duration: 429.10259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:00:44.959553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:00:44.529676Z","time spent":"429.859255ms","remote":"127.0.0.1:59854","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-04-23T10:00:44.958907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.376915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-23T10:00:44.959631Z","caller":"traceutil/trace.go:171","msg":"trace[1653828703] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1845; }","duration":"577.14006ms","start":"2026-04-23T10:00:44.382476Z","end":"2026-04-23T10:00:44.959616Z","steps":["trace[1653828703] 'agreement among raft nodes before linearized reading' (duration: 576.307834ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:00:44.959754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:00:44.382457Z","time spent":"577.250552ms","remote":"127.0.0.1:42102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-23T10:00:44.960122Z","caller":"traceutil/trace.go:171","msg":"trace[207825694] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1845; }","duration":"707.194244ms","start":"2026-04-23T10:00:44.251807Z","end":"2026-04-23T10:00:44.959001Z","steps":["trace[207825694] 'agreement among raft nodes before linearized reading' (duration: 706.827777ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:00:44.960262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:00:44.251795Z","time spent":"708.443948ms","remote":"127.0.0.1:42016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":31,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-04-23T10:00:45.092726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.34167ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T10:00:45.092896Z","caller":"traceutil/trace.go:171","msg":"trace[1877894802] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"128.21906ms","start":"2026-04-23T10:00:44.96466Z","end":"2026-04-23T10:00:45.092879Z","steps":["trace[1877894802] 'process raft request' (duration: 128.164809ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:00:45.093039Z","caller":"traceutil/trace.go:171","msg":"trace[1592665884] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"128.910613ms","start":"2026-04-23T10:00:44.96401Z","end":"2026-04-23T10:00:45.092921Z","steps":["trace[1592665884] 'process raft request' (duration: 21.287398ms)","trace[1592665884] 'compare' (duration: 107.235338ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T10:00:46.040032Z","caller":"traceutil/trace.go:171","msg":"trace[1439942125] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"271.467327ms","start":"2026-04-23T10:00:45.768534Z","end":"2026-04-23T10:00:46.040001Z","steps":["trace[1439942125] 'process raft request' (duration: 271.285634ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:00:46.861705Z","caller":"traceutil/trace.go:171","msg":"trace[1265877782] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"263.405642ms","start":"2026-04-23T10:00:46.598275Z","end":"2026-04-23T10:00:46.861681Z","steps":["trace[1265877782] 'process raft request' (duration: 263.250289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:01:05.230452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.043817ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T10:01:05.230569Z","caller":"traceutil/trace.go:171","msg":"trace[1399171286] linearizableReadLoop","detail":"{readStateIndex:2073; appliedIndex:2072; }","duration":"186.128322ms","start":"2026-04-23T10:01:05.044424Z","end":"2026-04-23T10:01:05.230552Z","steps":["trace[1399171286] 'read index received' (duration: 73.797641ms)","trace[1399171286] 'applied index is now lower than readState.Index' (duration: 112.329481ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T10:01:05.230612Z","caller":"traceutil/trace.go:171","msg":"trace[40189386] transaction","detail":"{read_only:false; response_revision:1980; number_of_response:1; }","duration":"186.420058ms","start":"2026-04-23T10:01:05.044162Z","end":"2026-04-23T10:01:05.230582Z","steps":["trace[40189386] 'process raft request' (duration: 74.169518ms)","trace[40189386] 'compare' (duration: 111.909274ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T10:01:05.230801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.361109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-23T10:01:05.230881Z","caller":"traceutil/trace.go:171","msg":"trace[1953578233] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1980; }","duration":"186.48496ms","start":"2026-04-23T10:01:05.044377Z","end":"2026-04-23T10:01:05.230862Z","steps":["trace[1953578233] 'agreement among raft nodes before linearized reading' (duration: 186.248475ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:01:06.340537Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095394663015929,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T10:01:06.79059Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.344710036s","expected-duration":"1s"} {"level":"info","ts":"2026-04-23T10:01:06.791052Z","caller":"traceutil/trace.go:171","msg":"trace[397192690] transaction","detail":"{read_only:false; response_revision:1981; number_of_response:1; }","duration":"1.556099604s","start":"2026-04-23T10:01:05.234924Z","end":"2026-04-23T10:01:06.791024Z","steps":["trace[397192690] 'process raft request' (duration: 1.555921811s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:01:06.791183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:01:05.234908Z","time spent":"1.556196246s","remote":"127.0.0.1:42102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T10:01:06.904344Z","caller":"traceutil/trace.go:171","msg":"trace[982490790] linearizableReadLoop","detail":"{readStateIndex:2076; appliedIndex:2073; }","duration":"1.064202128s","start":"2026-04-23T10:01:05.840121Z","end":"2026-04-23T10:01:06.904323Z","steps":["trace[982490790] 'read index received' (duration: 950.732404ms)","trace[982490790] 'applied index is now lower than readState.Index' (duration: 113.468864ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T10:01:06.904418Z","caller":"traceutil/trace.go:171","msg":"trace[1638734189] transaction","detail":"{read_only:false; response_revision:1983; number_of_response:1; }","duration":"1.666552541s","start":"2026-04-23T10:01:05.237856Z","end":"2026-04-23T10:01:06.904408Z","steps":["trace[1638734189] 'process raft request' (duration: 1.666345707s)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:01:06.904522Z","caller":"traceutil/trace.go:171","msg":"trace[1351383002] transaction","detail":"{read_only:false; response_revision:1984; number_of_response:1; }","duration":"528.312952ms","start":"2026-04-23T10:01:06.376195Z","end":"2026-04-23T10:01:06.904508Z","steps":["trace[1351383002] 'process raft request' (duration: 528.075277ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:01:06.904541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:01:05.237832Z","time spent":"1.666623633s","remote":"127.0.0.1:42102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T10:01:06.904663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.064544974s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-23T10:01:06.904344Z","caller":"traceutil/trace.go:171","msg":"trace[2000534402] transaction","detail":"{read_only:false; response_revision:1982; number_of_response:1; }","duration":"1.666534201s","start":"2026-04-23T10:01:05.23778Z","end":"2026-04-23T10:01:06.904314Z","steps":["trace[2000534402] 'process raft request' (duration: 1.657608078s)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:01:06.904716Z","caller":"traceutil/trace.go:171","msg":"trace[1942594482] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1984; }","duration":"1.064632456s","start":"2026-04-23T10:01:05.840071Z","end":"2026-04-23T10:01:06.904704Z","steps":["trace[1942594482] 'agreement among raft nodes before linearized reading' (duration: 1.064489653s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:01:06.904757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:01:05.840051Z","time spent":"1.064698267s","remote":"127.0.0.1:42102","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-04-23T10:01:06.904756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:01:06.376175Z","time spent":"528.415293ms","remote":"127.0.0.1:42102","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-04-23T10:01:06.90476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:01:05.237763Z","time spent":"1.666952818s","remote":"127.0.0.1:42102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T10:01:06.90487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.795176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:01:06.904905Z","caller":"traceutil/trace.go:171","msg":"trace[1708336646] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1984; }","duration":"787.866418ms","start":"2026-04-23T10:01:06.117028Z","end":"2026-04-23T10:01:06.904895Z","steps":["trace[1708336646] 'agreement among raft nodes before linearized reading' (duration: 787.817197ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:01:06.904939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:01:06.117008Z","time spent":"787.923678ms","remote":"127.0.0.1:59836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-04-23T10:01:06.904973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.516022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:01:06.905095Z","caller":"traceutil/trace.go:171","msg":"trace[648801854] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1984; }","duration":"598.666105ms","start":"2026-04-23T10:01:06.306392Z","end":"2026-04-23T10:01:06.905058Z","steps":["trace[648801854] 'agreement among raft nodes before linearized reading' (duration: 598.494511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:01:06.905139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:01:06.30638Z","time spent":"598.749366ms","remote":"127.0.0.1:41840","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-04-23T10:01:06.905103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.323494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-23T10:01:06.905483Z","caller":"traceutil/trace.go:171","msg":"trace[1084209945] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1984; }","duration":"752.735102ms","start":"2026-04-23T10:01:06.152716Z","end":"2026-04-23T10:01:06.905451Z","steps":["trace[1084209945] 'agreement among raft nodes before linearized reading' (duration: 752.177811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:01:06.905531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:01:06.152705Z","time spent":"752.813893ms","remote":"127.0.0.1:42102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-23T10:01:07.481138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.613073ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T10:01:07.48134Z","caller":"traceutil/trace.go:171","msg":"trace[610829701] linearizableReadLoop","detail":"{readStateIndex:2079; appliedIndex:2077; }","duration":"570.655834ms","start":"2026-04-23T10:01:06.91066Z","end":"2026-04-23T10:01:07.481316Z","steps":["trace[610829701] 'read index received' (duration: 398.799506ms)","trace[610829701] 'applied index is now lower than readState.Index' (duration: 171.855148ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T10:01:07.481418Z","caller":"traceutil/trace.go:171","msg":"trace[973086508] transaction","detail":"{read_only:false; response_revision:1985; number_of_response:1; }","duration":"572.49785ms","start":"2026-04-23T10:01:06.90889Z","end":"2026-04-23T10:01:07.481388Z","steps":["trace[973086508] 'process raft request' (duration: 400.55328ms)","trace[973086508] 'compare' (duration: 171.477301ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T10:01:07.481455Z","caller":"traceutil/trace.go:171","msg":"trace[873190079] transaction","detail":"{read_only:false; response_revision:1986; number_of_response:1; }","duration":"571.540242ms","start":"2026-04-23T10:01:06.909901Z","end":"2026-04-23T10:01:07.481441Z","steps":["trace[873190079] 'process raft request' (duration: 571.345558ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:01:07.481536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:01:06.909881Z","time spent":"571.606413ms","remote":"127.0.0.1:42102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T10:01:07.481832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.157635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:01:07.481904Z","caller":"traceutil/trace.go:171","msg":"trace[533991998] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1986; }","duration":"571.254276ms","start":"2026-04-23T10:01:06.910631Z","end":"2026-04-23T10:01:07.481886Z","steps":["trace[533991998] 'agreement among raft nodes before linearized reading' (duration: 571.059562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:01:07.481959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:01:06.910624Z","time spent":"571.324467ms","remote":"127.0.0.1:41990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-04-23T10:01:07.48188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.82831ms","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-04-23T10:01:07.482049Z","caller":"traceutil/trace.go:171","msg":"trace[35391540] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1986; }","duration":"527.092316ms","start":"2026-04-23T10:01:06.954938Z","end":"2026-04-23T10:01:07.482031Z","steps":["trace[35391540] 'agreement among raft nodes before linearized reading' (duration: 526.718589ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:01:07.482115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:01:06.954894Z","time spent":"527.200789ms","remote":"127.0.0.1:42102","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":"warn","ts":"2026-04-23T10:01:07.482305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.923304ms","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-04-23T10:01:07.482325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:01:06.908875Z","time spent":"572.606402ms","remote":"127.0.0.1:42102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T10:01:07.482374Z","caller":"traceutil/trace.go:171","msg":"trace[667316022] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1986; }","duration":"247.039096ms","start":"2026-04-23T10:01:07.235318Z","end":"2026-04-23T10:01:07.482357Z","steps":["trace[667316022] 'agreement among raft nodes before linearized reading' (duration: 246.455184ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:01:07.654091Z","caller":"traceutil/trace.go:171","msg":"trace[191857792] transaction","detail":"{read_only:false; response_revision:1987; number_of_response:1; }","duration":"166.098076ms","start":"2026-04-23T10:01:07.487964Z","end":"2026-04-23T10:01:07.654062Z","steps":["trace[191857792] 'process raft request' (duration: 155.688057ms)","trace[191857792] 'compare' (duration: 10.291097ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T10:01:07.6973Z","caller":"traceutil/trace.go:171","msg":"trace[1473555462] transaction","detail":"{read_only:false; response_revision:1988; number_of_response:1; }","duration":"208.254899ms","start":"2026-04-23T10:01:07.489006Z","end":"2026-04-23T10:01:07.697261Z","steps":["trace[1473555462] 'process raft request' (duration: 208.031475ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:01:07.968608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.434744ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-23T10:01:07.968707Z","caller":"traceutil/trace.go:171","msg":"trace[949685269] linearizableReadLoop","detail":"{readStateIndex:2082; appliedIndex:2081; }","duration":"172.165313ms","start":"2026-04-23T10:01:07.796525Z","end":"2026-04-23T10:01:07.96869Z","steps":["trace[949685269] 'read index received' (duration: 40.681µs)","trace[949685269] 'applied index is now lower than readState.Index' (duration: 172.122732ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T10:01:07.96883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.292375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:01:07.968858Z","caller":"traceutil/trace.go:171","msg":"trace[1747470836] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1988; }","duration":"172.370827ms","start":"2026-04-23T10:01:07.796478Z","end":"2026-04-23T10:01:07.968849Z","steps":["trace[1747470836] 'agreement among raft nodes before linearized reading' (duration: 172.308026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:01:07.969016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.315275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-04-23T10:01:07.969095Z","caller":"traceutil/trace.go:171","msg":"trace[323464035] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1988; }","duration":"134.434507ms","start":"2026-04-23T10:01:07.834637Z","end":"2026-04-23T10:01:07.969071Z","steps":["trace[323464035] 'agreement among raft nodes before linearized reading' (duration: 134.221243ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:01:13.186997Z","caller":"traceutil/trace.go:171","msg":"trace[1817980037] linearizableReadLoop","detail":"{readStateIndex:2110; appliedIndex:2109; }","duration":"176.226093ms","start":"2026-04-23T10:01:13.010741Z","end":"2026-04-23T10:01:13.186967Z","steps":["trace[1817980037] 'read index received' (duration: 173.944138ms)","trace[1817980037] 'applied index is now lower than readState.Index' (duration: 2.280785ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T10:01:13.187153Z","caller":"traceutil/trace.go:171","msg":"trace[1008643225] transaction","detail":"{read_only:false; response_revision:2015; number_of_response:1; }","duration":"178.029787ms","start":"2026-04-23T10:01:13.009091Z","end":"2026-04-23T10:01:13.187121Z","steps":["trace[1008643225] 'process raft request' (duration: 175.470597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:01:13.18725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.489407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-23T10:01:13.187297Z","caller":"traceutil/trace.go:171","msg":"trace[547719888] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2015; }","duration":"176.58392ms","start":"2026-04-23T10:01:13.010699Z","end":"2026-04-23T10:01:13.187283Z","steps":["trace[547719888] 'agreement among raft nodes before linearized reading' (duration: 176.404106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:01:13.191477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.928331ms","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-04-23T10:01:13.191558Z","caller":"traceutil/trace.go:171","msg":"trace[1528520456] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2016; }","duration":"157.041802ms","start":"2026-04-23T10:01:13.034497Z","end":"2026-04-23T10:01:13.191538Z","steps":["trace[1528520456] 'agreement among raft nodes before linearized reading' (duration: 156.818368ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:01:13.191886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.833539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-23T10:01:13.191924Z","caller":"traceutil/trace.go:171","msg":"trace[622328040] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2016; }","duration":"156.92559ms","start":"2026-04-23T10:01:13.034989Z","end":"2026-04-23T10:01:13.191914Z","steps":["trace[622328040] 'agreement among raft nodes before linearized reading' (duration: 156.820218ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:01:13.191901Z","caller":"traceutil/trace.go:171","msg":"trace[1452590563] transaction","detail":"{read_only:false; response_revision:2016; number_of_response:1; }","duration":"163.909476ms","start":"2026-04-23T10:01:13.027926Z","end":"2026-04-23T10:01:13.191836Z","steps":["trace[1452590563] 'process raft request' (duration: 163.258003ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:01:13.354642Z","caller":"traceutil/trace.go:171","msg":"trace[1650931754] transaction","detail":"{read_only:false; response_revision:2020; number_of_response:1; }","duration":"136.142571ms","start":"2026-04-23T10:01:13.218477Z","end":"2026-04-23T10:01:13.354619Z","steps":["trace[1650931754] 'process raft request' (duration: 136.000318ms)"],"step_count":1}