{"level":"warn","ts":"2026-04-16T10:08:34.540568Z","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-16T10:08:34.540694Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.109: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.109:2380","--initial-cluster=instance=https://199.204.45.109:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.109:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.109: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-16T10:08:34.540768Z","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-16T10:08:34.540777Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-04-16T10:08:34.540796Z","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-16T10:08:34.542015Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"]} {"level":"info","ts":"2026-04-16T10:08:34.542112Z","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.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.109: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-16T10:08:34.546169Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.793945ms"} {"level":"info","ts":"2026-04-16T10:08:34.56163Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d71a1778b31d4ce","cluster-id":"aaca35652fabf9b2"} {"level":"info","ts":"2026-04-16T10:08:34.561911Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=()"} {"level":"info","ts":"2026-04-16T10:08:34.562014Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 0"} {"level":"info","ts":"2026-04-16T10:08:34.56207Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d71a1778b31d4ce [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-16T10:08:34.562146Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 1"} {"level":"info","ts":"2026-04-16T10:08:34.562233Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"warn","ts":"2026-04-16T10:08:34.571591Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-16T10:08:34.572982Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-16T10:08:34.575211Z","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-16T10:08:34.580472Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d71a1778b31d4ce","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-16T10:08:34.580702Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d71a1778b31d4ce","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-16T10:08:34.580905Z","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-16T10:08:34.581067Z","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-16T10:08:34.581086Z","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-16T10:08:34.586337Z","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-16T10:08:34.586479Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-04-16T10:08:34.586547Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-04-16T10:08:34.586686Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d71a1778b31d4ce","initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-16T10:08:34.586722Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-16T10:08:34.591201Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"info","ts":"2026-04-16T10:08:34.591338Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","added-peer-id":"d71a1778b31d4ce","added-peer-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-04-16T10:08:35.163029Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce is starting a new election at term 1"} {"level":"info","ts":"2026-04-16T10:08:35.163084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became pre-candidate at term 1"} {"level":"info","ts":"2026-04-16T10:08:35.163121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgPreVoteResp from d71a1778b31d4ce at term 1"} {"level":"info","ts":"2026-04-16T10:08:35.16314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became candidate at term 2"} {"level":"info","ts":"2026-04-16T10:08:35.163157Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgVoteResp from d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-04-16T10:08:35.163178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became leader at term 2"} {"level":"info","ts":"2026-04-16T10:08:35.163196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d71a1778b31d4ce elected leader d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-04-16T10:08:35.176101Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d71a1778b31d4ce","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.109:2379]}","request-path":"/0/members/d71a1778b31d4ce/attributes","cluster-id":"aaca35652fabf9b2","publish-timeout":"7s"} {"level":"info","ts":"2026-04-16T10:08:35.176116Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T10:08:35.176161Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T10:08:35.176185Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T10:08:35.17826Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-16T10:08:35.178349Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-16T10:08:35.179685Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.109:2379"} {"level":"info","ts":"2026-04-16T10:08:35.180088Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-16T10:08:35.28162Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T10:08:35.281809Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T10:08:35.281872Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-04-16T10:08:42.276529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.289912393s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-16T10:08:42.277574Z","caller":"traceutil/trace.go:171","msg":"trace[215220057] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"1.519346513s","start":"2026-04-16T10:08:40.757527Z","end":"2026-04-16T10:08:42.276874Z","steps":["trace[215220057] 'process raft request' (duration: 228.229805ms)","trace[215220057] 'compare' (duration: 1.289725681s)"],"step_count":2} {"level":"warn","ts":"2026-04-16T10:08:42.277811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:08:40.75752Z","time spent":"1.520208492s","remote":"127.0.0.1:47930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":199,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T10:08:42.277909Z","caller":"traceutil/trace.go:171","msg":"trace[1711727651] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"1.471951359s","start":"2026-04-16T10:08:40.805833Z","end":"2026-04-16T10:08:42.277784Z","steps":["trace[1711727651] 'process raft request' (duration: 1.471041039s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:08:42.278331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:08:40.805807Z","time spent":"1.472232613s","remote":"127.0.0.1:47820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":705,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T10:08:42.277069Z","caller":"traceutil/trace.go:171","msg":"trace[70403650] linearizableReadLoop","detail":"{readStateIndex:314; appliedIndex:313; }","duration":"1.471514004s","start":"2026-04-16T10:08:40.805528Z","end":"2026-04-16T10:08:42.277041Z","steps":["trace[70403650] 'read index received' (duration: 180.319396ms)","trace[70403650] 'applied index is now lower than readState.Index' (duration: 1.291191868s)"],"step_count":2} {"level":"warn","ts":"2026-04-16T10:08:42.279792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.474211396s","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-16T10:08:42.280072Z","caller":"traceutil/trace.go:171","msg":"trace[16276286] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:307; }","duration":"1.474376248s","start":"2026-04-16T10:08:40.805498Z","end":"2026-04-16T10:08:42.279874Z","steps":["trace[16276286] 'agreement among raft nodes before linearized reading' (duration: 1.472474595s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:08:42.281999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:08:40.805479Z","time spent":"1.474652381s","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-16T10:08:42.780119Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367047799735478,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T10:08:42.933244Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.566373905s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-16T10:08:43.792263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"858.718502ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-04-16T10:08:43.792442Z","caller":"traceutil/trace.go:171","msg":"trace[1051529332] transaction","detail":"{read_only:false; number_of_response:0; response_revision:307; }","duration":"2.800789557s","start":"2026-04-16T10:08:40.991621Z","end":"2026-04-16T10:08:43.792411Z","steps":["trace[1051529332] 'process raft request' (duration: 1.941898773s)","trace[1051529332] 'compare' (duration: 858.615141ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T10:08:43.792556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:08:40.991606Z","time spent":"2.800895698s","remote":"127.0.0.1:47914","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T10:08:43.792579Z","caller":"traceutil/trace.go:171","msg":"trace[741858776] transaction","detail":"{read_only:false; number_of_response:0; response_revision:307; }","duration":"2.797043902s","start":"2026-04-16T10:08:40.995514Z","end":"2026-04-16T10:08:43.792558Z","steps":["trace[741858776] 'process raft request' (duration: 2.79685613s)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:08:43.792603Z","caller":"traceutil/trace.go:171","msg":"trace[1926517837] linearizableReadLoop","detail":"{readStateIndex:320; appliedIndex:315; }","duration":"1.513634035s","start":"2026-04-16T10:08:42.278955Z","end":"2026-04-16T10:08:43.792589Z","steps":["trace[1926517837] 'read index received' (duration: 654.544679ms)","trace[1926517837] 'applied index is now lower than readState.Index' (duration: 859.088206ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T10:08:43.792665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:08:40.995493Z","time spent":"2.797135434s","remote":"127.0.0.1:47914","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T10:08:43.792745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.919932257s","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-16T10:08:43.792799Z","caller":"traceutil/trace.go:171","msg":"trace[494559464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:308; }","duration":"2.920038419s","start":"2026-04-16T10:08:40.872745Z","end":"2026-04-16T10:08:43.792783Z","steps":["trace[494559464] 'agreement among raft nodes before linearized reading' (duration: 2.919952748s)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:08:43.792838Z","caller":"traceutil/trace.go:171","msg":"trace[1470718907] transaction","detail":"{read_only:false; number_of_response:0; response_revision:307; }","duration":"2.796927302s","start":"2026-04-16T10:08:40.995906Z","end":"2026-04-16T10:08:43.792833Z","steps":["trace[1470718907] 'process raft request' (duration: 2.796565507s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:08:43.792853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:08:40.872716Z","time spent":"2.92012417s","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T10:08:43.79287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.620410024s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-16T10:08:43.792887Z","caller":"traceutil/trace.go:171","msg":"trace[1533671925] transaction","detail":"{read_only:false; number_of_response:0; response_revision:307; }","duration":"2.797270255s","start":"2026-04-16T10:08:40.995614Z","end":"2026-04-16T10:08:43.792884Z","steps":["trace[1533671925] 'process raft request' (duration: 2.79679284s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:08:43.792914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:08:40.995607Z","time spent":"2.797297435s","remote":"127.0.0.1:47914","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T10:08:43.79292Z","caller":"traceutil/trace.go:171","msg":"trace[477446931] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:308; }","duration":"2.620488334s","start":"2026-04-16T10:08:41.172414Z","end":"2026-04-16T10:08:43.792903Z","steps":["trace[477446931] 'agreement among raft nodes before linearized reading' (duration: 2.620418664s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:08:43.792962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:08:41.172385Z","time spent":"2.620566675s","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-16T10:08:43.793055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.143079932s","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-16T10:08:43.793086Z","caller":"traceutil/trace.go:171","msg":"trace[1219045002] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:308; }","duration":"1.143147562s","start":"2026-04-16T10:08:42.649927Z","end":"2026-04-16T10:08:43.793074Z","steps":["trace[1219045002] 'agreement among raft nodes before linearized reading' (duration: 1.143098572s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:08:43.792868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:08:40.995897Z","time spent":"2.796955532s","remote":"127.0.0.1:47914","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T10:08:43.792976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.509251854s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T10:08:43.793122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:08:42.649904Z","time spent":"1.143211593s","remote":"127.0.0.1:47994","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-16T10:08:43.792796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.801282312s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"info","ts":"2026-04-16T10:08:43.793167Z","caller":"traceutil/trace.go:171","msg":"trace[848196567] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:0; response_revision:308; }","duration":"1.509436346s","start":"2026-04-16T10:08:42.283712Z","end":"2026-04-16T10:08:43.793149Z","steps":["trace[848196567] 'agreement among raft nodes before linearized reading' (duration: 1.509231934s)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:08:43.7932Z","caller":"traceutil/trace.go:171","msg":"trace[506054268] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:308; }","duration":"2.801736328s","start":"2026-04-16T10:08:40.991455Z","end":"2026-04-16T10:08:43.793191Z","steps":["trace[506054268] 'agreement among raft nodes before linearized reading' (duration: 2.801263472s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:08:43.793217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:08:42.283691Z","time spent":"1.509513257s","remote":"127.0.0.1:47930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" "} {"level":"warn","ts":"2026-04-16T10:08:43.793228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:08:40.991428Z","time spent":"2.801787698s","remote":"127.0.0.1:47914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":3489,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"info","ts":"2026-04-16T10:08:43.792809Z","caller":"traceutil/trace.go:171","msg":"trace[95712281] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"1.507032258s","start":"2026-04-16T10:08:42.285769Z","end":"2026-04-16T10:08:43.792801Z","steps":["trace[95712281] 'process raft request' (duration: 1.506735995s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:08:43.793428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:08:42.285753Z","time spent":"1.507552364s","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T10:08:54.12442Z","caller":"traceutil/trace.go:171","msg":"trace[1975963922] linearizableReadLoop","detail":"{readStateIndex:458; appliedIndex:457; }","duration":"277.868421ms","start":"2026-04-16T10:08:53.846523Z","end":"2026-04-16T10:08:54.124392Z","steps":["trace[1975963922] 'read index received' (duration: 277.654899ms)","trace[1975963922] 'applied index is now lower than readState.Index' (duration: 212.332µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T10:08:54.124566Z","caller":"traceutil/trace.go:171","msg":"trace[83999003] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"288.470743ms","start":"2026-04-16T10:08:53.836083Z","end":"2026-04-16T10:08:54.124554Z","steps":["trace[83999003] 'process raft request' (duration: 288.14932ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:08:54.124788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.261887ms","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-16T10:08:54.124838Z","caller":"traceutil/trace.go:171","msg":"trace[1683598739] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:443; }","duration":"278.351937ms","start":"2026-04-16T10:08:53.846471Z","end":"2026-04-16T10:08:54.124823Z","steps":["trace[1683598739] 'agreement among raft nodes before linearized reading' (duration: 278.238666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:08:54.125042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.51387ms","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-16T10:08:54.125085Z","caller":"traceutil/trace.go:171","msg":"trace[395079243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:443; }","duration":"265.609121ms","start":"2026-04-16T10:08:53.859465Z","end":"2026-04-16T10:08:54.125074Z","steps":["trace[395079243] 'agreement among raft nodes before linearized reading' (duration: 265.533ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:08:54.125073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.918349ms","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-16T10:08:54.125178Z","caller":"traceutil/trace.go:171","msg":"trace[371551060] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:443; }","duration":"269.075241ms","start":"2026-04-16T10:08:53.856081Z","end":"2026-04-16T10:08:54.125156Z","steps":["trace[371551060] 'agreement among raft nodes before linearized reading' (duration: 268.759258ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:08:54.338649Z","caller":"traceutil/trace.go:171","msg":"trace[1528346348] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"209.631789ms","start":"2026-04-16T10:08:54.128993Z","end":"2026-04-16T10:08:54.338624Z","steps":["trace[1528346348] 'process raft request' (duration: 140.969853ms)","trace[1528346348] 'compare' (duration: 68.381813ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T10:08:54.338747Z","caller":"traceutil/trace.go:171","msg":"trace[191066375] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"209.174534ms","start":"2026-04-16T10:08:54.129515Z","end":"2026-04-16T10:08:54.33869Z","steps":["trace[191066375] 'process raft request' (duration: 209.003302ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:08:54.33865Z","caller":"traceutil/trace.go:171","msg":"trace[1266743517] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"208.798559ms","start":"2026-04-16T10:08:54.129829Z","end":"2026-04-16T10:08:54.338628Z","steps":["trace[1266743517] 'process raft request' (duration: 208.732518ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:09:02.471608Z","caller":"traceutil/trace.go:171","msg":"trace[111418255] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"100.025784ms","start":"2026-04-16T10:09:02.371548Z","end":"2026-04-16T10:09:02.471574Z","steps":["trace[111418255] 'process raft request' (duration: 99.884272ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:09:03.810475Z","caller":"traceutil/trace.go:171","msg":"trace[1885192718] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"462.165391ms","start":"2026-04-16T10:09:03.348282Z","end":"2026-04-16T10:09:03.810447Z","steps":["trace[1885192718] 'process raft request' (duration: 462.07621ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:09:03.810486Z","caller":"traceutil/trace.go:171","msg":"trace[222640211] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"462.627346ms","start":"2026-04-16T10:09:03.347837Z","end":"2026-04-16T10:09:03.810464Z","steps":["trace[222640211] 'process raft request' (duration: 450.864893ms)","trace[222640211] 'compare' (duration: 11.50747ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T10:09:03.810693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:09:03.347818Z","time spent":"462.832518ms","remote":"127.0.0.1:47784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T10:09:03.810727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:09:03.348275Z","time spent":"462.267982ms","remote":"127.0.0.1:47784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T10:09:03.810931Z","caller":"traceutil/trace.go:171","msg":"trace[494117175] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"456.707879ms","start":"2026-04-16T10:09:03.354211Z","end":"2026-04-16T10:09:03.810919Z","steps":["trace[494117175] 'process raft request' (duration: 456.522387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:09:03.810992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:09:03.354187Z","time spent":"456.779229ms","remote":"127.0.0.1:47784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T10:09:03.81102Z","caller":"traceutil/trace.go:171","msg":"trace[1569715850] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"456.695229ms","start":"2026-04-16T10:09:03.354316Z","end":"2026-04-16T10:09:03.811011Z","steps":["trace[1569715850] 'process raft request' (duration: 456.493667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:09:03.811145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:09:03.354284Z","time spent":"456.81479ms","remote":"127.0.0.1:47784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7684,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T10:09:03.811178Z","caller":"traceutil/trace.go:171","msg":"trace[1485620603] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"454.645375ms","start":"2026-04-16T10:09:03.356488Z","end":"2026-04-16T10:09:03.811133Z","steps":["trace[1485620603] 'process raft request' (duration: 454.464453ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:09:03.811238Z","caller":"traceutil/trace.go:171","msg":"trace[2023837259] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"359.475748ms","start":"2026-04-16T10:09:03.451753Z","end":"2026-04-16T10:09:03.811229Z","steps":["trace[2023837259] 'process raft request' (duration: 359.280266ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:09:03.811305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:09:03.451726Z","time spent":"359.555669ms","remote":"127.0.0.1:47784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T10:09:03.817397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:09:03.356476Z","time spent":"454.810647ms","remote":"127.0.0.1:47784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T10:09:04.253706Z","caller":"traceutil/trace.go:171","msg":"trace[216144639] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"178.411578ms","start":"2026-04-16T10:09:04.07527Z","end":"2026-04-16T10:09:04.253681Z","steps":["trace[216144639] 'process raft request' (duration: 170.209715ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:09:04.254026Z","caller":"traceutil/trace.go:171","msg":"trace[1782582897] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"138.822859ms","start":"2026-04-16T10:09:04.115184Z","end":"2026-04-16T10:09:04.254006Z","steps":["trace[1782582897] 'process raft request' (duration: 138.558006ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:09:04.254352Z","caller":"traceutil/trace.go:171","msg":"trace[542536414] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"138.641418ms","start":"2026-04-16T10:09:04.115691Z","end":"2026-04-16T10:09:04.254332Z","steps":["trace[542536414] 'process raft request' (duration: 138.189413ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:09:10.128911Z","caller":"traceutil/trace.go:171","msg":"trace[1299435061] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"219.80705ms","start":"2026-04-16T10:09:09.909081Z","end":"2026-04-16T10:09:10.128888Z","steps":["trace[1299435061] 'process raft request' (duration: 218.244233ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:09:10.550029Z","caller":"traceutil/trace.go:171","msg":"trace[1455093097] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"403.450912ms","start":"2026-04-16T10:09:10.146552Z","end":"2026-04-16T10:09:10.550002Z","steps":["trace[1455093097] 'process raft request' (duration: 403.312581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:09:10.550194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:09:10.14652Z","time spent":"403.578294ms","remote":"127.0.0.1:47914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T10:09:10.567281Z","caller":"traceutil/trace.go:171","msg":"trace[431015914] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:626; }","duration":"282.309282ms","start":"2026-04-16T10:09:10.284948Z","end":"2026-04-16T10:09:10.567258Z","steps":["trace[431015914] 'read index received' (duration: 264.921697ms)","trace[431015914] 'applied index is now lower than readState.Index' (duration: 17.386685ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T10:09:10.567391Z","caller":"traceutil/trace.go:171","msg":"trace[1538280410] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"379.24018ms","start":"2026-04-16T10:09:10.188143Z","end":"2026-04-16T10:09:10.567383Z","steps":["trace[1538280410] 'process raft request' (duration: 378.999548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:09:10.567473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:09:10.188123Z","time spent":"379.289721ms","remote":"127.0.0.1:48230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T10:09:10.56779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.834648ms","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-16T10:09:10.567852Z","caller":"traceutil/trace.go:171","msg":"trace[164438430] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:608; }","duration":"282.940538ms","start":"2026-04-16T10:09:10.284894Z","end":"2026-04-16T10:09:10.567834Z","steps":["trace[164438430] 'agreement among raft nodes before linearized reading' (duration: 282.823167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:09:11.888913Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.274473004s","expected-duration":"1s"} {"level":"info","ts":"2026-04-16T10:09:11.890104Z","caller":"traceutil/trace.go:171","msg":"trace[1849895927] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:629; }","duration":"1.311637861s","start":"2026-04-16T10:09:10.578444Z","end":"2026-04-16T10:09:11.890082Z","steps":["trace[1849895927] 'read index received' (duration: 36.037274ms)","trace[1849895927] 'applied index is now lower than readState.Index' (duration: 1.275599437s)"],"step_count":2} {"level":"info","ts":"2026-04-16T10:09:11.89009Z","caller":"traceutil/trace.go:171","msg":"trace[633061193] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"1.314434793s","start":"2026-04-16T10:09:10.575634Z","end":"2026-04-16T10:09:11.890068Z","steps":["trace[633061193] 'process raft request' (duration: 1.313486712s)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:09:11.890134Z","caller":"traceutil/trace.go:171","msg":"trace[1312735877] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"1.311895995s","start":"2026-04-16T10:09:10.578227Z","end":"2026-04-16T10:09:11.890123Z","steps":["trace[1312735877] 'process raft request' (duration: 1.311785733s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:09:11.890248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.311783843s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T10:09:11.89031Z","caller":"traceutil/trace.go:171","msg":"trace[1829427512] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:611; }","duration":"1.311883674s","start":"2026-04-16T10:09:10.578408Z","end":"2026-04-16T10:09:11.890292Z","steps":["trace[1829427512] 'agreement among raft nodes before linearized reading' (duration: 1.311783583s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:09:11.890364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:09:10.578393Z","time spent":"1.311957455s","remote":"127.0.0.1:47964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-04-16T10:09:11.890386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:09:10.57561Z","time spent":"1.314575634s","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T10:09:11.890516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:09:10.578216Z","time spent":"1.312067436s","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T10:09:12.390945Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367047799736062,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-16T10:09:12.499363Z","caller":"traceutil/trace.go:171","msg":"trace[1142958596] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"1.780220053s","start":"2026-04-16T10:09:10.719119Z","end":"2026-04-16T10:09:12.499339Z","steps":["trace[1142958596] 'process raft request' (duration: 1.742645922s)","trace[1142958596] 'compare' (duration: 37.317458ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T10:09:12.499489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:09:10.719098Z","time spent":"1.780328294s","remote":"127.0.0.1:47900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T10:09:12.499686Z","caller":"traceutil/trace.go:171","msg":"trace[471356822] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"1.769546853s","start":"2026-04-16T10:09:10.730124Z","end":"2026-04-16T10:09:12.499671Z","steps":["trace[471356822] 'process raft request' (duration: 1.769138598s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:09:12.499757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:09:10.730107Z","time spent":"1.769614273s","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T10:09:12.500029Z","caller":"traceutil/trace.go:171","msg":"trace[970888744] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:631; }","duration":"609.848288ms","start":"2026-04-16T10:09:11.890168Z","end":"2026-04-16T10:09:12.500017Z","steps":["trace[970888744] 'read index received' (duration: 571.500898ms)","trace[970888744] 'applied index is now lower than readState.Index' (duration: 38.34678ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T10:09:12.500117Z","caller":"traceutil/trace.go:171","msg":"trace[1545640936] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"186.46508ms","start":"2026-04-16T10:09:12.313637Z","end":"2026-04-16T10:09:12.500102Z","steps":["trace[1545640936] 'process raft request' (duration: 185.911503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:09:12.500132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.908294661s","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-16T10:09:12.500159Z","caller":"traceutil/trace.go:171","msg":"trace[81157265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:614; }","duration":"1.908347082s","start":"2026-04-16T10:09:10.591804Z","end":"2026-04-16T10:09:12.500151Z","steps":["trace[81157265] 'agreement among raft nodes before linearized reading' (duration: 1.908250431s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:09:12.500189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:09:10.591792Z","time spent":"1.908391192s","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T10:09:12.500191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.46750723s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T10:09:12.500256Z","caller":"traceutil/trace.go:171","msg":"trace[1555820489] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:614; }","duration":"1.467617851s","start":"2026-04-16T10:09:11.03262Z","end":"2026-04-16T10:09:12.500238Z","steps":["trace[1555820489] 'agreement among raft nodes before linearized reading' (duration: 1.46749524s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:09:12.500293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:09:11.032601Z","time spent":"1.467685142s","remote":"127.0.0.1:47744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-16T10:09:24.858996Z","caller":"traceutil/trace.go:171","msg":"trace[1785900435] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"185.341551ms","start":"2026-04-16T10:09:24.673632Z","end":"2026-04-16T10:09:24.858973Z","steps":["trace[1785900435] 'process raft request' (duration: 185.201087ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:09:27.77772Z","caller":"traceutil/trace.go:171","msg":"trace[532212441] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"312.394366ms","start":"2026-04-16T10:09:27.465299Z","end":"2026-04-16T10:09:27.777694Z","steps":["trace[532212441] 'process raft request' (duration: 312.188129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:09:27.777886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:09:27.465263Z","time spent":"312.53862ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T10:09:28.365976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.71001ms","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-16T10:09:28.366102Z","caller":"traceutil/trace.go:171","msg":"trace[2130044698] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:773; }","duration":"398.894766ms","start":"2026-04-16T10:09:27.967183Z","end":"2026-04-16T10:09:28.366078Z","steps":["trace[2130044698] 'range keys from in-memory index tree' (duration: 398.473784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:09:28.366073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.481016ms","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-16T10:09:28.366174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:09:27.967163Z","time spent":"398.993629ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-16T10:09:28.366247Z","caller":"traceutil/trace.go:171","msg":"trace[1127599344] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:773; }","duration":"402.669242ms","start":"2026-04-16T10:09:27.963509Z","end":"2026-04-16T10:09:28.366178Z","steps":["trace[1127599344] 'range keys from in-memory index tree' (duration: 402.233908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:09:28.366334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:09:27.963492Z","time spent":"402.829046ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-16T10:09:28.366557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.280644ms","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-16T10:09:28.366772Z","caller":"traceutil/trace.go:171","msg":"trace[1581105561] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:773; }","duration":"305.48402ms","start":"2026-04-16T10:09:28.061194Z","end":"2026-04-16T10:09:28.366678Z","steps":["trace[1581105561] 'range keys from in-memory index tree' (duration: 304.709176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:09:28.366887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:09:28.061169Z","time spent":"305.699487ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-16T10:09:28.418732Z","caller":"traceutil/trace.go:171","msg":"trace[1705710653] linearizableReadLoop","detail":"{readStateIndex:798; appliedIndex:797; }","duration":"138.290055ms","start":"2026-04-16T10:09:28.280415Z","end":"2026-04-16T10:09:28.418705Z","steps":["trace[1705710653] 'read index received' (duration: 137.80182ms)","trace[1705710653] 'applied index is now lower than readState.Index' (duration: 486.865µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T10:09:28.419007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.571834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-16T10:09:28.41908Z","caller":"traceutil/trace.go:171","msg":"trace[1705357016] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:774; }","duration":"138.678888ms","start":"2026-04-16T10:09:28.280373Z","end":"2026-04-16T10:09:28.419052Z","steps":["trace[1705357016] 'agreement among raft nodes before linearized reading' (duration: 138.44236ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:09:28.419223Z","caller":"traceutil/trace.go:171","msg":"trace[958507320] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"248.837807ms","start":"2026-04-16T10:09:28.170266Z","end":"2026-04-16T10:09:28.419104Z","steps":["trace[958507320] 'process raft request' (duration: 247.694292ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:09:28.525212Z","caller":"traceutil/trace.go:171","msg":"trace[6340928] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"154.217819ms","start":"2026-04-16T10:09:28.370975Z","end":"2026-04-16T10:09:28.525193Z","steps":["trace[6340928] 'process raft request' (duration: 154.189298ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:09:28.525571Z","caller":"traceutil/trace.go:171","msg":"trace[1081548161] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"154.725235ms","start":"2026-04-16T10:09:28.370837Z","end":"2026-04-16T10:09:28.525562Z","steps":["trace[1081548161] 'process raft request' (duration: 154.118297ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:09:28.525659Z","caller":"traceutil/trace.go:171","msg":"trace[361421643] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"154.728795ms","start":"2026-04-16T10:09:28.370925Z","end":"2026-04-16T10:09:28.525654Z","steps":["trace[361421643] 'process raft request' (duration: 154.144497ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:09:59.271921Z","caller":"traceutil/trace.go:171","msg":"trace[871352542] linearizableReadLoop","detail":"{readStateIndex:1001; appliedIndex:1000; }","duration":"486.250575ms","start":"2026-04-16T10:09:58.785647Z","end":"2026-04-16T10:09:59.271897Z","steps":["trace[871352542] 'read index received' (duration: 486.06904ms)","trace[871352542] 'applied index is now lower than readState.Index' (duration: 181.035µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T10:09:59.272002Z","caller":"traceutil/trace.go:171","msg":"trace[1740675036] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"625.015702ms","start":"2026-04-16T10:09:58.64698Z","end":"2026-04-16T10:09:59.271995Z","steps":["trace[1740675036] 'process raft request' (duration: 624.776946ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:09:59.272078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:09:58.646957Z","time spent":"625.061744ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T10:09:59.272256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.678053ms","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-16T10:09:59.27234Z","caller":"traceutil/trace.go:171","msg":"trace[2079375803] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:969; }","duration":"484.783535ms","start":"2026-04-16T10:09:58.787536Z","end":"2026-04-16T10:09:59.27232Z","steps":["trace[2079375803] 'agreement among raft nodes before linearized reading' (duration: 484.60465ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:09:59.272384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.681434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T10:09:59.272419Z","caller":"traceutil/trace.go:171","msg":"trace[906849762] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:969; }","duration":"237.768087ms","start":"2026-04-16T10:09:59.034645Z","end":"2026-04-16T10:09:59.272413Z","steps":["trace[906849762] 'agreement among raft nodes before linearized reading' (duration: 237.718666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:09:59.272417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:09:58.787527Z","time spent":"484.879767ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T10:09:59.272566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.920142ms","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-16T10:09:59.2726Z","caller":"traceutil/trace.go:171","msg":"trace[1417464195] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:969; }","duration":"486.991124ms","start":"2026-04-16T10:09:58.785604Z","end":"2026-04-16T10:09:59.272595Z","steps":["trace[1417464195] 'agreement among raft nodes before linearized reading' (duration: 486.897692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:09:59.272615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:09:58.785584Z","time spent":"487.027475ms","remote":"127.0.0.1:47994","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-16T10:09:59.27269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.676647ms","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-16T10:09:59.272752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.991823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-16T10:09:59.272752Z","caller":"traceutil/trace.go:171","msg":"trace[1730468507] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:969; }","duration":"461.760819ms","start":"2026-04-16T10:09:58.810977Z","end":"2026-04-16T10:09:59.272738Z","steps":["trace[1730468507] 'agreement among raft nodes before linearized reading' (duration: 461.622175ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:09:59.272774Z","caller":"traceutil/trace.go:171","msg":"trace[550817381] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:969; }","duration":"170.014164ms","start":"2026-04-16T10:09:59.102754Z","end":"2026-04-16T10:09:59.272768Z","steps":["trace[550817381] 'agreement among raft nodes before linearized reading' (duration: 169.927572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:09:59.272794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:09:58.810966Z","time spent":"461.817471ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-16T10:09:59.272993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.440105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-16T10:09:59.273016Z","caller":"traceutil/trace.go:171","msg":"trace[752798272] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:969; }","duration":"478.485956ms","start":"2026-04-16T10:09:58.794526Z","end":"2026-04-16T10:09:59.273012Z","steps":["trace[752798272] 'agreement among raft nodes before linearized reading' (duration: 478.431365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:09:59.273035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:09:58.794515Z","time spent":"478.515127ms","remote":"127.0.0.1:47994","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-16T10:10:25.958974Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367047799737077,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-16T10:10:26.375774Z","caller":"traceutil/trace.go:171","msg":"trace[1558296399] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"922.053209ms","start":"2026-04-16T10:10:25.453698Z","end":"2026-04-16T10:10:26.375751Z","steps":["trace[1558296399] 'process raft request' (duration: 913.114431ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:10:26.375897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:10:25.453684Z","time spent":"922.145942ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T10:10:26.376231Z","caller":"traceutil/trace.go:171","msg":"trace[168880491] linearizableReadLoop","detail":"{readStateIndex:1098; appliedIndex:1096; }","duration":"917.78876ms","start":"2026-04-16T10:10:25.458432Z","end":"2026-04-16T10:10:26.376221Z","steps":["trace[168880491] 'read index received' (duration: 908.380431ms)","trace[168880491] 'applied index is now lower than readState.Index' (duration: 9.407599ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T10:10:26.376406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"917.974404ms","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-16T10:10:26.376433Z","caller":"traceutil/trace.go:171","msg":"trace[324008420] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1061; }","duration":"918.026616ms","start":"2026-04-16T10:10:25.458398Z","end":"2026-04-16T10:10:26.376425Z","steps":["trace[324008420] 'agreement among raft nodes before linearized reading' (duration: 917.903253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:10:26.376456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:10:25.458387Z","time spent":"918.064026ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T10:10:26.376615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"889.452071ms","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-16T10:10:26.376655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.605104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T10:10:26.376674Z","caller":"traceutil/trace.go:171","msg":"trace[565480430] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1061; }","duration":"889.557193ms","start":"2026-04-16T10:10:25.487102Z","end":"2026-04-16T10:10:26.376659Z","steps":["trace[565480430] 'agreement among raft nodes before linearized reading' (duration: 889.4048ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:10:26.376682Z","caller":"traceutil/trace.go:171","msg":"trace[1198375465] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1061; }","duration":"344.666175ms","start":"2026-04-16T10:10:26.032008Z","end":"2026-04-16T10:10:26.376674Z","steps":["trace[1198375465] 'agreement among raft nodes before linearized reading' (duration: 344.620604ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:10:26.376705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:10:26.031987Z","time spent":"344.713836ms","remote":"127.0.0.1:47744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T10:10:26.376709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:10:25.48708Z","time spent":"889.617044ms","remote":"127.0.0.1:47994","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-16T10:10:26.376813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.910965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T10:10:26.376817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.452131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T10:10:26.376829Z","caller":"traceutil/trace.go:171","msg":"trace[15918076] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1061; }","duration":"345.955835ms","start":"2026-04-16T10:10:26.030867Z","end":"2026-04-16T10:10:26.376823Z","steps":["trace[15918076] 'agreement among raft nodes before linearized reading' (duration: 345.925564ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:10:26.376845Z","caller":"traceutil/trace.go:171","msg":"trace[781142266] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1061; }","duration":"198.483001ms","start":"2026-04-16T10:10:26.178354Z","end":"2026-04-16T10:10:26.376837Z","steps":["trace[781142266] 'agreement among raft nodes before linearized reading' (duration: 198.4378ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:10:26.376847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:10:26.030852Z","time spent":"345.990486ms","remote":"127.0.0.1:47876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-04-16T10:10:26.376992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.854554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-16T10:10:26.377009Z","caller":"traceutil/trace.go:171","msg":"trace[843322592] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1061; }","duration":"872.894355ms","start":"2026-04-16T10:10:25.504109Z","end":"2026-04-16T10:10:26.377003Z","steps":["trace[843322592] 'agreement among raft nodes before linearized reading' (duration: 872.840224ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:10:26.377027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:10:25.504098Z","time spent":"872.923626ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-16T10:10:26.377133Z","caller":"traceutil/trace.go:171","msg":"trace[326896036] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"845.995568ms","start":"2026-04-16T10:10:25.530324Z","end":"2026-04-16T10:10:26.37632Z","steps":["trace[326896036] 'process raft request' (duration: 845.847085ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:10:26.377202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:10:25.530296Z","time spent":"846.857949ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T10:10:29.636533Z","caller":"traceutil/trace.go:171","msg":"trace[1009533192] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"157.766613ms","start":"2026-04-16T10:10:29.478749Z","end":"2026-04-16T10:10:29.636515Z","steps":["trace[1009533192] 'process raft request' (duration: 157.659851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:10:30.611122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.913141ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T10:10:30.611215Z","caller":"traceutil/trace.go:171","msg":"trace[1511497932] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"181.657199ms","start":"2026-04-16T10:10:30.429544Z","end":"2026-04-16T10:10:30.611201Z","steps":["trace[1511497932] 'process raft request' (duration: 35.593577ms)","trace[1511497932] 'compare' (duration: 145.804909ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T10:10:30.636525Z","caller":"traceutil/trace.go:171","msg":"trace[1852991109] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"206.75403ms","start":"2026-04-16T10:10:30.429742Z","end":"2026-04-16T10:10:30.636496Z","steps":["trace[1852991109] 'process raft request' (duration: 206.533838ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:10:30.636673Z","caller":"traceutil/trace.go:171","msg":"trace[763361367] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"206.848331ms","start":"2026-04-16T10:10:30.429812Z","end":"2026-04-16T10:10:30.636661Z","steps":["trace[763361367] 'process raft request' (duration: 206.610298ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:10:30.636749Z","caller":"traceutil/trace.go:171","msg":"trace[1670236305] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"206.406416ms","start":"2026-04-16T10:10:30.430329Z","end":"2026-04-16T10:10:30.636735Z","steps":["trace[1670236305] 'process raft request' (duration: 206.294565ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:10:33.114965Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367047799737129,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T10:10:33.615451Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367047799737129,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T10:10:33.757212Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.230486154s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-16T10:10:33.760233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.346150987s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T10:10:33.760341Z","caller":"traceutil/trace.go:171","msg":"trace[9526673] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1079; }","duration":"1.346315339s","start":"2026-04-16T10:10:32.413999Z","end":"2026-04-16T10:10:33.760314Z","steps":["trace[9526673] 'count revisions from in-memory index tree' (duration: 1.346042036s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:10:33.760564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:10:32.413978Z","time spent":"1.346562423s","remote":"127.0.0.1:50906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-04-16T10:10:33.761003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.503601196s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T10:10:33.76105Z","caller":"traceutil/trace.go:171","msg":"trace[288742355] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1079; }","duration":"1.503699807s","start":"2026-04-16T10:10:32.257339Z","end":"2026-04-16T10:10:33.761039Z","steps":["trace[288742355] 'count revisions from in-memory index tree' (duration: 1.503516495s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:10:33.761047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.004585048s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T10:10:33.761107Z","caller":"traceutil/trace.go:171","msg":"trace[2088502162] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1079; }","duration":"2.004810291s","start":"2026-04-16T10:10:31.756281Z","end":"2026-04-16T10:10:33.761092Z","steps":["trace[2088502162] 'count revisions from in-memory index tree' (duration: 2.004521807s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:10:33.76116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.407887189s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T10:10:33.761186Z","caller":"traceutil/trace.go:171","msg":"trace[1755641894] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1079; }","duration":"1.40795156s","start":"2026-04-16T10:10:32.353225Z","end":"2026-04-16T10:10:33.761177Z","steps":["trace[1755641894] 'count revisions from in-memory index tree' (duration: 1.407799728s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:10:33.761169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:10:31.756268Z","time spent":"2.004876342s","remote":"127.0.0.1:48060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":16,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-04-16T10:10:33.761071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:10:32.257315Z","time spent":"1.503749858s","remote":"127.0.0.1:48206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-04-16T10:10:33.761205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:10:32.353204Z","time spent":"1.40799538s","remote":"127.0.0.1:50800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"info","ts":"2026-04-16T10:10:33.761765Z","caller":"traceutil/trace.go:171","msg":"trace[1756118439] linearizableReadLoop","detail":"{readStateIndex:1118; appliedIndex:1117; }","duration":"1.146915822s","start":"2026-04-16T10:10:32.61484Z","end":"2026-04-16T10:10:33.761756Z","steps":["trace[1756118439] 'read index received' (duration: 1.14279275s)","trace[1756118439] 'applied index is now lower than readState.Index' (duration: 4.122452ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T10:10:33.761852Z","caller":"traceutil/trace.go:171","msg":"trace[1917358117] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"1.340591485s","start":"2026-04-16T10:10:32.421243Z","end":"2026-04-16T10:10:33.761835Z","steps":["trace[1917358117] 'process raft request' (duration: 1.336371672s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:10:33.761939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:10:32.421218Z","time spent":"1.340666817s","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T10:10:33.761989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.147150596s","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-16T10:10:33.762053Z","caller":"traceutil/trace.go:171","msg":"trace[502742660] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1080; }","duration":"1.147253317s","start":"2026-04-16T10:10:32.614781Z","end":"2026-04-16T10:10:33.762034Z","steps":["trace[502742660] 'agreement among raft nodes before linearized reading' (duration: 1.147070565s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:10:33.762103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:10:32.614752Z","time spent":"1.147340329s","remote":"127.0.0.1:47994","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-16T10:10:33.94874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.429905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T10:10:33.948755Z","caller":"traceutil/trace.go:171","msg":"trace[1758094090] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"182.011404ms","start":"2026-04-16T10:10:33.766717Z","end":"2026-04-16T10:10:33.948729Z","steps":["trace[1758094090] 'process raft request' (duration: 181.853412ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:10:33.948822Z","caller":"traceutil/trace.go:171","msg":"trace[102427680] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1080; }","duration":"915.550018ms","start":"2026-04-16T10:10:33.033252Z","end":"2026-04-16T10:10:33.948802Z","steps":["trace[102427680] 'agreement among raft nodes before linearized reading' (duration: 915.406486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:10:33.948846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.582938ms","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-16T10:10:33.948888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.30806624s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-16T10:10:33.948902Z","caller":"traceutil/trace.go:171","msg":"trace[1592726368] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1081; }","duration":"234.716769ms","start":"2026-04-16T10:10:33.714176Z","end":"2026-04-16T10:10:33.948893Z","steps":["trace[1592726368] 'agreement among raft nodes before linearized reading' (duration: 234.526826ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:10:33.948955Z","caller":"traceutil/trace.go:171","msg":"trace[970136540] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1081; }","duration":"1.30812955s","start":"2026-04-16T10:10:32.640791Z","end":"2026-04-16T10:10:33.94892Z","steps":["trace[970136540] 'agreement among raft nodes before linearized reading' (duration: 1.307895967s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:10:33.949051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:10:32.64078Z","time spent":"1.308259772s","remote":"127.0.0.1:47994","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-16T10:10:33.948867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:10:33.033238Z","time spent":"915.620608ms","remote":"127.0.0.1:47744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T10:10:33.948916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.096112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T10:10:33.949172Z","caller":"traceutil/trace.go:171","msg":"trace[1821018445] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1081; }","duration":"660.350685ms","start":"2026-04-16T10:10:33.28881Z","end":"2026-04-16T10:10:33.949161Z","steps":["trace[1821018445] 'agreement among raft nodes before linearized reading' (duration: 660.079592ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:10:33.948674Z","caller":"traceutil/trace.go:171","msg":"trace[930162662] linearizableReadLoop","detail":"{readStateIndex:1119; appliedIndex:1118; }","duration":"186.861756ms","start":"2026-04-16T10:10:33.761789Z","end":"2026-04-16T10:10:33.948651Z","steps":["trace[930162662] 'read index received' (duration: 170.306963ms)","trace[930162662] 'applied index is now lower than readState.Index' (duration: 16.551793ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T10:10:33.949262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:10:33.288722Z","time spent":"660.526308ms","remote":"127.0.0.1:47832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-04-16T10:10:33.94939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.309293855s","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-16T10:10:33.949427Z","caller":"traceutil/trace.go:171","msg":"trace[492508781] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1081; }","duration":"1.309372176s","start":"2026-04-16T10:10:32.640046Z","end":"2026-04-16T10:10:33.949418Z","steps":["trace[492508781] 'agreement among raft nodes before linearized reading' (duration: 1.309276284s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:10:33.949459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:10:32.640023Z","time spent":"1.309427997s","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T10:10:33.94934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.308536065s","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-16T10:10:33.949559Z","caller":"traceutil/trace.go:171","msg":"trace[61901342] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1081; }","duration":"1.30887457s","start":"2026-04-16T10:10:32.640657Z","end":"2026-04-16T10:10:33.949531Z","steps":["trace[61901342] 'agreement among raft nodes before linearized reading' (duration: 1.308308203s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:10:33.949626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:10:32.640633Z","time spent":"1.308965021s","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-16T10:10:58.715643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.000893ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T10:10:58.71584Z","caller":"traceutil/trace.go:171","msg":"trace[215061546] linearizableReadLoop","detail":"{readStateIndex:1207; appliedIndex:1204; }","duration":"430.59743ms","start":"2026-04-16T10:10:58.285227Z","end":"2026-04-16T10:10:58.715824Z","steps":["trace[215061546] 'read index received' (duration: 55.771µs)","trace[215061546] 'applied index is now lower than readState.Index' (duration: 430.540569ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T10:10:58.715888Z","caller":"traceutil/trace.go:171","msg":"trace[209905426] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"607.285045ms","start":"2026-04-16T10:10:58.108587Z","end":"2026-04-16T10:10:58.715872Z","steps":["trace[209905426] 'process raft request' (duration: 607.149473ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:10:58.715936Z","caller":"traceutil/trace.go:171","msg":"trace[1838051800] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"607.365846ms","start":"2026-04-16T10:10:58.108539Z","end":"2026-04-16T10:10:58.715905Z","steps":["trace[1838051800] 'process raft request' (duration: 165.981308ms)","trace[1838051800] 'compare' (duration: 440.867832ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T10:10:58.715985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:10:58.108566Z","time spent":"607.355907ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T10:10:58.715996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.775532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-16T10:10:58.716007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:10:58.108527Z","time spent":"607.441087ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T10:10:58.716024Z","caller":"traceutil/trace.go:171","msg":"trace[965756634] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:1164; }","duration":"430.843574ms","start":"2026-04-16T10:10:58.285171Z","end":"2026-04-16T10:10:58.716015Z","steps":["trace[965756634] 'agreement among raft nodes before linearized reading' (duration: 430.698042ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:10:58.715986Z","caller":"traceutil/trace.go:171","msg":"trace[1530392511] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"607.336576ms","start":"2026-04-16T10:10:58.108608Z","end":"2026-04-16T10:10:58.715945Z","steps":["trace[1530392511] 'process raft request' (duration: 607.174394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:10:58.716195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:10:58.108585Z","time spent":"607.514708ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T10:10:58.716051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:10:58.285151Z","time spent":"430.892564ms","remote":"127.0.0.1:47772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.109\" "} {"level":"warn","ts":"2026-04-16T10:10:59.180141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:10:58.717099Z","time spent":"463.036606ms","remote":"127.0.0.1:47772","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-16T10:10:59.387855Z","caller":"traceutil/trace.go:171","msg":"trace[715986124] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"206.398516ms","start":"2026-04-16T10:10:59.181437Z","end":"2026-04-16T10:10:59.387836Z","steps":["trace[715986124] 'process raft request' (duration: 196.55999ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:11:00.257236Z","caller":"traceutil/trace.go:171","msg":"trace[1406818917] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"144.329931ms","start":"2026-04-16T10:11:00.112865Z","end":"2026-04-16T10:11:00.257195Z","steps":["trace[1406818917] 'process raft request' (duration: 144.101998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:11:01.331105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"833.563216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T10:11:01.331199Z","caller":"traceutil/trace.go:171","msg":"trace[1366083971] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1168; }","duration":"833.709748ms","start":"2026-04-16T10:11:00.497475Z","end":"2026-04-16T10:11:01.331185Z","steps":["trace[1366083971] 'count revisions from in-memory index tree' (duration: 833.427044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:11:01.331231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:11:00.497454Z","time spent":"833.768258ms","remote":"127.0.0.1:47840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":14,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-04-16T10:11:01.331348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.847161ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T10:11:01.331526Z","caller":"traceutil/trace.go:171","msg":"trace[1523112639] linearizableReadLoop","detail":"{readStateIndex:1213; appliedIndex:1212; }","duration":"611.716111ms","start":"2026-04-16T10:11:00.719753Z","end":"2026-04-16T10:11:01.331469Z","steps":["trace[1523112639] 'read index received' (duration: 358.517816ms)","trace[1523112639] 'applied index is now lower than readState.Index' (duration: 253.191165ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T10:11:01.331583Z","caller":"traceutil/trace.go:171","msg":"trace[1748938089] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"823.496537ms","start":"2026-04-16T10:11:00.508072Z","end":"2026-04-16T10:11:01.331568Z","steps":["trace[1748938089] 'process raft request' (duration: 570.367432ms)","trace[1748938089] 'compare' (duration: 252.73908ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T10:11:01.331647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:11:00.508055Z","time spent":"823.558617ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T10:11:01.331821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.060586ms","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-16T10:11:01.331861Z","caller":"traceutil/trace.go:171","msg":"trace[197042479] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1169; }","duration":"612.103477ms","start":"2026-04-16T10:11:00.719747Z","end":"2026-04-16T10:11:01.33185Z","steps":["trace[197042479] 'agreement among raft nodes before linearized reading' (duration: 611.874524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:11:01.331893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:11:00.71969Z","time spent":"612.193828ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T10:11:01.332016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.466246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T10:11:01.331901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.232866ms","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-16T10:11:01.332042Z","caller":"traceutil/trace.go:171","msg":"trace[1755224794] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1169; }","duration":"298.521547ms","start":"2026-04-16T10:11:01.033514Z","end":"2026-04-16T10:11:01.332035Z","steps":["trace[1755224794] 'agreement among raft nodes before linearized reading' (duration: 298.488796ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:11:01.332054Z","caller":"traceutil/trace.go:171","msg":"trace[380217867] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1169; }","duration":"611.404058ms","start":"2026-04-16T10:11:00.720641Z","end":"2026-04-16T10:11:01.332045Z","steps":["trace[380217867] 'agreement among raft nodes before linearized reading' (duration: 611.195595ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:11:01.332086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:11:00.720631Z","time spent":"611.446348ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-16T10:11:01.332157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.450328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-16T10:11:01.332181Z","caller":"traceutil/trace.go:171","msg":"trace[294285898] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1169; }","duration":"611.487938ms","start":"2026-04-16T10:11:00.720687Z","end":"2026-04-16T10:11:01.332175Z","steps":["trace[294285898] 'agreement among raft nodes before linearized reading' (duration: 611.431398ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:11:01.3322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:11:00.720681Z","time spent":"611.514319ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-16T10:11:01.72128Z","caller":"traceutil/trace.go:171","msg":"trace[193207787] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"385.156847ms","start":"2026-04-16T10:11:01.336096Z","end":"2026-04-16T10:11:01.721253Z","steps":["trace[193207787] 'process raft request' (duration: 316.007881ms)","trace[193207787] 'compare' (duration: 69.026335ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T10:11:01.721439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:11:01.336073Z","time spent":"385.285369ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T10:11:01.732956Z","caller":"traceutil/trace.go:171","msg":"trace[36393996] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"396.159869ms","start":"2026-04-16T10:11:01.336762Z","end":"2026-04-16T10:11:01.732922Z","steps":["trace[36393996] 'process raft request' (duration: 395.957497ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:11:01.732988Z","caller":"traceutil/trace.go:171","msg":"trace[999025309] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"394.63895ms","start":"2026-04-16T10:11:01.338324Z","end":"2026-04-16T10:11:01.732963Z","steps":["trace[999025309] 'process raft request' (duration: 394.525198ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:11:01.733067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:11:01.336751Z","time spent":"396.26018ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T10:11:01.733118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:11:01.338312Z","time spent":"394.736391ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T10:11:03.136096Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367047799737364,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T10:11:03.792773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.151819ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T10:11:03.793646Z","caller":"traceutil/trace.go:171","msg":"trace[1814884939] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"1.437906523s","start":"2026-04-16T10:11:02.35572Z","end":"2026-04-16T10:11:03.793627Z","steps":["trace[1814884939] 'process raft request' (duration: 784.830602ms)","trace[1814884939] 'compare' (duration: 651.780875ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T10:11:03.793747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:11:02.355699Z","time spent":"1.437997314s","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T10:11:03.793934Z","caller":"traceutil/trace.go:171","msg":"trace[1807589939] linearizableReadLoop","detail":"{readStateIndex:1219; appliedIndex:1218; }","duration":"1.157908284s","start":"2026-04-16T10:11:02.636002Z","end":"2026-04-16T10:11:03.79391Z","steps":["trace[1807589939] 'read index received' (duration: 504.469028ms)","trace[1807589939] 'applied index is now lower than readState.Index' (duration: 653.436526ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T10:11:03.828657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.290454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T10:11:03.828737Z","caller":"traceutil/trace.go:171","msg":"trace[1387991405] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1175; }","duration":"795.408736ms","start":"2026-04-16T10:11:03.033308Z","end":"2026-04-16T10:11:03.828717Z","steps":["trace[1387991405] 'agreement among raft nodes before linearized reading' (duration: 795.296645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:11:03.828786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:11:03.033289Z","time spent":"795.486117ms","remote":"127.0.0.1:47744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T10:11:03.828886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.192841682s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T10:11:03.8289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.792795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T10:11:03.829003Z","caller":"traceutil/trace.go:171","msg":"trace[213510546] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1175; }","duration":"236.954457ms","start":"2026-04-16T10:11:03.592029Z","end":"2026-04-16T10:11:03.828984Z","steps":["trace[213510546] 'agreement among raft nodes before linearized reading' (duration: 236.757904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:11:03.828986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.178133ms","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-16T10:11:03.829089Z","caller":"traceutil/trace.go:171","msg":"trace[766285071] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1175; }","duration":"103.352595ms","start":"2026-04-16T10:11:03.725717Z","end":"2026-04-16T10:11:03.829069Z","steps":["trace[766285071] 'agreement among raft nodes before linearized reading' (duration: 103.089831ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:11:03.829241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.094299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-16T10:11:03.829166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.307135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T10:11:03.829386Z","caller":"traceutil/trace.go:171","msg":"trace[520583037] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1175; }","duration":"444.566019ms","start":"2026-04-16T10:11:03.384811Z","end":"2026-04-16T10:11:03.829377Z","steps":["trace[520583037] 'agreement among raft nodes before linearized reading' (duration: 444.295985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:11:03.829414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:11:03.384793Z","time spent":"444.611309ms","remote":"127.0.0.1:47842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-04-16T10:11:03.82937Z","caller":"traceutil/trace.go:171","msg":"trace[26949240] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1175; }","duration":"627.25313ms","start":"2026-04-16T10:11:03.202102Z","end":"2026-04-16T10:11:03.829355Z","steps":["trace[26949240] 'agreement among raft nodes before linearized reading' (duration: 626.922676ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:11:03.8296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:11:03.202078Z","time spent":"627.507774ms","remote":"127.0.0.1:51054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":3,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-04-16T10:11:03.829941Z","caller":"traceutil/trace.go:171","msg":"trace[611552498] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1175; }","duration":"1.193027294s","start":"2026-04-16T10:11:02.635946Z","end":"2026-04-16T10:11:03.828974Z","steps":["trace[611552498] 'agreement among raft nodes before linearized reading' (duration: 1.158138996s)","trace[611552498] 'count revisions from in-memory index tree' (duration: 34.719806ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T10:11:03.830044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:11:02.635923Z","time spent":"1.194100658s","remote":"127.0.0.1:48114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"info","ts":"2026-04-16T10:11:05.950461Z","caller":"traceutil/trace.go:171","msg":"trace[1137832202] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"148.258401ms","start":"2026-04-16T10:11:05.802182Z","end":"2026-04-16T10:11:05.95044Z","steps":["trace[1137832202] 'process raft request' (duration: 147.787995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:11:40.361383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.065581ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T10:11:40.36169Z","caller":"traceutil/trace.go:171","msg":"trace[1814574108] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"254.47547ms","start":"2026-04-16T10:11:40.107165Z","end":"2026-04-16T10:11:40.361641Z","steps":["trace[1814574108] 'process raft request' (duration: 126.074849ms)","trace[1814574108] 'compare' (duration: 127.957988ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T10:11:40.361751Z","caller":"traceutil/trace.go:171","msg":"trace[1823361871] linearizableReadLoop","detail":"{readStateIndex:1347; appliedIndex:1346; }","duration":"211.489574ms","start":"2026-04-16T10:11:40.150233Z","end":"2026-04-16T10:11:40.361723Z","steps":["trace[1823361871] 'read index received' (duration: 83.019761ms)","trace[1823361871] 'applied index is now lower than readState.Index' (duration: 128.466413ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T10:11:40.361718Z","caller":"traceutil/trace.go:171","msg":"trace[652079406] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"207.841462ms","start":"2026-04-16T10:11:40.153859Z","end":"2026-04-16T10:11:40.361701Z","steps":["trace[652079406] 'process raft request' (duration: 207.77911ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:11:40.361911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.033983ms","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-16T10:11:40.361972Z","caller":"traceutil/trace.go:171","msg":"trace[1345105940] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1296; }","duration":"214.122825ms","start":"2026-04-16T10:11:40.147834Z","end":"2026-04-16T10:11:40.361957Z","steps":["trace[1345105940] 'agreement among raft nodes before linearized reading' (duration: 213.925099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:11:40.361982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.663504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-16T10:11:40.362024Z","caller":"traceutil/trace.go:171","msg":"trace[969694317] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1296; }","duration":"215.744816ms","start":"2026-04-16T10:11:40.146267Z","end":"2026-04-16T10:11:40.362012Z","steps":["trace[969694317] 'agreement among raft nodes before linearized reading' (duration: 215.56781ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:11:40.540765Z","caller":"traceutil/trace.go:171","msg":"trace[1829633487] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"174.39302ms","start":"2026-04-16T10:11:40.366347Z","end":"2026-04-16T10:11:40.54074Z","steps":["trace[1829633487] 'process raft request' (duration: 174.052669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:12:12.570616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.108835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T10:12:12.570816Z","caller":"traceutil/trace.go:171","msg":"trace[882860061] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1399; }","duration":"143.357703ms","start":"2026-04-16T10:12:12.427432Z","end":"2026-04-16T10:12:12.57079Z","steps":["trace[882860061] 'count revisions from in-memory index tree' (duration: 143.027772ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:12:12.57084Z","caller":"traceutil/trace.go:171","msg":"trace[469523699] transaction","detail":"{read_only:false; response_revision:1400; number_of_response:1; }","duration":"102.227844ms","start":"2026-04-16T10:12:12.468569Z","end":"2026-04-16T10:12:12.570797Z","steps":["trace[469523699] 'process raft request' (duration: 100.919604ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:12:12.713426Z","caller":"traceutil/trace.go:171","msg":"trace[1958564065] transaction","detail":"{read_only:false; response_revision:1401; number_of_response:1; }","duration":"139.039439ms","start":"2026-04-16T10:12:12.574356Z","end":"2026-04-16T10:12:12.713395Z","steps":["trace[1958564065] 'process raft request' (duration: 110.85354ms)","trace[1958564065] 'compare' (duration: 27.987723ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T10:12:16.687968Z","caller":"traceutil/trace.go:171","msg":"trace[1461926732] transaction","detail":"{read_only:false; response_revision:1413; number_of_response:1; }","duration":"102.307246ms","start":"2026-04-16T10:12:16.58564Z","end":"2026-04-16T10:12:16.687947Z","steps":["trace[1461926732] 'process raft request' (duration: 102.176922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:12:18.426552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.419493ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T10:12:18.42687Z","caller":"traceutil/trace.go:171","msg":"trace[283025598] transaction","detail":"{read_only:false; response_revision:1419; number_of_response:1; }","duration":"131.518847ms","start":"2026-04-16T10:12:18.295285Z","end":"2026-04-16T10:12:18.426803Z","steps":["trace[283025598] 'process raft request' (duration: 25.777584ms)","trace[283025598] 'compare' (duration: 105.3093ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T10:15:18.064637Z","caller":"traceutil/trace.go:171","msg":"trace[1058962986] linearizableReadLoop","detail":"{readStateIndex:2105; appliedIndex:2104; }","duration":"200.716764ms","start":"2026-04-16T10:15:17.863896Z","end":"2026-04-16T10:15:18.064612Z","steps":["trace[1058962986] 'read index received' (duration: 200.530289ms)","trace[1058962986] 'applied index is now lower than readState.Index' (duration: 185.735µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T10:15:18.064693Z","caller":"traceutil/trace.go:171","msg":"trace[824098147] transaction","detail":"{read_only:false; response_revision:2010; number_of_response:1; }","duration":"337.30016ms","start":"2026-04-16T10:15:17.727363Z","end":"2026-04-16T10:15:18.064663Z","steps":["trace[824098147] 'process raft request' (duration: 337.130036ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:15:18.064868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.95007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-16T10:15:18.064902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:15:17.727342Z","time spent":"337.466185ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T10:15:18.064912Z","caller":"traceutil/trace.go:171","msg":"trace[312185751] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2010; }","duration":"201.050532ms","start":"2026-04-16T10:15:17.863851Z","end":"2026-04-16T10:15:18.064901Z","steps":["trace[312185751] 'agreement among raft nodes before linearized reading' (duration: 200.891288ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:15:18.065022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.710454ms","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-16T10:15:18.065101Z","caller":"traceutil/trace.go:171","msg":"trace[1470042628] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2010; }","duration":"192.823398ms","start":"2026-04-16T10:15:17.872255Z","end":"2026-04-16T10:15:18.065079Z","steps":["trace[1470042628] 'agreement among raft nodes before linearized reading' (duration: 192.599792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:15:18.065272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.459278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-16T10:15:18.065303Z","caller":"traceutil/trace.go:171","msg":"trace[2097747328] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2010; }","duration":"198.510679ms","start":"2026-04-16T10:15:17.866784Z","end":"2026-04-16T10:15:18.065294Z","steps":["trace[2097747328] 'agreement among raft nodes before linearized reading' (duration: 198.021907ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:15:19.109126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.969546ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T10:15:19.109511Z","caller":"traceutil/trace.go:171","msg":"trace[510194863] linearizableReadLoop","detail":"{readStateIndex:2108; appliedIndex:2105; }","duration":"810.966718ms","start":"2026-04-16T10:15:18.298519Z","end":"2026-04-16T10:15:19.109486Z","steps":["trace[510194863] 'read index received' (duration: 151.575961ms)","trace[510194863] 'applied index is now lower than readState.Index' (duration: 659.389527ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T10:15:19.109554Z","caller":"traceutil/trace.go:171","msg":"trace[1207070962] transaction","detail":"{read_only:false; response_revision:2013; number_of_response:1; }","duration":"1.038271074s","start":"2026-04-16T10:15:18.071262Z","end":"2026-04-16T10:15:19.109533Z","steps":["trace[1207070962] 'process raft request' (duration: 1.038023318s)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:15:19.109454Z","caller":"traceutil/trace.go:171","msg":"trace[1419342941] transaction","detail":"{read_only:false; response_revision:2011; number_of_response:1; }","duration":"1.039354351s","start":"2026-04-16T10:15:18.070057Z","end":"2026-04-16T10:15:19.109411Z","steps":["trace[1419342941] 'process raft request' (duration: 380.025246ms)","trace[1419342941] 'compare' (duration: 658.701159ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T10:15:19.109614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:15:18.07125Z","time spent":"1.038336525s","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T10:15:19.109333Z","caller":"traceutil/trace.go:171","msg":"trace[835733745] transaction","detail":"{read_only:false; response_revision:2012; number_of_response:1; }","duration":"1.038592751s","start":"2026-04-16T10:15:18.070724Z","end":"2026-04-16T10:15:19.109317Z","steps":["trace[835733745] 'process raft request' (duration: 1.038492799s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:15:19.109725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:15:18.070706Z","time spent":"1.038981421s","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T10:15:19.109744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:15:18.070029Z","time spent":"1.039599346s","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T10:15:19.109757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.226175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-16T10:15:19.10989Z","caller":"traceutil/trace.go:171","msg":"trace[790938966] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:2013; }","duration":"811.329376ms","start":"2026-04-16T10:15:18.298466Z","end":"2026-04-16T10:15:19.109795Z","steps":["trace[790938966] 'agreement among raft nodes before linearized reading' (duration: 811.157152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:15:19.109944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:15:18.298445Z","time spent":"811.487091ms","remote":"127.0.0.1:47772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.109\" "} {"level":"warn","ts":"2026-04-16T10:15:19.611494Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367047799739571,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T10:15:19.855072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.238424ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T10:15:19.855233Z","caller":"traceutil/trace.go:171","msg":"trace[356493024] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2013; }","duration":"600.412038ms","start":"2026-04-16T10:15:19.254796Z","end":"2026-04-16T10:15:19.855208Z","steps":["trace[356493024] 'range keys from in-memory index tree' (duration: 600.217384ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:15:19.855489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.879142ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-04-16T10:15:19.855584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:15:19.110971Z","time spent":"744.609956ms","remote":"127.0.0.1:47772","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-16T10:15:19.858283Z","caller":"traceutil/trace.go:171","msg":"trace[451490539] linearizableReadLoop","detail":"{readStateIndex:2110; appliedIndex:2108; }","duration":"746.813771ms","start":"2026-04-16T10:15:19.111451Z","end":"2026-04-16T10:15:19.858264Z","steps":["trace[451490539] 'read index received' (duration: 622.1566ms)","trace[451490539] 'applied index is now lower than readState.Index' (duration: 124.655871ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T10:15:19.858342Z","caller":"traceutil/trace.go:171","msg":"trace[277201341] transaction","detail":"{read_only:false; response_revision:2014; number_of_response:1; }","duration":"431.443864ms","start":"2026-04-16T10:15:19.426876Z","end":"2026-04-16T10:15:19.85832Z","steps":["trace[277201341] 'process raft request' (duration: 431.245319ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:15:19.858428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.969395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T10:15:19.858454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:15:19.426854Z","time spent":"431.534637ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T10:15:19.858473Z","caller":"traceutil/trace.go:171","msg":"trace[608917674] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2014; }","duration":"747.044516ms","start":"2026-04-16T10:15:19.111415Z","end":"2026-04-16T10:15:19.85846Z","steps":["trace[608917674] 'agreement among raft nodes before linearized reading' (duration: 746.948264ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:15:19.858516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:15:19.111314Z","time spent":"747.191911ms","remote":"127.0.0.1:47748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T10:15:19.859656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.997431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-16T10:15:19.859719Z","caller":"traceutil/trace.go:171","msg":"trace[468984272] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2015; }","duration":"494.105213ms","start":"2026-04-16T10:15:19.365599Z","end":"2026-04-16T10:15:19.859704Z","steps":["trace[468984272] 'agreement among raft nodes before linearized reading' (duration: 493.937869ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:15:19.859751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:15:19.365579Z","time spent":"494.163725ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-16T10:15:19.859651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.189992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T10:15:19.859811Z","caller":"traceutil/trace.go:171","msg":"trace[1242645331] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:2015; }","duration":"272.383117ms","start":"2026-04-16T10:15:19.587409Z","end":"2026-04-16T10:15:19.859792Z","steps":["trace[1242645331] 'agreement among raft nodes before linearized reading' (duration: 272.180612ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:15:41.554549Z","caller":"traceutil/trace.go:171","msg":"trace[1799399366] linearizableReadLoop","detail":"{readStateIndex:2185; appliedIndex:2184; }","duration":"343.156198ms","start":"2026-04-16T10:15:41.211375Z","end":"2026-04-16T10:15:41.554531Z","steps":["trace[1799399366] 'read index received' (duration: 342.975164ms)","trace[1799399366] 'applied index is now lower than readState.Index' (duration: 180.234µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T10:15:41.554603Z","caller":"traceutil/trace.go:171","msg":"trace[498326917] transaction","detail":"{read_only:false; response_revision:2085; number_of_response:1; }","duration":"344.314486ms","start":"2026-04-16T10:15:41.210263Z","end":"2026-04-16T10:15:41.554577Z","steps":["trace[498326917] 'process raft request' (duration: 344.129362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:15:41.554718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.329492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-16T10:15:41.55475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.23415ms","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-16T10:15:41.554774Z","caller":"traceutil/trace.go:171","msg":"trace[1993455187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2085; }","duration":"343.302431ms","start":"2026-04-16T10:15:41.211465Z","end":"2026-04-16T10:15:41.554767Z","steps":["trace[1993455187] 'agreement among raft nodes before linearized reading' (duration: 343.178588ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:15:41.554778Z","caller":"traceutil/trace.go:171","msg":"trace[1221804303] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2085; }","duration":"343.414615ms","start":"2026-04-16T10:15:41.211342Z","end":"2026-04-16T10:15:41.554757Z","steps":["trace[1221804303] 'agreement among raft nodes before linearized reading' (duration: 343.260281ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:15:41.554804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:15:41.211452Z","time spent":"343.346033ms","remote":"127.0.0.1:47994","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-16T10:15:41.554806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:15:41.211328Z","time spent":"343.471526ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T10:15:41.55495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:15:41.210244Z","time spent":"344.42596ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T10:15:42.03276Z","caller":"traceutil/trace.go:171","msg":"trace[904719567] transaction","detail":"{read_only:false; response_revision:2086; number_of_response:1; }","duration":"472.306332ms","start":"2026-04-16T10:15:41.560428Z","end":"2026-04-16T10:15:42.032734Z","steps":["trace[904719567] 'process raft request' (duration: 423.840226ms)","trace[904719567] 'compare' (duration: 48.321652ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T10:15:42.032897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:15:41.560407Z","time spent":"472.420855ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T10:15:42.032995Z","caller":"traceutil/trace.go:171","msg":"trace[1460499904] transaction","detail":"{read_only:false; response_revision:2087; number_of_response:1; }","duration":"472.555919ms","start":"2026-04-16T10:15:41.560417Z","end":"2026-04-16T10:15:42.032973Z","steps":["trace[1460499904] 'process raft request' (duration: 472.281082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:15:42.033117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:15:41.56039Z","time spent":"472.661471ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T10:15:43.062705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:15:42.31654Z","time spent":"746.155424ms","remote":"127.0.0.1:35994","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-16T10:15:43.06361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.047136ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T10:15:43.063818Z","caller":"traceutil/trace.go:171","msg":"trace[1310392122] linearizableReadLoop","detail":"{readStateIndex:2188; appliedIndex:2185; }","duration":"1.101924097s","start":"2026-04-16T10:15:41.961782Z","end":"2026-04-16T10:15:43.063706Z","steps":["trace[1310392122] 'read index received' (duration: 22.49785ms)","trace[1310392122] 'applied index is now lower than readState.Index' (duration: 1.079423926s)"],"step_count":2} {"level":"info","ts":"2026-04-16T10:15:43.064002Z","caller":"traceutil/trace.go:171","msg":"trace[1299926890] transaction","detail":"{read_only:false; response_revision:2088; number_of_response:1; }","duration":"1.131159864s","start":"2026-04-16T10:15:41.932814Z","end":"2026-04-16T10:15:43.063974Z","steps":["trace[1299926890] 'process raft request' (duration: 469.678387ms)","trace[1299926890] 'compare' (duration: 660.78643ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T10:15:43.064145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:15:41.932789Z","time spent":"1.131274237s","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T10:15:43.064368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.102585353s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-16T10:15:43.064416Z","caller":"traceutil/trace.go:171","msg":"trace[2087249002] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2088; }","duration":"1.102670105s","start":"2026-04-16T10:15:41.961734Z","end":"2026-04-16T10:15:43.064404Z","steps":["trace[2087249002] 'agreement among raft nodes before linearized reading' (duration: 1.10246182s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:15:43.064451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:15:41.961718Z","time spent":"1.102724837s","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-16T10:15:43.064589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.357614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T10:15:43.064652Z","caller":"traceutil/trace.go:171","msg":"trace[223939675] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:2088; }","duration":"579.464917ms","start":"2026-04-16T10:15:42.485171Z","end":"2026-04-16T10:15:43.064636Z","steps":["trace[223939675] 'agreement among raft nodes before linearized reading' (duration: 579.374314ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:15:43.064696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:15:42.485147Z","time spent":"579.535759ms","remote":"127.0.0.1:47880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-04-16T10:15:43.065145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.933657ms","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-16T10:15:43.065198Z","caller":"traceutil/trace.go:171","msg":"trace[139641872] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2088; }","duration":"848.357987ms","start":"2026-04-16T10:15:42.216827Z","end":"2026-04-16T10:15:43.065185Z","steps":["trace[139641872] 'agreement among raft nodes before linearized reading' (duration: 847.725181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:15:43.065347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:15:42.216797Z","time spent":"848.491881ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-16T10:15:43.885589Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367047799739776,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T10:15:44.345504Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.273701901s","expected-duration":"1s"} {"level":"info","ts":"2026-04-16T10:15:44.346108Z","caller":"traceutil/trace.go:171","msg":"trace[1979737143] transaction","detail":"{read_only:false; response_revision:2089; number_of_response:1; }","duration":"1.274377508s","start":"2026-04-16T10:15:43.071707Z","end":"2026-04-16T10:15:44.346085Z","steps":["trace[1979737143] 'process raft request' (duration: 1.27405363s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:15:44.346249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:15:43.07169Z","time spent":"1.27446946s","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T10:15:44.62212Z","caller":"traceutil/trace.go:171","msg":"trace[2077765373] transaction","detail":"{read_only:false; response_revision:2090; number_of_response:1; }","duration":"1.54991802s","start":"2026-04-16T10:15:43.072174Z","end":"2026-04-16T10:15:44.622092Z","steps":["trace[2077765373] 'process raft request' (duration: 1.532317552s)","trace[2077765373] 'compare' (duration: 17.465325ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T10:15:44.622284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:15:43.072165Z","time spent":"1.550035543s","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T10:15:44.681742Z","caller":"traceutil/trace.go:171","msg":"trace[434236078] linearizableReadLoop","detail":"{readStateIndex:2191; appliedIndex:2188; }","duration":"1.296737148s","start":"2026-04-16T10:15:43.384967Z","end":"2026-04-16T10:15:44.681704Z","steps":["trace[434236078] 'read index received' (duration: 960.786525ms)","trace[434236078] 'applied index is now lower than readState.Index' (duration: 335.946153ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T10:15:44.681938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.296944453s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T10:15:44.682037Z","caller":"traceutil/trace.go:171","msg":"trace[343324450] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:2090; }","duration":"1.297084827s","start":"2026-04-16T10:15:43.384927Z","end":"2026-04-16T10:15:44.682012Z","steps":["trace[343324450] 'agreement among raft nodes before linearized reading' (duration: 1.296912922s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:15:44.682084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.735971ms","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-16T10:15:44.682103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:15:43.384891Z","time spent":"1.297195329s","remote":"127.0.0.1:48060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":16,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-04-16T10:15:44.682131Z","caller":"traceutil/trace.go:171","msg":"trace[1601825637] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2090; }","duration":"645.843813ms","start":"2026-04-16T10:15:44.036276Z","end":"2026-04-16T10:15:44.68212Z","steps":["trace[1601825637] 'agreement among raft nodes before linearized reading' (duration: 645.647158ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:15:44.682177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:15:44.036242Z","time spent":"645.926285ms","remote":"127.0.0.1:47994","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-16T10:15:44.682183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.413794ms","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-16T10:15:44.682238Z","caller":"traceutil/trace.go:171","msg":"trace[1617925588] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:2090; }","duration":"797.497297ms","start":"2026-04-16T10:15:43.884721Z","end":"2026-04-16T10:15:44.682218Z","steps":["trace[1617925588] 'agreement among raft nodes before linearized reading' (duration: 797.432995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:15:44.682294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:15:43.884702Z","time spent":"797.581498ms","remote":"127.0.0.1:50996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-04-16T10:15:44.682109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.123106178s","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-16T10:15:44.682407Z","caller":"traceutil/trace.go:171","msg":"trace[1326966692] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2090; }","duration":"1.123457567s","start":"2026-04-16T10:15:43.558938Z","end":"2026-04-16T10:15:44.682396Z","steps":["trace[1326966692] 'agreement among raft nodes before linearized reading' (duration: 1.122966095s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:15:44.682442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:15:43.55892Z","time spent":"1.123511318s","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-16T10:15:44.682608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.71441ms","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-16T10:15:44.682689Z","caller":"traceutil/trace.go:171","msg":"trace[1020906037] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2090; }","duration":"645.857444ms","start":"2026-04-16T10:15:44.036814Z","end":"2026-04-16T10:15:44.682671Z","steps":["trace[1020906037] 'agreement among raft nodes before linearized reading' (duration: 645.498675ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:15:44.682751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:15:44.036781Z","time spent":"645.961056ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-16T10:15:46.677916Z","caller":"traceutil/trace.go:171","msg":"trace[56368063] linearizableReadLoop","detail":"{readStateIndex:2196; appliedIndex:2195; }","duration":"345.139168ms","start":"2026-04-16T10:15:46.33276Z","end":"2026-04-16T10:15:46.677899Z","steps":["trace[56368063] 'read index received' (duration: 344.968274ms)","trace[56368063] 'applied index is now lower than readState.Index' (duration: 170.174µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T10:15:46.678187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.152706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-16T10:15:46.678219Z","caller":"traceutil/trace.go:171","msg":"trace[1180195405] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2095; }","duration":"328.306239ms","start":"2026-04-16T10:15:46.349904Z","end":"2026-04-16T10:15:46.67821Z","steps":["trace[1180195405] 'agreement among raft nodes before linearized reading' (duration: 328.201156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:15:46.67824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:15:46.349882Z","time spent":"328.352751ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-16T10:15:46.678196Z","caller":"traceutil/trace.go:171","msg":"trace[319118367] transaction","detail":"{read_only:false; response_revision:2095; number_of_response:1; }","duration":"413.010431ms","start":"2026-04-16T10:15:46.265124Z","end":"2026-04-16T10:15:46.678134Z","steps":["trace[319118367] 'process raft request' (duration: 412.660043ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:15:46.678366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.59733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T10:15:46.678383Z","caller":"traceutil/trace.go:171","msg":"trace[231795006] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:2095; }","duration":"271.652741ms","start":"2026-04-16T10:15:46.406725Z","end":"2026-04-16T10:15:46.678378Z","steps":["trace[231795006] 'agreement among raft nodes before linearized reading' (duration: 271.62099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:15:46.678414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:15:46.265098Z","time spent":"413.194015ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T10:15:46.678629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.856907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T10:15:46.678829Z","caller":"traceutil/trace.go:171","msg":"trace[1462881114] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:2095; }","duration":"346.105623ms","start":"2026-04-16T10:15:46.332704Z","end":"2026-04-16T10:15:46.67881Z","steps":["trace[1462881114] 'agreement among raft nodes before linearized reading' (duration: 345.341904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:15:46.678947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:15:46.332683Z","time spent":"346.183454ms","remote":"127.0.0.1:48206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"info","ts":"2026-04-16T10:15:46.803008Z","caller":"traceutil/trace.go:171","msg":"trace[1274602869] transaction","detail":"{read_only:false; response_revision:2096; number_of_response:1; }","duration":"120.647137ms","start":"2026-04-16T10:15:46.682335Z","end":"2026-04-16T10:15:46.802982Z","steps":["trace[1274602869] 'process raft request' (duration: 42.326291ms)","trace[1274602869] 'compare' (duration: 78.09625ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T10:15:46.803164Z","caller":"traceutil/trace.go:171","msg":"trace[1197461240] linearizableReadLoop","detail":"{readStateIndex:2198; appliedIndex:2196; }","duration":"108.4913ms","start":"2026-04-16T10:15:46.694643Z","end":"2026-04-16T10:15:46.803134Z","steps":["trace[1197461240] 'read index received' (duration: 30.022781ms)","trace[1197461240] 'applied index is now lower than readState.Index' (duration: 78.467179ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T10:15:46.803376Z","caller":"traceutil/trace.go:171","msg":"trace[1072044950] transaction","detail":"{read_only:false; response_revision:2097; number_of_response:1; }","duration":"120.681777ms","start":"2026-04-16T10:15:46.682682Z","end":"2026-04-16T10:15:46.803364Z","steps":["trace[1072044950] 'process raft request' (duration: 120.244166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:15:46.803411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.756536ms","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-16T10:15:46.803447Z","caller":"traceutil/trace.go:171","msg":"trace[1144166978] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2097; }","duration":"108.848199ms","start":"2026-04-16T10:15:46.694589Z","end":"2026-04-16T10:15:46.803437Z","steps":["trace[1144166978] 'agreement among raft nodes before linearized reading' (duration: 108.693655ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:15:48.679976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:15:48.301214Z","time spent":"378.757828ms","remote":"127.0.0.1:47772","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-16T10:15:48.957257Z","caller":"traceutil/trace.go:171","msg":"trace[2089818240] linearizableReadLoop","detail":"{readStateIndex:2204; appliedIndex:2203; }","duration":"149.443377ms","start":"2026-04-16T10:15:48.80778Z","end":"2026-04-16T10:15:48.957224Z","steps":["trace[2089818240] 'read index received' (duration: 77.347692ms)","trace[2089818240] 'applied index is now lower than readState.Index' (duration: 72.093925ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T10:15:48.957536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.737945ms","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-16T10:15:48.957595Z","caller":"traceutil/trace.go:171","msg":"trace[428184809] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2102; }","duration":"149.847418ms","start":"2026-04-16T10:15:48.807735Z","end":"2026-04-16T10:15:48.957583Z","steps":["trace[428184809] 'agreement among raft nodes before linearized reading' (duration: 149.602122ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:15:48.957291Z","caller":"traceutil/trace.go:171","msg":"trace[1695504184] transaction","detail":"{read_only:false; response_revision:2102; number_of_response:1; }","duration":"276.142681ms","start":"2026-04-16T10:15:48.681095Z","end":"2026-04-16T10:15:48.957238Z","steps":["trace[1695504184] 'process raft request' (duration: 204.053217ms)","trace[1695504184] 'compare' (duration: 71.595062ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T10:15:48.958123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.177745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-16T10:15:48.958232Z","caller":"traceutil/trace.go:171","msg":"trace[154214578] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2102; }","duration":"150.341609ms","start":"2026-04-16T10:15:48.807864Z","end":"2026-04-16T10:15:48.958205Z","steps":["trace[154214578] 'agreement among raft nodes before linearized reading' (duration: 149.513519ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:15:49.391289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.202975ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T10:15:49.391444Z","caller":"traceutil/trace.go:171","msg":"trace[863701731] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2103; }","duration":"136.364658ms","start":"2026-04-16T10:15:49.255056Z","end":"2026-04-16T10:15:49.39142Z","steps":["trace[863701731] 'range keys from in-memory index tree' (duration: 136.186314ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:15:49.407263Z","caller":"traceutil/trace.go:171","msg":"trace[131943192] linearizableReadLoop","detail":"{readStateIndex:2207; appliedIndex:2204; }","duration":"444.027536ms","start":"2026-04-16T10:15:48.963222Z","end":"2026-04-16T10:15:49.407249Z","steps":["trace[131943192] 'read index received' (duration: 45.644671ms)","trace[131943192] 'applied index is now lower than readState.Index' (duration: 398.381875ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T10:15:49.407266Z","caller":"traceutil/trace.go:171","msg":"trace[1494905168] transaction","detail":"{read_only:false; response_revision:2104; number_of_response:1; }","duration":"444.394406ms","start":"2026-04-16T10:15:48.962834Z","end":"2026-04-16T10:15:49.407229Z","steps":["trace[1494905168] 'process raft request' (duration: 444.218132ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:15:49.407306Z","caller":"traceutil/trace.go:171","msg":"trace[363359230] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"443.273668ms","start":"2026-04-16T10:15:48.964009Z","end":"2026-04-16T10:15:49.407282Z","steps":["trace[363359230] 'process raft request' (duration: 443.183496ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:15:49.407342Z","caller":"traceutil/trace.go:171","msg":"trace[1380203914] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"442.930469ms","start":"2026-04-16T10:15:48.964402Z","end":"2026-04-16T10:15:49.407333Z","steps":["trace[1380203914] 'process raft request' (duration: 442.819287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:15:49.407422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:15:48.964392Z","time spent":"442.971391ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T10:15:49.407454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:15:48.96282Z","time spent":"444.529039ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T10:15:49.407539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.316403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-16T10:15:49.407562Z","caller":"traceutil/trace.go:171","msg":"trace[119754182] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:2107; }","duration":"444.358435ms","start":"2026-04-16T10:15:48.963196Z","end":"2026-04-16T10:15:49.407555Z","steps":["trace[119754182] 'agreement among raft nodes before linearized reading' (duration: 444.312734ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:15:49.407547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:15:48.963999Z","time spent":"443.3515ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T10:15:49.407583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:15:48.963186Z","time spent":"444.391945ms","remote":"127.0.0.1:47998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-04-16T10:15:49.40742Z","caller":"traceutil/trace.go:171","msg":"trace[632088267] transaction","detail":"{read_only:false; response_revision:2105; number_of_response:1; }","duration":"444.547609ms","start":"2026-04-16T10:15:48.962835Z","end":"2026-04-16T10:15:49.407383Z","steps":["trace[632088267] 'process raft request' (duration: 444.323033ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:15:49.408571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:15:48.962813Z","time spent":"445.584054ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T10:15:49.412268Z","caller":"traceutil/trace.go:171","msg":"trace[1424100326] transaction","detail":"{read_only:false; response_revision:2108; number_of_response:1; }","duration":"294.469157ms","start":"2026-04-16T10:15:49.117779Z","end":"2026-04-16T10:15:49.412248Z","steps":["trace[1424100326] 'process raft request' (duration: 294.381915ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:15:49.412284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.355897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T10:15:49.412467Z","caller":"traceutil/trace.go:171","msg":"trace[2120482284] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:2108; }","duration":"380.580112ms","start":"2026-04-16T10:15:49.031875Z","end":"2026-04-16T10:15:49.412455Z","steps":["trace[2120482284] 'agreement among raft nodes before linearized reading' (duration: 380.352657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:15:49.412491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:15:49.031849Z","time spent":"380.635404ms","remote":"127.0.0.1:48222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-04-16T10:16:01.698193Z","caller":"traceutil/trace.go:171","msg":"trace[757743106] transaction","detail":"{read_only:false; response_revision:2194; number_of_response:1; }","duration":"521.981914ms","start":"2026-04-16T10:16:01.176189Z","end":"2026-04-16T10:16:01.698171Z","steps":["trace[757743106] 'process raft request' (duration: 508.158077ms)","trace[757743106] 'compare' (duration: 13.468408ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T10:16:01.698341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:16:01.176164Z","time spent":"522.095677ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T10:16:01.699055Z","caller":"traceutil/trace.go:171","msg":"trace[77764258] linearizableReadLoop","detail":"{readStateIndex:2301; appliedIndex:2299; }","duration":"224.998016ms","start":"2026-04-16T10:16:01.473998Z","end":"2026-04-16T10:16:01.698996Z","steps":["trace[77764258] 'read index received' (duration: 210.38685ms)","trace[77764258] 'applied index is now lower than readState.Index' (duration: 14.609656ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T10:16:01.699264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.260022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-16T10:16:01.699311Z","caller":"traceutil/trace.go:171","msg":"trace[399956381] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2195; }","duration":"225.343204ms","start":"2026-04-16T10:16:01.473957Z","end":"2026-04-16T10:16:01.6993Z","steps":["trace[399956381] 'agreement among raft nodes before linearized reading' (duration: 225.18456ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:01.699389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.932591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-16T10:16:01.699409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.347914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-16T10:16:01.699498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.398696ms","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-16T10:16:01.6995Z","caller":"traceutil/trace.go:171","msg":"trace[1683378359] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2195; }","duration":"225.479718ms","start":"2026-04-16T10:16:01.474006Z","end":"2026-04-16T10:16:01.699486Z","steps":["trace[1683378359] 'agreement among raft nodes before linearized reading' (duration: 225.284983ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:16:01.699543Z","caller":"traceutil/trace.go:171","msg":"trace[295057695] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2195; }","duration":"225.473427ms","start":"2026-04-16T10:16:01.47406Z","end":"2026-04-16T10:16:01.699533Z","steps":["trace[295057695] 'agreement among raft nodes before linearized reading' (duration: 225.372814ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:16:01.699108Z","caller":"traceutil/trace.go:171","msg":"trace[1347868078] transaction","detail":"{read_only:false; response_revision:2195; number_of_response:1; }","duration":"240.278358ms","start":"2026-04-16T10:16:01.458811Z","end":"2026-04-16T10:16:01.699089Z","steps":["trace[1347868078] 'process raft request' (duration: 239.996191ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:16:01.69946Z","caller":"traceutil/trace.go:171","msg":"trace[1951659896] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2195; }","duration":"208.084593ms","start":"2026-04-16T10:16:01.491358Z","end":"2026-04-16T10:16:01.699443Z","steps":["trace[1951659896] 'agreement among raft nodes before linearized reading' (duration: 207.892649ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:16:03.815838Z","caller":"traceutil/trace.go:171","msg":"trace[1145234525] transaction","detail":"{read_only:false; response_revision:2200; number_of_response:1; }","duration":"692.565896ms","start":"2026-04-16T10:16:03.123247Z","end":"2026-04-16T10:16:03.815813Z","steps":["trace[1145234525] 'process raft request' (duration: 692.400562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:03.816026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:16:03.123217Z","time spent":"692.729099ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T10:16:04.034252Z","caller":"traceutil/trace.go:171","msg":"trace[1728008079] linearizableReadLoop","detail":"{readStateIndex:2307; appliedIndex:2305; }","duration":"315.389216ms","start":"2026-04-16T10:16:03.718837Z","end":"2026-04-16T10:16:04.034227Z","steps":["trace[1728008079] 'read index received' (duration: 96.829336ms)","trace[1728008079] 'applied index is now lower than readState.Index' (duration: 218.55879ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T10:16:04.034378Z","caller":"traceutil/trace.go:171","msg":"trace[665284273] transaction","detail":"{read_only:false; response_revision:2201; number_of_response:1; }","duration":"329.341476ms","start":"2026-04-16T10:16:03.705025Z","end":"2026-04-16T10:16:04.034367Z","steps":["trace[665284273] 'process raft request' (duration: 286.469423ms)","trace[665284273] 'compare' (duration: 42.241428ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T10:16:04.034483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.148831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-16T10:16:04.034539Z","caller":"traceutil/trace.go:171","msg":"trace[480407612] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2201; }","duration":"313.237644ms","start":"2026-04-16T10:16:03.721289Z","end":"2026-04-16T10:16:04.034527Z","steps":["trace[480407612] 'agreement among raft nodes before linearized reading' (duration: 313.111311ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:04.034567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:16:03.721273Z","time spent":"313.287855ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-16T10:16:04.034728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.330842ms","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-16T10:16:04.034753Z","caller":"traceutil/trace.go:171","msg":"trace[2015370598] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2201; }","duration":"312.364072ms","start":"2026-04-16T10:16:03.722383Z","end":"2026-04-16T10:16:04.034747Z","steps":["trace[2015370598] 'agreement among raft nodes before linearized reading' (duration: 312.294781ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:04.034772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:16:03.722378Z","time spent":"312.388403ms","remote":"127.0.0.1:47994","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-16T10:16:04.034824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.992061ms","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-16T10:16:04.03487Z","caller":"traceutil/trace.go:171","msg":"trace[389280788] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2201; }","duration":"316.071042ms","start":"2026-04-16T10:16:03.718786Z","end":"2026-04-16T10:16:04.034857Z","steps":["trace[389280788] 'agreement among raft nodes before linearized reading' (duration: 315.944899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:04.034886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.550407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-16T10:16:04.034933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:16:03.718764Z","time spent":"316.132054ms","remote":"127.0.0.1:47994","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-16T10:16:04.034933Z","caller":"traceutil/trace.go:171","msg":"trace[2013001818] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2201; }","duration":"312.621729ms","start":"2026-04-16T10:16:03.722304Z","end":"2026-04-16T10:16:04.034925Z","steps":["trace[2013001818] 'agreement among raft nodes before linearized reading' (duration: 312.544847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:04.035064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:16:03.722299Z","time spent":"312.757642ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-16T10:16:04.034489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:16:03.704998Z","time spent":"329.406917ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T10:16:16.281832Z","caller":"traceutil/trace.go:171","msg":"trace[1919740866] linearizableReadLoop","detail":"{readStateIndex:2349; appliedIndex:2348; }","duration":"185.205498ms","start":"2026-04-16T10:16:16.096588Z","end":"2026-04-16T10:16:16.281793Z","steps":["trace[1919740866] 'read index received' (duration: 185.033524ms)","trace[1919740866] 'applied index is now lower than readState.Index' (duration: 171.314µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T10:16:16.282127Z","caller":"traceutil/trace.go:171","msg":"trace[630023863] transaction","detail":"{read_only:false; response_revision:2240; number_of_response:1; }","duration":"214.142172ms","start":"2026-04-16T10:16:16.067974Z","end":"2026-04-16T10:16:16.282116Z","steps":["trace[630023863] 'process raft request' (duration: 213.700811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:16.282399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.796732ms","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-16T10:16:16.282437Z","caller":"traceutil/trace.go:171","msg":"trace[1841155960] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2240; }","duration":"185.845123ms","start":"2026-04-16T10:16:16.096583Z","end":"2026-04-16T10:16:16.282428Z","steps":["trace[1841155960] 'agreement among raft nodes before linearized reading' (duration: 185.730461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:16.282577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.758459ms","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-16T10:16:16.2826Z","caller":"traceutil/trace.go:171","msg":"trace[141243023] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2240; }","duration":"173.81053ms","start":"2026-04-16T10:16:16.108783Z","end":"2026-04-16T10:16:16.282593Z","steps":["trace[141243023] 'agreement among raft nodes before linearized reading' (duration: 173.747999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:16.282795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.958616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-16T10:16:16.282817Z","caller":"traceutil/trace.go:171","msg":"trace[394016369] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2240; }","duration":"170.006638ms","start":"2026-04-16T10:16:16.112804Z","end":"2026-04-16T10:16:16.282811Z","steps":["trace[394016369] 'agreement among raft nodes before linearized reading' (duration: 169.932786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:16.283026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.132558ms","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-16T10:16:16.283093Z","caller":"traceutil/trace.go:171","msg":"trace[1592660074] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2240; }","duration":"174.259411ms","start":"2026-04-16T10:16:16.108817Z","end":"2026-04-16T10:16:16.283076Z","steps":["trace[1592660074] 'agreement among raft nodes before linearized reading' (duration: 174.047006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:16.451504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.789ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T10:16:16.451601Z","caller":"traceutil/trace.go:171","msg":"trace[671044466] transaction","detail":"{read_only:false; response_revision:2241; number_of_response:1; }","duration":"164.845762ms","start":"2026-04-16T10:16:16.286738Z","end":"2026-04-16T10:16:16.451583Z","steps":["trace[671044466] 'process raft request' (duration: 36.906609ms)","trace[671044466] 'compare' (duration: 127.697457ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T10:16:16.515521Z","caller":"traceutil/trace.go:171","msg":"trace[467956286] transaction","detail":"{read_only:false; response_revision:2244; number_of_response:1; }","duration":"224.138905ms","start":"2026-04-16T10:16:16.291355Z","end":"2026-04-16T10:16:16.515494Z","steps":["trace[467956286] 'process raft request' (duration: 224.096534ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:16:16.515734Z","caller":"traceutil/trace.go:171","msg":"trace[1385237167] transaction","detail":"{read_only:false; response_revision:2243; number_of_response:1; }","duration":"228.267766ms","start":"2026-04-16T10:16:16.287437Z","end":"2026-04-16T10:16:16.515705Z","steps":["trace[1385237167] 'process raft request' (duration: 227.981519ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:16:16.515637Z","caller":"traceutil/trace.go:171","msg":"trace[1052733450] transaction","detail":"{read_only:false; response_revision:2242; number_of_response:1; }","duration":"228.805659ms","start":"2026-04-16T10:16:16.286775Z","end":"2026-04-16T10:16:16.515581Z","steps":["trace[1052733450] 'process raft request' (duration: 228.505362ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:16:17.990798Z","caller":"traceutil/trace.go:171","msg":"trace[377235577] transaction","detail":"{read_only:false; response_revision:2246; number_of_response:1; }","duration":"100.526087ms","start":"2026-04-16T10:16:17.890249Z","end":"2026-04-16T10:16:17.990776Z","steps":["trace[377235577] 'process raft request' (duration: 100.363783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:22.188755Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367047799740208,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-16T10:16:22.252396Z","caller":"traceutil/trace.go:171","msg":"trace[1599475890] transaction","detail":"{read_only:false; response_revision:2283; number_of_response:1; }","duration":"568.572647ms","start":"2026-04-16T10:16:21.683795Z","end":"2026-04-16T10:16:22.252367Z","steps":["trace[1599475890] 'process raft request' (duration: 568.401043ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:22.252581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:16:21.683753Z","time spent":"568.729171ms","remote":"127.0.0.1:47914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1475,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T10:16:23.262542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.791071ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T10:16:23.262745Z","caller":"traceutil/trace.go:171","msg":"trace[213035619] transaction","detail":"{read_only:false; response_revision:2284; number_of_response:1; }","duration":"1.206915774s","start":"2026-04-16T10:16:22.055805Z","end":"2026-04-16T10:16:23.262721Z","steps":["trace[213035619] 'process raft request' (duration: 527.854486ms)","trace[213035619] 'compare' (duration: 678.565485ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T10:16:23.262948Z","caller":"traceutil/trace.go:171","msg":"trace[1354887879] linearizableReadLoop","detail":"{readStateIndex:2394; appliedIndex:2392; }","duration":"1.574793147s","start":"2026-04-16T10:16:21.688099Z","end":"2026-04-16T10:16:23.262892Z","steps":["trace[1354887879] 'read index received' (duration: 564.104668ms)","trace[1354887879] 'applied index is now lower than readState.Index' (duration: 1.010687479s)"],"step_count":2} {"level":"info","ts":"2026-04-16T10:16:23.263053Z","caller":"traceutil/trace.go:171","msg":"trace[1333061684] transaction","detail":"{read_only:false; response_revision:2285; number_of_response:1; }","duration":"1.004825175s","start":"2026-04-16T10:16:22.258215Z","end":"2026-04-16T10:16:23.26304Z","steps":["trace[1333061684] 'process raft request' (duration: 1.004454326s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:23.263127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:16:22.258194Z","time spent":"1.004893448s","remote":"127.0.0.1:47820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":767,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T10:16:23.26334Z","caller":"traceutil/trace.go:171","msg":"trace[1965304584] transaction","detail":"{read_only:false; response_revision:2287; number_of_response:1; }","duration":"905.631521ms","start":"2026-04-16T10:16:22.357658Z","end":"2026-04-16T10:16:23.263289Z","steps":["trace[1965304584] 'process raft request' (duration: 905.17019ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:23.263486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:16:22.357636Z","time spent":"905.772414ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T10:16:23.263502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.575430822s","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-16T10:16:23.263542Z","caller":"traceutil/trace.go:171","msg":"trace[1005847529] transaction","detail":"{read_only:false; response_revision:2286; number_of_response:1; }","duration":"1.004331603s","start":"2026-04-16T10:16:22.259191Z","end":"2026-04-16T10:16:23.263523Z","steps":["trace[1005847529] 'process raft request' (duration: 1.003575555s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:23.263627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.435471965s","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":"warn","ts":"2026-04-16T10:16:23.263637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:16:22.259177Z","time spent":"1.004411966s","remote":"127.0.0.1:47914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1933,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T10:16:23.263556Z","caller":"traceutil/trace.go:171","msg":"trace[691047660] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:2287; }","duration":"1.575527025s","start":"2026-04-16T10:16:21.688014Z","end":"2026-04-16T10:16:23.263541Z","steps":["trace[691047660] 'agreement among raft nodes before linearized reading' (duration: 1.575405002s)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:16:23.26367Z","caller":"traceutil/trace.go:171","msg":"trace[577049562] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:2287; }","duration":"1.435551257s","start":"2026-04-16T10:16:21.828108Z","end":"2026-04-16T10:16:23.263659Z","steps":["trace[577049562] 'agreement among raft nodes before linearized reading' (duration: 1.435487076s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:23.263715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:16:21.828087Z","time spent":"1.435614319s","remote":"127.0.0.1:50984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-16T10:16:23.263742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:16:21.687965Z","time spent":"1.575736519s","remote":"127.0.0.1:50812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-16T10:16:23.263794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.177881ms","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-16T10:16:23.263847Z","caller":"traceutil/trace.go:171","msg":"trace[1623755618] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2287; }","duration":"707.265644ms","start":"2026-04-16T10:16:22.556567Z","end":"2026-04-16T10:16:23.263833Z","steps":["trace[1623755618] 'agreement among raft nodes before linearized reading' (duration: 707.10429ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:23.263894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:16:22.556548Z","time spent":"707.335225ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-16T10:16:23.26391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.00586269s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1948"} {"level":"warn","ts":"2026-04-16T10:16:23.263942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.089071996s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T10:16:23.26399Z","caller":"traceutil/trace.go:171","msg":"trace[1139855402] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:2287; }","duration":"1.089180028s","start":"2026-04-16T10:16:22.174798Z","end":"2026-04-16T10:16:23.263978Z","steps":["trace[1139855402] 'agreement among raft nodes before linearized reading' (duration: 1.089101296s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:23.264001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"977.583153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T10:16:23.264033Z","caller":"traceutil/trace.go:171","msg":"trace[1173284057] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:2287; }","duration":"977.653854ms","start":"2026-04-16T10:16:22.286371Z","end":"2026-04-16T10:16:23.264025Z","steps":["trace[1173284057] 'agreement among raft nodes before linearized reading' (duration: 977.602623ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:23.264029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:16:22.174779Z","time spent":"1.08923868s","remote":"127.0.0.1:47842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-04-16T10:16:23.264028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.891935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-04-16T10:16:23.264062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:16:22.286351Z","time spent":"977.704295ms","remote":"127.0.0.1:48098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"info","ts":"2026-04-16T10:16:23.264075Z","caller":"traceutil/trace.go:171","msg":"trace[1485043421] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2287; }","duration":"932.993147ms","start":"2026-04-16T10:16:22.331071Z","end":"2026-04-16T10:16:23.264064Z","steps":["trace[1485043421] 'agreement among raft nodes before linearized reading' (duration: 932.842264ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:23.264102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.73638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T10:16:23.26412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:16:22.33105Z","time spent":"933.053178ms","remote":"127.0.0.1:48242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":135,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-04-16T10:16:23.264147Z","caller":"traceutil/trace.go:171","msg":"trace[902033760] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2287; }","duration":"231.819152ms","start":"2026-04-16T10:16:23.032312Z","end":"2026-04-16T10:16:23.264131Z","steps":["trace[902033760] 'agreement among raft nodes before linearized reading' (duration: 231.75235ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:23.264158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.688581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-16T10:16:23.264207Z","caller":"traceutil/trace.go:171","msg":"trace[1283599671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2287; }","duration":"651.769693ms","start":"2026-04-16T10:16:22.612422Z","end":"2026-04-16T10:16:23.264191Z","steps":["trace[1283599671] 'agreement among raft nodes before linearized reading' (duration: 651.64855ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:23.264247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:16:22.612403Z","time spent":"651.833374ms","remote":"127.0.0.1:47994","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-16T10:16:23.263955Z","caller":"traceutil/trace.go:171","msg":"trace[919055960] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:2287; }","duration":"1.005941232s","start":"2026-04-16T10:16:22.258002Z","end":"2026-04-16T10:16:23.263943Z","steps":["trace[919055960] 'agreement among raft nodes before linearized reading' (duration: 1.0058493s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:23.264293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.262900106s","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-16T10:16:23.264327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:16:22.257978Z","time spent":"1.006339512s","remote":"127.0.0.1:47914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":1971,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "} {"level":"info","ts":"2026-04-16T10:16:23.264338Z","caller":"traceutil/trace.go:171","msg":"trace[255050436] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2287; }","duration":"1.262968128s","start":"2026-04-16T10:16:22.001357Z","end":"2026-04-16T10:16:23.264325Z","steps":["trace[255050436] 'agreement among raft nodes before linearized reading' (duration: 1.262850495s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:23.264382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:16:22.001342Z","time spent":"1.263029799s","remote":"127.0.0.1:47994","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-16T10:16:23.264386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.220665ms","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-16T10:16:23.264428Z","caller":"traceutil/trace.go:171","msg":"trace[942895131] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2287; }","duration":"779.263906ms","start":"2026-04-16T10:16:22.485152Z","end":"2026-04-16T10:16:23.264416Z","steps":["trace[942895131] 'agreement among raft nodes before linearized reading' (duration: 779.110142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:23.264484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:16:22.485133Z","time spent":"779.341128ms","remote":"127.0.0.1:47994","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-16T10:16:23.264659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.203887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-16T10:16:23.264694Z","caller":"traceutil/trace.go:171","msg":"trace[1579314477] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2287; }","duration":"653.272799ms","start":"2026-04-16T10:16:22.611411Z","end":"2026-04-16T10:16:23.264683Z","steps":["trace[1579314477] 'agreement among raft nodes before linearized reading' (duration: 653.148946ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:23.264722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:16:22.611394Z","time spent":"653.320471ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-16T10:16:23.262854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:16:22.055772Z","time spent":"1.207023195s","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T10:16:23.671173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.912033ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T10:16:23.671526Z","caller":"traceutil/trace.go:171","msg":"trace[1385541587] transaction","detail":"{read_only:false; response_revision:2291; number_of_response:1; }","duration":"401.050771ms","start":"2026-04-16T10:16:23.270453Z","end":"2026-04-16T10:16:23.671504Z","steps":["trace[1385541587] 'process raft request' (duration: 400.971209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:23.671598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:16:23.270444Z","time spent":"401.126293ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T10:16:23.67166Z","caller":"traceutil/trace.go:171","msg":"trace[289702679] transaction","detail":"{read_only:false; response_revision:2288; number_of_response:1; }","duration":"402.149547ms","start":"2026-04-16T10:16:23.269461Z","end":"2026-04-16T10:16:23.67161Z","steps":["trace[289702679] 'process raft request' (duration: 114.65585ms)","trace[289702679] 'compare' (duration: 286.697558ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T10:16:23.671722Z","caller":"traceutil/trace.go:171","msg":"trace[1640462911] transaction","detail":"{read_only:false; response_revision:2290; number_of_response:1; }","duration":"401.356508ms","start":"2026-04-16T10:16:23.270336Z","end":"2026-04-16T10:16:23.671692Z","steps":["trace[1640462911] 'process raft request' (duration: 401.046391ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:16:23.671724Z","caller":"traceutil/trace.go:171","msg":"trace[134579401] transaction","detail":"{read_only:false; response_revision:2289; number_of_response:1; }","duration":"401.41651ms","start":"2026-04-16T10:16:23.270274Z","end":"2026-04-16T10:16:23.671691Z","steps":["trace[134579401] 'process raft request' (duration: 401.041421ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:16:23.671803Z","caller":"traceutil/trace.go:171","msg":"trace[1291062209] transaction","detail":"{read_only:false; response_revision:2292; number_of_response:1; }","duration":"400.894297ms","start":"2026-04-16T10:16:23.270846Z","end":"2026-04-16T10:16:23.67174Z","steps":["trace[1291062209] 'process raft request' (duration: 400.615901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:23.671832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:16:23.269443Z","time spent":"402.292741ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T10:16:23.671887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:16:23.270293Z","time spent":"401.504462ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T10:16:23.672006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:16:23.270824Z","time spent":"401.091132ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T10:16:23.672319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:16:23.270252Z","time spent":"401.607364ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T10:16:23.801278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.64333ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-16T10:16:24.832504Z","caller":"traceutil/trace.go:171","msg":"trace[1498428494] linearizableReadLoop","detail":"{readStateIndex:2405; appliedIndex:2404; }","duration":"130.452785ms","start":"2026-04-16T10:16:24.702016Z","end":"2026-04-16T10:16:24.832469Z","steps":["trace[1498428494] 'read index received' (duration: 130.227199ms)","trace[1498428494] 'applied index is now lower than readState.Index' (duration: 224.816µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T10:16:24.832565Z","caller":"traceutil/trace.go:171","msg":"trace[1807222550] transaction","detail":"{read_only:false; response_revision:2294; number_of_response:1; }","duration":"203.254296ms","start":"2026-04-16T10:16:24.629266Z","end":"2026-04-16T10:16:24.83252Z","steps":["trace[1807222550] 'process raft request' (duration: 202.980559ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:24.832782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.713091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T10:16:24.832927Z","caller":"traceutil/trace.go:171","msg":"trace[1999138778] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:2294; }","duration":"130.928556ms","start":"2026-04-16T10:16:24.701975Z","end":"2026-04-16T10:16:24.832904Z","steps":["trace[1999138778] 'agreement among raft nodes before linearized reading' (duration: 130.581258ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:25.279233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.18685ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-16T10:16:25.279385Z","caller":"traceutil/trace.go:171","msg":"trace[251714242] transaction","detail":"{read_only:false; response_revision:2295; number_of_response:1; }","duration":"442.048278ms","start":"2026-04-16T10:16:24.837306Z","end":"2026-04-16T10:16:25.279355Z","steps":["trace[251714242] 'process raft request' (duration: 313.645133ms)","trace[251714242] 'compare' (duration: 128.000566ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T10:16:25.279573Z","caller":"traceutil/trace.go:171","msg":"trace[262629686] transaction","detail":"{read_only:false; response_revision:2296; number_of_response:1; }","duration":"148.933155ms","start":"2026-04-16T10:16:25.130619Z","end":"2026-04-16T10:16:25.279552Z","steps":["trace[262629686] 'process raft request' (duration: 148.71224ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:16:25.279815Z","caller":"traceutil/trace.go:171","msg":"trace[252264472] linearizableReadLoop","detail":"{readStateIndex:2406; appliedIndex:2405; }","duration":"246.874978ms","start":"2026-04-16T10:16:25.032929Z","end":"2026-04-16T10:16:25.279804Z","steps":["trace[252264472] 'read index received' (duration: 118.109755ms)","trace[252264472] 'applied index is now lower than readState.Index' (duration: 128.764343ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T10:16:25.279909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.97079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T10:16:25.279942Z","caller":"traceutil/trace.go:171","msg":"trace[755155169] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2296; }","duration":"247.049012ms","start":"2026-04-16T10:16:25.032885Z","end":"2026-04-16T10:16:25.279934Z","steps":["trace[755155169] 'agreement among raft nodes before linearized reading' (duration: 246.96434ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:25.280249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:16:24.837273Z","time spent":"442.5054ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T10:16:26.194419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.923452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T10:16:26.194498Z","caller":"traceutil/trace.go:171","msg":"trace[1185185545] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2306; }","duration":"160.040044ms","start":"2026-04-16T10:16:26.034434Z","end":"2026-04-16T10:16:26.194474Z","steps":["trace[1185185545] 'range keys from in-memory index tree' (duration: 159.810859ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:26.194422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.540805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T10:16:26.194761Z","caller":"traceutil/trace.go:171","msg":"trace[1076187499] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:2306; }","duration":"330.899294ms","start":"2026-04-16T10:16:25.863784Z","end":"2026-04-16T10:16:26.194683Z","steps":["trace[1076187499] 'count revisions from in-memory index tree' (duration: 330.459902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:26.19495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:16:25.863764Z","time spent":"331.115228ms","remote":"127.0.0.1:48230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-16T10:16:34.219913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.551903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T10:16:34.220013Z","caller":"traceutil/trace.go:171","msg":"trace[311411810] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2386; }","duration":"189.724808ms","start":"2026-04-16T10:16:34.030264Z","end":"2026-04-16T10:16:34.219989Z","steps":["trace[311411810] 'range keys from in-memory index tree' (duration: 189.42648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:40.27488Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367047799740477,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T10:16:40.770378Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.001422342s","expected-duration":"1s"} {"level":"info","ts":"2026-04-16T10:16:40.770709Z","caller":"traceutil/trace.go:171","msg":"trace[1889258459] linearizableReadLoop","detail":"{readStateIndex:2530; appliedIndex:2529; }","duration":"996.447001ms","start":"2026-04-16T10:16:39.774245Z","end":"2026-04-16T10:16:40.770692Z","steps":["trace[1889258459] 'read index received' (duration: 996.244736ms)","trace[1889258459] 'applied index is now lower than readState.Index' (duration: 201.655µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T10:16:40.771243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.743295ms","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-16T10:16:40.771316Z","caller":"traceutil/trace.go:171","msg":"trace[144675930] transaction","detail":"{read_only:false; response_revision:2416; number_of_response:1; }","duration":"1.002486398s","start":"2026-04-16T10:16:39.768815Z","end":"2026-04-16T10:16:40.771301Z","steps":["trace[144675930] 'process raft request' (duration: 1.00174832s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:40.771379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.42937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-04-16T10:16:40.771421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:16:39.768789Z","time spent":"1.00258524s","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T10:16:40.771409Z","caller":"traceutil/trace.go:171","msg":"trace[2022245824] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2416; }","duration":"113.489822ms","start":"2026-04-16T10:16:40.65791Z","end":"2026-04-16T10:16:40.7714Z","steps":["trace[2022245824] 'agreement among raft nodes before linearized reading' (duration: 113.42084ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:16:40.77153Z","caller":"traceutil/trace.go:171","msg":"trace[377564217] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2416; }","duration":"382.92042ms","start":"2026-04-16T10:16:40.388428Z","end":"2026-04-16T10:16:40.771348Z","steps":["trace[377564217] 'agreement among raft nodes before linearized reading' (duration: 382.654223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:40.771621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"997.364743ms","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-16T10:16:40.771689Z","caller":"traceutil/trace.go:171","msg":"trace[988710595] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2416; }","duration":"997.450936ms","start":"2026-04-16T10:16:39.774222Z","end":"2026-04-16T10:16:40.771673Z","steps":["trace[988710595] 'agreement among raft nodes before linearized reading' (duration: 996.605405ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:40.77174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:16:39.774213Z","time spent":"997.514497ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-16T10:16:40.771567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.610855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-16T10:16:40.771796Z","caller":"traceutil/trace.go:171","msg":"trace[1431478585] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2416; }","duration":"249.865641ms","start":"2026-04-16T10:16:40.52192Z","end":"2026-04-16T10:16:40.771786Z","steps":["trace[1431478585] 'agreement among raft nodes before linearized reading' (duration: 249.591015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:40.771301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.065673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-16T10:16:40.771876Z","caller":"traceutil/trace.go:171","msg":"trace[1399694184] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2416; }","duration":"911.697208ms","start":"2026-04-16T10:16:39.860174Z","end":"2026-04-16T10:16:40.771871Z","steps":["trace[1399694184] 'agreement among raft nodes before linearized reading' (duration: 911.023952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:40.771904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:16:39.860143Z","time spent":"911.7517ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-16T10:16:40.771264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.80802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T10:16:40.771983Z","caller":"traceutil/trace.go:171","msg":"trace[1300347993] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2416; }","duration":"739.580339ms","start":"2026-04-16T10:16:40.032394Z","end":"2026-04-16T10:16:40.771974Z","steps":["trace[1300347993] 'agreement among raft nodes before linearized reading' (duration: 738.846711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:40.772015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:16:40.032364Z","time spent":"739.644251ms","remote":"127.0.0.1:47744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T10:16:40.771337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"992.893895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-16T10:16:40.771165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"993.957321ms","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-16T10:16:40.772229Z","caller":"traceutil/trace.go:171","msg":"trace[1285633890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2416; }","duration":"995.079058ms","start":"2026-04-16T10:16:39.77714Z","end":"2026-04-16T10:16:40.772219Z","steps":["trace[1285633890] 'agreement among raft nodes before linearized reading' (duration: 993.883899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:40.772254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:16:39.77712Z","time spent":"995.127469ms","remote":"127.0.0.1:47994","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-16T10:16:40.772239Z","caller":"traceutil/trace.go:171","msg":"trace[2142744829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2416; }","duration":"993.815547ms","start":"2026-04-16T10:16:39.778404Z","end":"2026-04-16T10:16:40.77222Z","steps":["trace[2142744829] 'agreement among raft nodes before linearized reading' (duration: 992.833913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:40.772303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:16:39.778388Z","time spent":"993.897719ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-16T10:16:40.772207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:16:40.388407Z","time spent":"383.78155ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-16T10:16:41.957412Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.148936663s","expected-duration":"1s"} {"level":"info","ts":"2026-04-16T10:16:42.008661Z","caller":"traceutil/trace.go:171","msg":"trace[2056451534] transaction","detail":"{read_only:false; response_revision:2417; number_of_response:1; }","duration":"1.232684301s","start":"2026-04-16T10:16:40.775944Z","end":"2026-04-16T10:16:42.008628Z","steps":["trace[2056451534] 'process raft request' (duration: 1.181697841s)","trace[2056451534] 'compare' (duration: 50.798326ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T10:16:42.008833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:16:40.775927Z","time spent":"1.232828215s","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T10:16:42.029207Z","caller":"traceutil/trace.go:171","msg":"trace[1501742761] transaction","detail":"{read_only:false; response_revision:2418; number_of_response:1; }","duration":"1.251798887s","start":"2026-04-16T10:16:40.777382Z","end":"2026-04-16T10:16:42.02918Z","steps":["trace[1501742761] 'process raft request' (duration: 1.251459249s)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:16:42.029676Z","caller":"traceutil/trace.go:171","msg":"trace[818787501] transaction","detail":"{read_only:false; response_revision:2419; number_of_response:1; }","duration":"1.252155915s","start":"2026-04-16T10:16:40.777491Z","end":"2026-04-16T10:16:42.029647Z","steps":["trace[818787501] 'process raft request' (duration: 1.251633972s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:42.029831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:16:40.777483Z","time spent":"1.252264738s","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T10:16:42.029692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:16:40.777342Z","time spent":"1.25193877s","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T10:16:42.030544Z","caller":"traceutil/trace.go:171","msg":"trace[1509939155] transaction","detail":"{read_only:false; response_revision:2420; number_of_response:1; }","duration":"1.253004896s","start":"2026-04-16T10:16:40.777525Z","end":"2026-04-16T10:16:42.03053Z","steps":["trace[1509939155] 'process raft request' (duration: 1.251867748s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:42.03061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:16:40.777505Z","time spent":"1.253069508s","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T10:16:42.030778Z","caller":"traceutil/trace.go:171","msg":"trace[1923283556] transaction","detail":"{read_only:false; response_revision:2421; number_of_response:1; }","duration":"1.25320658s","start":"2026-04-16T10:16:40.777554Z","end":"2026-04-16T10:16:42.030761Z","steps":["trace[1923283556] 'process raft request' (duration: 1.252879452s)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:16:42.030872Z","caller":"traceutil/trace.go:171","msg":"trace[1568694110] transaction","detail":"{read_only:false; response_revision:2422; number_of_response:1; }","duration":"1.253075417s","start":"2026-04-16T10:16:40.777777Z","end":"2026-04-16T10:16:42.030852Z","steps":["trace[1568694110] 'process raft request' (duration: 1.252893733s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:42.030874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:16:40.777547Z","time spent":"1.253281573s","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T10:16:42.030972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:16:40.777728Z","time spent":"1.25320363s","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T10:16:42.031139Z","caller":"traceutil/trace.go:171","msg":"trace[1479266404] transaction","detail":"{read_only:false; response_revision:2423; number_of_response:1; }","duration":"1.252862172s","start":"2026-04-16T10:16:40.778269Z","end":"2026-04-16T10:16:42.031131Z","steps":["trace[1479266404] 'process raft request' (duration: 1.252446892s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:42.031515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:16:40.778256Z","time spent":"1.252912003s","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T10:16:42.031674Z","caller":"traceutil/trace.go:171","msg":"trace[1807871429] transaction","detail":"{read_only:false; response_revision:2424; number_of_response:1; }","duration":"662.616487ms","start":"2026-04-16T10:16:41.369028Z","end":"2026-04-16T10:16:42.031644Z","steps":["trace[1807871429] 'process raft request' (duration: 662.070413ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:42.031807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:16:41.369008Z","time spent":"662.696789ms","remote":"127.0.0.1:47994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T10:16:42.03207Z","caller":"traceutil/trace.go:171","msg":"trace[1100950643] linearizableReadLoop","detail":"{readStateIndex:2539; appliedIndex:2530; }","duration":"179.465528ms","start":"2026-04-16T10:16:41.852566Z","end":"2026-04-16T10:16:42.032032Z","steps":["trace[1100950643] 'read index received' (duration: 105.003846ms)","trace[1100950643] 'applied index is now lower than readState.Index' (duration: 74.451922ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T10:16:42.032396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.827717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T10:16:42.032433Z","caller":"traceutil/trace.go:171","msg":"trace[1850613035] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:2425; }","duration":"179.899599ms","start":"2026-04-16T10:16:41.852523Z","end":"2026-04-16T10:16:42.032423Z","steps":["trace[1850613035] 'agreement among raft nodes before linearized reading' (duration: 179.591101ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T10:16:42.032722Z","caller":"traceutil/trace.go:171","msg":"trace[1625587511] transaction","detail":"{read_only:false; response_revision:2425; number_of_response:1; }","duration":"446.61986ms","start":"2026-04-16T10:16:41.586086Z","end":"2026-04-16T10:16:42.032706Z","steps":["trace[1625587511] 'process raft request' (duration: 445.481942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T10:16:42.032806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T10:16:41.58606Z","time spent":"446.701092ms","remote":"127.0.0.1:47900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7347,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T10:16:43.018065Z","caller":"traceutil/trace.go:171","msg":"trace[876820620] transaction","detail":"{read_only:false; response_revision:2427; number_of_response:1; }","duration":"165.079348ms","start":"2026-04-16T10:16:42.852924Z","end":"2026-04-16T10:16:43.018003Z","steps":["trace[876820620] 'process raft request' (duration: 112.839766ms)","trace[876820620] 'compare' (duration: 51.983136ms)"],"step_count":2}