{"level":"warn","ts":"2026-04-19T11:01:27.521276Z","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-19T11:01:27.521406Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.141: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.19.213.141:2380","--initial-cluster=instance=https://199.19.213.141:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.141:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.141: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-19T11:01:27.521494Z","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-19T11:01:27.521514Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.141:2380"]} {"level":"info","ts":"2026-04-19T11:01:27.521546Z","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-19T11:01:27.523517Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.141:2379"]} {"level":"info","ts":"2026-04-19T11:01:27.52363Z","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.19.213.141:2380"],"listen-peer-urls":["https://199.19.213.141:2380"],"advertise-client-urls":["https://199.19.213.141:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.141:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.141: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-19T11:01:27.530823Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.678312ms"} {"level":"info","ts":"2026-04-19T11:01:27.538141Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fe263d225de0d201","cluster-id":"24f15cf84c3a7bc3"} {"level":"info","ts":"2026-04-19T11:01:27.538227Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe263d225de0d201 switched to configuration voters=()"} {"level":"info","ts":"2026-04-19T11:01:27.538257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe263d225de0d201 became follower at term 0"} {"level":"info","ts":"2026-04-19T11:01:27.538272Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fe263d225de0d201 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-19T11:01:27.538286Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe263d225de0d201 became follower at term 1"} {"level":"info","ts":"2026-04-19T11:01:27.538329Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe263d225de0d201 switched to configuration voters=(18313392152561897985)"} {"level":"warn","ts":"2026-04-19T11:01:27.544587Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-19T11:01:27.545802Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-19T11:01:27.546913Z","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-19T11:01:27.548995Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fe263d225de0d201","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-19T11:01:27.549502Z","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-19T11:01:27.549579Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fe263d225de0d201","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-19T11:01:27.549622Z","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-19T11:01:27.549744Z","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-19T11:01:27.55128Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe263d225de0d201 switched to configuration voters=(18313392152561897985)"} {"level":"info","ts":"2026-04-19T11:01:27.55145Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"24f15cf84c3a7bc3","local-member-id":"fe263d225de0d201","added-peer-id":"fe263d225de0d201","added-peer-peer-urls":["https://199.19.213.141:2380"]} {"level":"info","ts":"2026-04-19T11:01:27.554568Z","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-19T11:01:27.554739Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.141:2380"} {"level":"info","ts":"2026-04-19T11:01:27.554831Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.141:2380"} {"level":"info","ts":"2026-04-19T11:01:27.554913Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fe263d225de0d201","initial-advertise-peer-urls":["https://199.19.213.141:2380"],"listen-peer-urls":["https://199.19.213.141:2380"],"advertise-client-urls":["https://199.19.213.141:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.141:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-19T11:01:27.554977Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-19T11:01:28.439241Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe263d225de0d201 is starting a new election at term 1"} {"level":"info","ts":"2026-04-19T11:01:28.43931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe263d225de0d201 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-19T11:01:28.439415Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe263d225de0d201 received MsgPreVoteResp from fe263d225de0d201 at term 1"} {"level":"info","ts":"2026-04-19T11:01:28.439436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe263d225de0d201 became candidate at term 2"} {"level":"info","ts":"2026-04-19T11:01:28.439451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe263d225de0d201 received MsgVoteResp from fe263d225de0d201 at term 2"} {"level":"info","ts":"2026-04-19T11:01:28.439465Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe263d225de0d201 became leader at term 2"} {"level":"info","ts":"2026-04-19T11:01:28.439481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fe263d225de0d201 elected leader fe263d225de0d201 at term 2"} {"level":"info","ts":"2026-04-19T11:01:28.440494Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fe263d225de0d201","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.141:2379]}","request-path":"/0/members/fe263d225de0d201/attributes","cluster-id":"24f15cf84c3a7bc3","publish-timeout":"7s"} {"level":"info","ts":"2026-04-19T11:01:28.440634Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-19T11:01:28.440713Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-19T11:01:28.440759Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T11:01:28.441097Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-19T11:01:28.441174Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-19T11:01:28.44167Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"24f15cf84c3a7bc3","local-member-id":"fe263d225de0d201","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T11:01:28.441745Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T11:01:28.441764Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T11:01:28.442852Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.141:2379"} {"level":"info","ts":"2026-04-19T11:01:28.445286Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-19T11:01:32.247367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"974.870956ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-19T11:01:32.247666Z","caller":"traceutil/trace.go:171","msg":"trace[947352950] transaction","detail":"{read_only:false; response_revision:175; number_of_response:1; }","duration":"1.201206454s","start":"2026-04-19T11:01:31.046383Z","end":"2026-04-19T11:01:32.247589Z","steps":["trace[947352950] 'process raft request' (duration: 225.113895ms)","trace[947352950] 'compare' (duration: 974.652697ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:01:32.247791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:01:31.046374Z","time spent":"1.201371972s","remote":"127.0.0.1:55492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":657,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-19T11:01:32.247982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.056913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:01:32.248076Z","caller":"traceutil/trace.go:171","msg":"trace[259700884] linearizableReadLoop","detail":"{readStateIndex:180; appliedIndex:179; }","duration":"694.646905ms","start":"2026-04-19T11:01:31.552878Z","end":"2026-04-19T11:01:32.247525Z","steps":["trace[259700884] 'read index received' (duration: 44.742µs)","trace[259700884] 'applied index is now lower than readState.Index' (duration: 694.600423ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:01:32.248079Z","caller":"traceutil/trace.go:171","msg":"trace[10566922] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:175; }","duration":"695.22731ms","start":"2026-04-19T11:01:31.552818Z","end":"2026-04-19T11:01:32.248046Z","steps":["trace[10566922] 'agreement among raft nodes before linearized reading' (duration: 694.938487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:01:32.248436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:01:31.552793Z","time spent":"695.548544ms","remote":"127.0.0.1:55274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"warn","ts":"2026-04-19T11:01:32.24845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.278294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:01:32.248645Z","caller":"traceutil/trace.go:171","msg":"trace[470678980] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:175; }","duration":"443.528735ms","start":"2026-04-19T11:01:31.805097Z","end":"2026-04-19T11:01:32.248626Z","steps":["trace[470678980] 'agreement among raft nodes before linearized reading' (duration: 443.082166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:01:32.248708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:01:31.805069Z","time spent":"443.625819ms","remote":"127.0.0.1:55108","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-19T11:01:32.751756Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15132549556665676705,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-19T11:01:33.252253Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15132549556665676705,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-19T11:01:33.753493Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15132549556665676705,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-19T11:01:34.25415Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15132549556665676705,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-19T11:01:34.298018Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.047075397s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-19T11:01:34.904792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999950092s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-19T11:01:34.904864Z","caller":"traceutil/trace.go:171","msg":"trace[1848126233] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000064587s","start":"2026-04-19T11:01:32.904779Z","end":"2026-04-19T11:01:34.904843Z","steps":["trace[1848126233] 'agreement among raft nodes before linearized reading' (duration: 1.999978194s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:01:34.904924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:01:32.904753Z","time spent":"2.000160901s","remote":"127.0.0.1:55108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-19T11:01:35.121335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.952932ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-19T11:01:35.121446Z","caller":"traceutil/trace.go:171","msg":"trace[1387704114] transaction","detail":"{read_only:false; response_revision:176; number_of_response:1; }","duration":"2.870561463s","start":"2026-04-19T11:01:32.250862Z","end":"2026-04-19T11:01:35.121424Z","steps":["trace[1387704114] 'process raft request' (duration: 2.047460744s)","trace[1387704114] 'compare' (duration: 822.211771ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:01:35.121507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:01:32.250849Z","time spent":"2.870636826s","remote":"127.0.0.1:55318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-19T11:01:35.508809Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.210557483s","expected-duration":"1s"} {"level":"info","ts":"2026-04-19T11:01:35.518886Z","caller":"traceutil/trace.go:171","msg":"trace[615306442] linearizableReadLoop","detail":"{readStateIndex:183; appliedIndex:180; }","duration":"3.267583668s","start":"2026-04-19T11:01:32.251281Z","end":"2026-04-19T11:01:35.518864Z","steps":["trace[615306442] 'read index received' (duration: 2.047037225s)","trace[615306442] 'applied index is now lower than readState.Index' (duration: 1.220545623s)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:01:35.518962Z","caller":"traceutil/trace.go:171","msg":"trace[344480995] transaction","detail":"{read_only:false; response_revision:177; number_of_response:1; }","duration":"3.211214011s","start":"2026-04-19T11:01:32.307705Z","end":"2026-04-19T11:01:35.518919Z","steps":["trace[344480995] 'process raft request' (duration: 3.201310035s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:01:35.519052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.267761416s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/cluster-admin\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:01:35.519101Z","caller":"traceutil/trace.go:171","msg":"trace[1362228852] range","detail":"{range_begin:/registry/clusterrolebindings/cluster-admin; range_end:; response_count:0; response_revision:178; }","duration":"3.267849809s","start":"2026-04-19T11:01:32.251238Z","end":"2026-04-19T11:01:35.519087Z","steps":["trace[1362228852] 'agreement among raft nodes before linearized reading' (duration: 3.267735554s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:01:35.519142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:01:32.251219Z","time spent":"3.267913822s","remote":"127.0.0.1:55500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/clusterrolebindings/cluster-admin\" "} {"level":"warn","ts":"2026-04-19T11:01:35.519188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:01:32.307675Z","time spent":"3.211460911s","remote":"127.0.0.1:55318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-19T11:01:35.519485Z","caller":"traceutil/trace.go:171","msg":"trace[817563796] transaction","detail":"{read_only:false; response_revision:178; number_of_response:1; }","duration":"2.100305008s","start":"2026-04-19T11:01:33.419165Z","end":"2026-04-19T11:01:35.51947Z","steps":["trace[817563796] 'process raft request' (duration: 2.099661981s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:01:35.519587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:01:33.419079Z","time spent":"2.100456505s","remote":"127.0.0.1:55318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-19T11:01:35.52024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.998126489s","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-19T11:01:35.520279Z","caller":"traceutil/trace.go:171","msg":"trace[822400284] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:179; }","duration":"2.998360298s","start":"2026-04-19T11:01:32.521909Z","end":"2026-04-19T11:01:35.520269Z","steps":["trace[822400284] 'agreement among raft nodes before linearized reading' (duration: 2.998249343s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:01:35.520306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:01:32.52187Z","time spent":"2.998427741s","remote":"127.0.0.1:55402","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-19T11:01:35.520746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.395515831s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:01:35.520822Z","caller":"traceutil/trace.go:171","msg":"trace[1370422524] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:179; }","duration":"1.395628035s","start":"2026-04-19T11:01:34.125175Z","end":"2026-04-19T11:01:35.520803Z","steps":["trace[1370422524] 'agreement among raft nodes before linearized reading' (duration: 1.395475419s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:01:35.52086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:01:34.125158Z","time spent":"1.395695338s","remote":"127.0.0.1:55108","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-19T11:01:35.520852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.087624075s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:01:35.520914Z","caller":"traceutil/trace.go:171","msg":"trace[237432796] transaction","detail":"{read_only:false; response_revision:179; number_of_response:1; }","duration":"1.112371534s","start":"2026-04-19T11:01:34.408533Z","end":"2026-04-19T11:01:35.520905Z","steps":["trace[237432796] 'process raft request' (duration: 1.111499577s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:01:35.520956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:01:34.408509Z","time spent":"1.112428006s","remote":"127.0.0.1:55318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-19T11:01:35.520953Z","caller":"traceutil/trace.go:171","msg":"trace[2110916030] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:179; }","duration":"2.08775499s","start":"2026-04-19T11:01:33.43317Z","end":"2026-04-19T11:01:35.520925Z","steps":["trace[2110916030] 'agreement among raft nodes before linearized reading' (duration: 2.087607974s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:01:35.521001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:01:33.433159Z","time spent":"2.087833203s","remote":"127.0.0.1:55402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-19T11:01:35.521001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.702981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:01:35.521059Z","caller":"traceutil/trace.go:171","msg":"trace[38140978] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:179; }","duration":"615.791305ms","start":"2026-04-19T11:01:34.905252Z","end":"2026-04-19T11:01:35.521044Z","steps":["trace[38140978] 'agreement among raft nodes before linearized reading' (duration: 615.678581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:01:35.52109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:01:34.90524Z","time spent":"615.843908ms","remote":"127.0.0.1:55102","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-19T11:01:48.708854Z","caller":"traceutil/trace.go:171","msg":"trace[1028417339] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"982.582354ms","start":"2026-04-19T11:01:47.726248Z","end":"2026-04-19T11:01:48.70883Z","steps":["trace[1028417339] 'process raft request' (duration: 982.355296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:01:48.709129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:01:47.72623Z","time spent":"982.695538ms","remote":"127.0.0.1:55318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6950,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T11:02:04.580593Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15132549556665677419,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-19T11:02:05.082124Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15132549556665677419,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-19T11:02:05.563428Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.864655596s","expected-duration":"1s"} {"level":"info","ts":"2026-04-19T11:02:05.563909Z","caller":"traceutil/trace.go:171","msg":"trace[930076698] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"1.879202815s","start":"2026-04-19T11:02:03.684682Z","end":"2026-04-19T11:02:05.563884Z","steps":["trace[930076698] 'process raft request' (duration: 1.87903903s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:05.56405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:03.684668Z","time spent":"1.879310029s","remote":"127.0.0.1:55402","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-19T11:02:05.573326Z","caller":"traceutil/trace.go:171","msg":"trace[1093915458] linearizableReadLoop","detail":"{readStateIndex:538; appliedIndex:536; }","duration":"1.493255596s","start":"2026-04-19T11:02:04.080049Z","end":"2026-04-19T11:02:05.573304Z","steps":["trace[1093915458] 'read index received' (duration: 1.483658465s)","trace[1093915458] 'applied index is now lower than readState.Index' (duration: 9.596401ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:02:05.573437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.49337062s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:02:05.573466Z","caller":"traceutil/trace.go:171","msg":"trace[203170460] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:519; }","duration":"1.493433122s","start":"2026-04-19T11:02:04.080023Z","end":"2026-04-19T11:02:05.573456Z","steps":["trace[203170460] 'agreement among raft nodes before linearized reading' (duration: 1.49337268s)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:02:05.573441Z","caller":"traceutil/trace.go:171","msg":"trace[1527414939] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"1.886900395s","start":"2026-04-19T11:02:03.686513Z","end":"2026-04-19T11:02:05.573414Z","steps":["trace[1527414939] 'process raft request' (duration: 1.886684328s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:05.573498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:04.080005Z","time spent":"1.493486413s","remote":"127.0.0.1:55108","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-19T11:02:05.573561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:03.686502Z","time spent":"1.886997757s","remote":"127.0.0.1:55402","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-19T11:02:05.573568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.952106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:02:05.573645Z","caller":"traceutil/trace.go:171","msg":"trace[1113673761] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:519; }","duration":"190.093661ms","start":"2026-04-19T11:02:05.383535Z","end":"2026-04-19T11:02:05.573629Z","steps":["trace[1113673761] 'agreement among raft nodes before linearized reading' (duration: 189.943256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:05.573625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.543742ms","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-19T11:02:05.573701Z","caller":"traceutil/trace.go:171","msg":"trace[1406733306] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:519; }","duration":"714.650245ms","start":"2026-04-19T11:02:04.85903Z","end":"2026-04-19T11:02:05.57368Z","steps":["trace[1406733306] 'agreement among raft nodes before linearized reading' (duration: 714.423618ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:05.573765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:04.859014Z","time spent":"714.738658ms","remote":"127.0.0.1:55402","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-19T11:02:05.573841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.745246ms","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-19T11:02:05.573927Z","caller":"traceutil/trace.go:171","msg":"trace[2116155881] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:519; }","duration":"680.87436ms","start":"2026-04-19T11:02:04.893025Z","end":"2026-04-19T11:02:05.5739Z","steps":["trace[2116155881] 'agreement among raft nodes before linearized reading' (duration: 680.530759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:05.573983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:04.893009Z","time spent":"680.966084ms","remote":"127.0.0.1:55402","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-19T11:02:07.58005Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15132549556665677468,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-19T11:02:07.823481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.573423639s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-19T11:02:07.823694Z","caller":"traceutil/trace.go:171","msg":"trace[1264193093] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"1.684356542s","start":"2026-04-19T11:02:06.139321Z","end":"2026-04-19T11:02:07.823677Z","steps":["trace[1264193093] 'process raft request' (duration: 1.68426937s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:07.823765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:06.139299Z","time spent":"1.684433655s","remote":"127.0.0.1:55176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59616,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T11:02:07.823834Z","caller":"traceutil/trace.go:171","msg":"trace[718139498] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"1.696160153s","start":"2026-04-19T11:02:06.127638Z","end":"2026-04-19T11:02:07.823798Z","steps":["trace[718139498] 'process raft request' (duration: 122.334561ms)","trace[718139498] 'compare' (duration: 1.573276975s)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:02:07.823985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:06.127615Z","time spent":"1.696290107s","remote":"127.0.0.1:55176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10846,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T11:02:07.836221Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.032151408s","expected-duration":"1s"} {"level":"info","ts":"2026-04-19T11:02:07.83667Z","caller":"traceutil/trace.go:171","msg":"trace[623517551] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:591; }","duration":"756.954601ms","start":"2026-04-19T11:02:07.079692Z","end":"2026-04-19T11:02:07.836647Z","steps":["trace[623517551] 'read index received' (duration: 756.823847ms)","trace[623517551] 'applied index is now lower than readState.Index' (duration: 129.874µs)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:02:07.836793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.111416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:02:07.836816Z","caller":"traceutil/trace.go:171","msg":"trace[1221247477] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"1.644268718s","start":"2026-04-19T11:02:06.192473Z","end":"2026-04-19T11:02:07.836742Z","steps":["trace[1221247477] 'process raft request' (duration: 1.644066432s)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:02:07.836911Z","caller":"traceutil/trace.go:171","msg":"trace[964316503] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:568; }","duration":"757.208959ms","start":"2026-04-19T11:02:07.079658Z","end":"2026-04-19T11:02:07.836867Z","steps":["trace[964316503] 'agreement among raft nodes before linearized reading' (duration: 757.073275ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:07.836958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:07.079598Z","time spent":"757.347814ms","remote":"127.0.0.1:55108","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-19T11:02:07.837094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:06.192446Z","time spent":"1.644449774s","remote":"127.0.0.1:55176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-19T11:02:07.846206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.468826ms","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-19T11:02:07.846753Z","caller":"traceutil/trace.go:171","msg":"trace[1193718905] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:569; }","duration":"260.071775ms","start":"2026-04-19T11:02:07.586667Z","end":"2026-04-19T11:02:07.846738Z","steps":["trace[1193718905] 'agreement among raft nodes before linearized reading' (duration: 259.452195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:07.846263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.244139ms","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-19T11:02:07.846899Z","caller":"traceutil/trace.go:171","msg":"trace[631906029] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:569; }","duration":"269.89342ms","start":"2026-04-19T11:02:07.576999Z","end":"2026-04-19T11:02:07.846892Z","steps":["trace[631906029] 'agreement among raft nodes before linearized reading' (duration: 269.131705ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:07.846283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.805301ms","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-19T11:02:07.847019Z","caller":"traceutil/trace.go:171","msg":"trace[2008211512] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:569; }","duration":"262.581026ms","start":"2026-04-19T11:02:07.584432Z","end":"2026-04-19T11:02:07.847013Z","steps":["trace[2008211512] 'agreement among raft nodes before linearized reading' (duration: 261.741679ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:07.846283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.765001ms","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-19T11:02:07.847141Z","caller":"traceutil/trace.go:171","msg":"trace[1667158073] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:569; }","duration":"278.6683ms","start":"2026-04-19T11:02:07.568466Z","end":"2026-04-19T11:02:07.847134Z","steps":["trace[1667158073] 'agreement among raft nodes before linearized reading' (duration: 277.705689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:13.012906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.413234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-d72xq\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-19T11:02:13.012985Z","caller":"traceutil/trace.go:171","msg":"trace[1568586537] range","detail":"{range_begin:/registry/pods/kube-system/cilium-d72xq; range_end:; response_count:1; response_revision:624; }","duration":"250.525618ms","start":"2026-04-19T11:02:12.762439Z","end":"2026-04-19T11:02:13.012964Z","steps":["trace[1568586537] 'range keys from in-memory index tree' (duration: 250.23684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:14.155194Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.288345492s","expected-duration":"1s"} {"level":"info","ts":"2026-04-19T11:02:14.156388Z","caller":"traceutil/trace.go:171","msg":"trace[2111092833] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"1.290808657s","start":"2026-04-19T11:02:12.865533Z","end":"2026-04-19T11:02:14.156342Z","steps":["trace[2111092833] 'process raft request' (duration: 1.290226899s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:14.156621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:12.865504Z","time spent":"1.290973672s","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036225,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T11:02:14.283351Z","caller":"traceutil/trace.go:171","msg":"trace[771796358] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:649; }","duration":"407.61857ms","start":"2026-04-19T11:02:13.875688Z","end":"2026-04-19T11:02:14.283307Z","steps":["trace[771796358] 'read index received' (duration: 279.968972ms)","trace[771796358] 'applied index is now lower than readState.Index' (duration: 127.648018ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:02:14.283684Z","caller":"traceutil/trace.go:171","msg":"trace[657949083] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"1.257976224s","start":"2026-04-19T11:02:13.025543Z","end":"2026-04-19T11:02:14.283519Z","steps":["trace[657949083] 'process raft request' (duration: 1.211642417s)","trace[657949083] 'compare' (duration: 45.891834ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:02:14.283912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.153656ms","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-19T11:02:14.284Z","caller":"traceutil/trace.go:171","msg":"trace[866119233] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:626; }","duration":"408.334681ms","start":"2026-04-19T11:02:13.875643Z","end":"2026-04-19T11:02:14.283977Z","steps":["trace[866119233] 'agreement among raft nodes before linearized reading' (duration: 407.855046ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:14.284205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:13.87562Z","time spent":"408.564948ms","remote":"127.0.0.1:55402","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-19T11:02:14.284264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:13.025517Z","time spent":"1.258244632s","remote":"127.0.0.1:55318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T11:02:15.950126Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.372511659s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-19T11:02:16.080199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000778628s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-19T11:02:16.080264Z","caller":"traceutil/trace.go:171","msg":"trace[1939716153] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00088789s","start":"2026-04-19T11:02:14.079362Z","end":"2026-04-19T11:02:16.08025Z","steps":["trace[1939716153] 'agreement among raft nodes before linearized reading' (duration: 2.000803798s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:16.0803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:14.079344Z","time spent":"2.000945023s","remote":"127.0.0.1:55108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/19 11:02:16 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-19T11:02:17.4195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.841807339s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-19T11:02:17.419758Z","caller":"traceutil/trace.go:171","msg":"trace[1017613099] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"3.130080076s","start":"2026-04-19T11:02:14.28966Z","end":"2026-04-19T11:02:17.41974Z","steps":["trace[1017613099] 'process raft request' (duration: 3.129988413s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:17.419844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:14.289637Z","time spent":"3.130159187s","remote":"127.0.0.1:55402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T11:02:17.419949Z","caller":"traceutil/trace.go:171","msg":"trace[810127040] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:651; }","duration":"3.136464299s","start":"2026-04-19T11:02:14.283454Z","end":"2026-04-19T11:02:17.419918Z","steps":["trace[810127040] 'read index received' (duration: 294.197765ms)","trace[810127040] 'applied index is now lower than readState.Index' (duration: 2.842261894s)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:02:17.420208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.537085574s","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-19T11:02:17.420212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.531969678s","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-19T11:02:17.420255Z","caller":"traceutil/trace.go:171","msg":"trace[1419832089] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:628; }","duration":"3.537145736s","start":"2026-04-19T11:02:13.883092Z","end":"2026-04-19T11:02:17.420238Z","steps":["trace[1419832089] 'agreement among raft nodes before linearized reading' (duration: 3.536978061s)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:02:17.420293Z","caller":"traceutil/trace.go:171","msg":"trace[447924776] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:628; }","duration":"3.532092791s","start":"2026-04-19T11:02:13.888178Z","end":"2026-04-19T11:02:17.420271Z","steps":["trace[447924776] 'agreement among raft nodes before linearized reading' (duration: 3.531814563s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:17.420301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:13.883085Z","time spent":"3.537205198s","remote":"127.0.0.1:55402","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-19T11:02:17.420333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:13.888159Z","time spent":"3.532166244s","remote":"127.0.0.1:55402","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-19T11:02:17.420352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.51368293s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.141\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-19T11:02:17.420393Z","caller":"traceutil/trace.go:171","msg":"trace[2136029020] range","detail":"{range_begin:/registry/masterleases/199.19.213.141; range_end:; response_count:1; response_revision:628; }","duration":"1.513763883s","start":"2026-04-19T11:02:15.906617Z","end":"2026-04-19T11:02:17.420381Z","steps":["trace[2136029020] 'agreement among raft nodes before linearized reading' (duration: 1.513658909s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:17.420426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:15.906595Z","time spent":"1.513824115s","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.141\" "} {"level":"info","ts":"2026-04-19T11:02:17.420456Z","caller":"traceutil/trace.go:171","msg":"trace[110644544] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"3.198312247s","start":"2026-04-19T11:02:14.22213Z","end":"2026-04-19T11:02:17.420442Z","steps":["trace[110644544] 'process raft request' (duration: 355.501238ms)","trace[110644544] 'compare' (duration: 2.84014487s)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:02:17.420529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:14.222109Z","time spent":"3.198374399s","remote":"127.0.0.1:55492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-19T11:02:17.420632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.541769357s","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-19T11:02:17.42067Z","caller":"traceutil/trace.go:171","msg":"trace[1894418767] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:628; }","duration":"3.541830669s","start":"2026-04-19T11:02:13.878829Z","end":"2026-04-19T11:02:17.42066Z","steps":["trace[1894418767] 'agreement among raft nodes before linearized reading' (duration: 3.541728396s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:17.420705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:13.878818Z","time spent":"3.54187696s","remote":"127.0.0.1:55402","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-19T11:02:17.420772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.339890424s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:02:17.420803Z","caller":"traceutil/trace.go:171","msg":"trace[272813549] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:628; }","duration":"1.339987017s","start":"2026-04-19T11:02:16.080806Z","end":"2026-04-19T11:02:17.420793Z","steps":["trace[272813549] 'agreement among raft nodes before linearized reading' (duration: 1.339940345s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:17.420885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:16.080769Z","time spent":"1.34010925s","remote":"127.0.0.1:55102","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-19T11:02:17.965818Z","caller":"traceutil/trace.go:171","msg":"trace[1847795709] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"186.949361ms","start":"2026-04-19T11:02:17.778851Z","end":"2026-04-19T11:02:17.965801Z","steps":["trace[1847795709] 'process raft request' (duration: 186.759565ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:02:17.979558Z","caller":"traceutil/trace.go:171","msg":"trace[1458371060] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"189.416244ms","start":"2026-04-19T11:02:17.79011Z","end":"2026-04-19T11:02:17.979526Z","steps":["trace[1458371060] 'process raft request' (duration: 189.205837ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:02:18.214813Z","caller":"traceutil/trace.go:171","msg":"trace[933080406] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"178.12376ms","start":"2026-04-19T11:02:18.036583Z","end":"2026-04-19T11:02:18.214707Z","steps":["trace[933080406] 'process raft request' (duration: 118.095838ms)","trace[933080406] 'compare' (duration: 59.814876ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:02:18.556468Z","caller":"traceutil/trace.go:171","msg":"trace[137119998] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"335.776638ms","start":"2026-04-19T11:02:18.220674Z","end":"2026-04-19T11:02:18.556451Z","steps":["trace[137119998] 'process raft request' (duration: 334.48419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:18.55656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:18.220652Z","time spent":"335.861851ms","remote":"127.0.0.1:55200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":700,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-19T11:02:19.513322Z","caller":"traceutil/trace.go:171","msg":"trace[1762530513] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"380.477622ms","start":"2026-04-19T11:02:19.132825Z","end":"2026-04-19T11:02:19.513302Z","steps":["trace[1762530513] 'process raft request' (duration: 379.495923ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:19.513484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:19.132796Z","time spent":"380.594776ms","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T11:02:22.302789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.944702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-04-19T11:02:22.302865Z","caller":"traceutil/trace.go:171","msg":"trace[837188092] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:711; }","duration":"222.059544ms","start":"2026-04-19T11:02:22.08079Z","end":"2026-04-19T11:02:22.30285Z","steps":["trace[837188092] 'range keys from in-memory index tree' (duration: 221.823728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:22.30301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.23351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:02:22.303037Z","caller":"traceutil/trace.go:171","msg":"trace[2040707559] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:711; }","duration":"222.309121ms","start":"2026-04-19T11:02:22.080722Z","end":"2026-04-19T11:02:22.303031Z","steps":["trace[2040707559] 'range keys from in-memory index tree' (duration: 222.160247ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:02:31.980791Z","caller":"traceutil/trace.go:171","msg":"trace[724729679] linearizableReadLoop","detail":"{readStateIndex:815; appliedIndex:814; }","duration":"369.842623ms","start":"2026-04-19T11:02:31.610926Z","end":"2026-04-19T11:02:31.980768Z","steps":["trace[724729679] 'read index received' (duration: 369.609267ms)","trace[724729679] 'applied index is now lower than readState.Index' (duration: 232.106µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:02:31.980882Z","caller":"traceutil/trace.go:171","msg":"trace[178813979] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"428.567218ms","start":"2026-04-19T11:02:31.552281Z","end":"2026-04-19T11:02:31.980847Z","steps":["trace[178813979] 'process raft request' (duration: 428.25813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:31.981036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:31.552255Z","time spent":"428.702701ms","remote":"127.0.0.1:55402","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-19T11:02:31.981041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.09211ms","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-19T11:02:31.981165Z","caller":"traceutil/trace.go:171","msg":"trace[870319397] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:786; }","duration":"370.257634ms","start":"2026-04-19T11:02:31.610883Z","end":"2026-04-19T11:02:31.981141Z","steps":["trace[870319397] 'agreement among raft nodes before linearized reading' (duration: 370.031988ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:31.981233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:31.610863Z","time spent":"370.348467ms","remote":"127.0.0.1:55402","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-19T11:02:31.981432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.159489ms","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-19T11:02:31.981709Z","caller":"traceutil/trace.go:171","msg":"trace[41707752] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:786; }","duration":"364.509428ms","start":"2026-04-19T11:02:31.617188Z","end":"2026-04-19T11:02:31.981697Z","steps":["trace[41707752] 'agreement among raft nodes before linearized reading' (duration: 364.001284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:31.981741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:31.617162Z","time spent":"364.56586ms","remote":"127.0.0.1:55402","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-19T11:02:31.981741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.644869ms","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-19T11:02:31.981745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.818845ms","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-19T11:02:31.981777Z","caller":"traceutil/trace.go:171","msg":"trace[2020872175] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:786; }","duration":"365.697001ms","start":"2026-04-19T11:02:31.616071Z","end":"2026-04-19T11:02:31.981768Z","steps":["trace[2020872175] 'agreement among raft nodes before linearized reading' (duration: 365.420414ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:02:31.981795Z","caller":"traceutil/trace.go:171","msg":"trace[1449517041] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:786; }","duration":"368.933689ms","start":"2026-04-19T11:02:31.612851Z","end":"2026-04-19T11:02:31.981784Z","steps":["trace[1449517041] 'agreement among raft nodes before linearized reading' (duration: 368.811175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:31.981802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:31.616064Z","time spent":"365.732362ms","remote":"127.0.0.1:55402","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-19T11:02:31.981815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:31.612834Z","time spent":"368.975959ms","remote":"127.0.0.1:55402","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-19T11:02:36.191024Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15132549556665678013,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-19T11:02:36.630758Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.74237541s","expected-duration":"1s"} {"level":"info","ts":"2026-04-19T11:02:36.631266Z","caller":"traceutil/trace.go:171","msg":"trace[349835737] linearizableReadLoop","detail":"{readStateIndex:837; appliedIndex:836; }","duration":"940.821289ms","start":"2026-04-19T11:02:35.690419Z","end":"2026-04-19T11:02:36.63124Z","steps":["trace[349835737] 'read index received' (duration: 940.551731ms)","trace[349835737] 'applied index is now lower than readState.Index' (duration: 268.308µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:02:36.631392Z","caller":"traceutil/trace.go:171","msg":"trace[1259352694] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"1.7431325s","start":"2026-04-19T11:02:34.888228Z","end":"2026-04-19T11:02:36.63136Z","steps":["trace[1259352694] 'process raft request' (duration: 1.742846062s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:36.631492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"910.367101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-19T11:02:36.631594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:34.888203Z","time spent":"1.743256452s","remote":"127.0.0.1:55376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T11:02:36.631612Z","caller":"traceutil/trace.go:171","msg":"trace[110118963] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:806; }","duration":"910.511385ms","start":"2026-04-19T11:02:35.721084Z","end":"2026-04-19T11:02:36.631595Z","steps":["trace[110118963] 'agreement among raft nodes before linearized reading' (duration: 910.33454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:36.631663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:35.721021Z","time spent":"910.626567ms","remote":"127.0.0.1:55262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-04-19T11:02:36.631715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"941.107956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-19T11:02:36.631825Z","caller":"traceutil/trace.go:171","msg":"trace[1709780791] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:806; }","duration":"941.431544ms","start":"2026-04-19T11:02:35.690366Z","end":"2026-04-19T11:02:36.631797Z","steps":["trace[1709780791] 'agreement among raft nodes before linearized reading' (duration: 941.027104ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:36.631949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:35.690344Z","time spent":"941.582119ms","remote":"127.0.0.1:55626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-04-19T11:02:36.632004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.532952ms","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-19T11:02:36.632066Z","caller":"traceutil/trace.go:171","msg":"trace[2025603391] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:806; }","duration":"605.606264ms","start":"2026-04-19T11:02:36.026444Z","end":"2026-04-19T11:02:36.63205Z","steps":["trace[2025603391] 'agreement among raft nodes before linearized reading' (duration: 605.434789ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:36.632112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:36.026437Z","time spent":"605.663646ms","remote":"127.0.0.1:55402","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-19T11:02:36.632158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.541763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-19T11:02:36.63202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.020382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-19T11:02:36.63231Z","caller":"traceutil/trace.go:171","msg":"trace[2021913262] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:806; }","duration":"604.458674ms","start":"2026-04-19T11:02:36.027834Z","end":"2026-04-19T11:02:36.632293Z","steps":["trace[2021913262] 'agreement among raft nodes before linearized reading' (duration: 603.92015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:36.632373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.687354ms","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-19T11:02:36.632439Z","caller":"traceutil/trace.go:171","msg":"trace[393055391] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:806; }","duration":"603.777676ms","start":"2026-04-19T11:02:36.028646Z","end":"2026-04-19T11:02:36.632424Z","steps":["trace[393055391] 'agreement among raft nodes before linearized reading' (duration: 603.622362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:36.632496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:36.028635Z","time spent":"603.847658ms","remote":"127.0.0.1:55402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-19T11:02:36.632267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.581944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.141\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-19T11:02:36.63253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.362418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-19T11:02:36.632544Z","caller":"traceutil/trace.go:171","msg":"trace[610999949] range","detail":"{range_begin:/registry/masterleases/199.19.213.141; range_end:; response_count:1; response_revision:806; }","duration":"724.885463ms","start":"2026-04-19T11:02:35.907648Z","end":"2026-04-19T11:02:36.632533Z","steps":["trace[610999949] 'agreement among raft nodes before linearized reading' (duration: 724.537413ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:36.632592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:35.907631Z","time spent":"724.951664ms","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.141\" "} {"level":"info","ts":"2026-04-19T11:02:36.632591Z","caller":"traceutil/trace.go:171","msg":"trace[193154145] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:806; }","duration":"201.457091ms","start":"2026-04-19T11:02:36.43112Z","end":"2026-04-19T11:02:36.632577Z","steps":["trace[193154145] 'agreement among raft nodes before linearized reading' (duration: 201.366148ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:36.632662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:36.027827Z","time spent":"604.556337ms","remote":"127.0.0.1:55402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-19T11:02:36.632342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.87053ms","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-19T11:02:36.632774Z","caller":"traceutil/trace.go:171","msg":"trace[1123267417] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:806; }","duration":"609.329793ms","start":"2026-04-19T11:02:36.023437Z","end":"2026-04-19T11:02:36.632767Z","steps":["trace[1123267417] 'agreement among raft nodes before linearized reading' (duration: 608.85331ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:36.632809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:36.023418Z","time spent":"609.383264ms","remote":"127.0.0.1:55402","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-19T11:02:36.63288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.655015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:02:36.632932Z","caller":"traceutil/trace.go:171","msg":"trace[1319054599] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:806; }","duration":"553.716746ms","start":"2026-04-19T11:02:36.07919Z","end":"2026-04-19T11:02:36.632907Z","steps":["trace[1319054599] 'agreement among raft nodes before linearized reading' (duration: 552.945076ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:36.632958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:36.079175Z","time spent":"553.778808ms","remote":"127.0.0.1:55108","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-19T11:02:36.632337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.628317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-19T11:02:36.633885Z","caller":"traceutil/trace.go:171","msg":"trace[678190546] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:806; }","duration":"609.166988ms","start":"2026-04-19T11:02:36.024683Z","end":"2026-04-19T11:02:36.63385Z","steps":["trace[678190546] 'agreement among raft nodes before linearized reading' (duration: 607.545266ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:02:36.632225Z","caller":"traceutil/trace.go:171","msg":"trace[671556182] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:806; }","duration":"341.638866ms","start":"2026-04-19T11:02:36.290568Z","end":"2026-04-19T11:02:36.632207Z","steps":["trace[671556182] 'agreement among raft nodes before linearized reading' (duration: 341.542924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:02:36.634168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:36.290549Z","time spent":"343.545546ms","remote":"127.0.0.1:55358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-04-19T11:02:36.633994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:02:36.024677Z","time spent":"609.308802ms","remote":"127.0.0.1:55402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-19T11:03:13.689444Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15132549556665678550,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-19T11:03:13.698091Z","caller":"traceutil/trace.go:171","msg":"trace[978776950] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"804.475263ms","start":"2026-04-19T11:03:12.893553Z","end":"2026-04-19T11:03:13.698028Z","steps":["trace[978776950] 'process raft request' (duration: 804.3376ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:03:13.698264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:12.893537Z","time spent":"804.657687ms","remote":"127.0.0.1:55402","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-19T11:03:13.703891Z","caller":"traceutil/trace.go:171","msg":"trace[2042077217] linearizableReadLoop","detail":"{readStateIndex:1100; appliedIndex:1098; }","duration":"804.639136ms","start":"2026-04-19T11:03:12.899229Z","end":"2026-04-19T11:03:13.703868Z","steps":["trace[2042077217] 'read index received' (duration: 798.678675ms)","trace[2042077217] 'applied index is now lower than readState.Index' (duration: 5.959041ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:03:13.704092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"804.84413ms","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-19T11:03:13.704113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.720032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:03:13.704121Z","caller":"traceutil/trace.go:171","msg":"trace[1049867289] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1059; }","duration":"804.915271ms","start":"2026-04-19T11:03:12.899195Z","end":"2026-04-19T11:03:13.70411Z","steps":["trace[1049867289] 'agreement among raft nodes before linearized reading' (duration: 804.793609ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:03:13.70417Z","caller":"traceutil/trace.go:171","msg":"trace[743715871] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1059; }","duration":"623.840724ms","start":"2026-04-19T11:03:13.080319Z","end":"2026-04-19T11:03:13.70416Z","steps":["trace[743715871] 'agreement among raft nodes before linearized reading' (duration: 623.725482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:03:13.704175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.251596ms","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-19T11:03:13.70398Z","caller":"traceutil/trace.go:171","msg":"trace[472228800] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"808.557773ms","start":"2026-04-19T11:03:12.895394Z","end":"2026-04-19T11:03:13.703952Z","steps":["trace[472228800] 'process raft request' (duration: 808.338018ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:03:13.704214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:12.899183Z","time spent":"805.012554ms","remote":"127.0.0.1:55402","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-19T11:03:13.704203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:13.080295Z","time spent":"623.901325ms","remote":"127.0.0.1:55108","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-19T11:03:13.704249Z","caller":"traceutil/trace.go:171","msg":"trace[553407366] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1059; }","duration":"799.376089ms","start":"2026-04-19T11:03:12.904855Z","end":"2026-04-19T11:03:13.704231Z","steps":["trace[553407366] 'agreement among raft nodes before linearized reading' (duration: 799.174365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:03:13.704309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:12.904836Z","time spent":"799.466641ms","remote":"127.0.0.1:55402","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-19T11:03:13.704361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.890717ms","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-19T11:03:13.704464Z","caller":"traceutil/trace.go:171","msg":"trace[936142265] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1059; }","duration":"789.04492ms","start":"2026-04-19T11:03:12.915395Z","end":"2026-04-19T11:03:13.70444Z","steps":["trace[936142265] 'agreement among raft nodes before linearized reading' (duration: 788.714622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:03:13.704528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:12.915379Z","time spent":"789.139322ms","remote":"127.0.0.1:55402","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-19T11:03:13.704649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:12.895367Z","time spent":"808.89117ms","remote":"127.0.0.1:55402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T11:03:56.415472Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15132549556665678916,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-19T11:03:56.916407Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15132549556665678916,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-19T11:03:57.963202Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15132549556665678916,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-19T11:03:58.080415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.001090741s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-19T11:03:58.08051Z","caller":"traceutil/trace.go:171","msg":"trace[1393490868] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001238614s","start":"2026-04-19T11:03:56.079253Z","end":"2026-04-19T11:03:58.080491Z","steps":["trace[1393490868] 'agreement among raft nodes before linearized reading' (duration: 2.001109372s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:03:58.080566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:56.079235Z","time spent":"2.001317625s","remote":"127.0.0.1:55108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-19T11:03:58.463304Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15132549556665678916,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-19T11:03:58.964436Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15132549556665678916,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-19T11:03:59.389882Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.475991857s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-19T11:03:59.390257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:55.91374Z","time spent":"3.476512238s","remote":"127.0.0.1:55154","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-19T11:03:59.512292Z","caller":"traceutil/trace.go:171","msg":"trace[1261325777] linearizableReadLoop","detail":"{readStateIndex:1247; appliedIndex:1244; }","duration":"3.597773756s","start":"2026-04-19T11:03:55.914495Z","end":"2026-04-19T11:03:59.512269Z","steps":["trace[1261325777] 'read index received' (duration: 3.47562484s)","trace[1261325777] 'applied index is now lower than readState.Index' (duration: 122.148086ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:03:59.512413Z","caller":"traceutil/trace.go:171","msg":"trace[1730702641] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"3.327423132s","start":"2026-04-19T11:03:56.18497Z","end":"2026-04-19T11:03:59.512394Z","steps":["trace[1730702641] 'process raft request' (duration: 3.327240769s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:03:59.512524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.59800129s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-19T11:03:59.51254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:56.184955Z","time spent":"3.327523835s","remote":"127.0.0.1:55402","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-19T11:03:59.512562Z","caller":"traceutil/trace.go:171","msg":"trace[1116536189] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1197; }","duration":"3.598081401s","start":"2026-04-19T11:03:55.914469Z","end":"2026-04-19T11:03:59.51255Z","steps":["trace[1116536189] 'agreement among raft nodes before linearized reading' (duration: 3.597925118s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:03:59.512617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:55.914457Z","time spent":"3.598150913s","remote":"127.0.0.1:55402","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-19T11:03:59.51272Z","caller":"traceutil/trace.go:171","msg":"trace[1422948925] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"3.597323177s","start":"2026-04-19T11:03:55.915384Z","end":"2026-04-19T11:03:59.512708Z","steps":["trace[1422948925] 'process raft request' (duration: 3.59641964s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:03:59.512771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:55.91537Z","time spent":"3.597375648s","remote":"127.0.0.1:55402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T11:03:59.517655Z","caller":"traceutil/trace.go:171","msg":"trace[2040696155] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"126.329706ms","start":"2026-04-19T11:03:59.391303Z","end":"2026-04-19T11:03:59.517632Z","steps":["trace[2040696155] 'process raft request' (duration: 126.074921ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:03:59.517735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.437091421s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:03:59.51777Z","caller":"traceutil/trace.go:171","msg":"trace[1661266076] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1198; }","duration":"1.437177383s","start":"2026-04-19T11:03:58.080584Z","end":"2026-04-19T11:03:59.517761Z","steps":["trace[1661266076] 'agreement among raft nodes before linearized reading' (duration: 1.437100591s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:03:59.517793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:58.080565Z","time spent":"1.437222263s","remote":"127.0.0.1:55102","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-19T11:03:59.517959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.581461267s","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-19T11:03:59.518004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.74128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:03:59.518022Z","caller":"traceutil/trace.go:171","msg":"trace[2045312474] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1198; }","duration":"3.581549119s","start":"2026-04-19T11:03:55.93646Z","end":"2026-04-19T11:03:59.518009Z","steps":["trace[2045312474] 'agreement among raft nodes before linearized reading' (duration: 3.581355455s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:03:59.518059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.600844255s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-19T11:03:59.518073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:55.936449Z","time spent":"3.581617011s","remote":"127.0.0.1:55402","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-19T11:03:59.518028Z","caller":"traceutil/trace.go:171","msg":"trace[1933776720] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1198; }","duration":"315.793811ms","start":"2026-04-19T11:03:59.202227Z","end":"2026-04-19T11:03:59.518021Z","steps":["trace[1933776720] 'agreement among raft nodes before linearized reading' (duration: 315.75237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:03:59.518116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:59.202214Z","time spent":"315.894963ms","remote":"127.0.0.1:55108","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-19T11:03:59.518298Z","caller":"traceutil/trace.go:171","msg":"trace[1941193939] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1198; }","duration":"3.601178791s","start":"2026-04-19T11:03:55.916903Z","end":"2026-04-19T11:03:59.518082Z","steps":["trace[1941193939] 'agreement among raft nodes before linearized reading' (duration: 3.600741192s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:03:59.518349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:55.916894Z","time spent":"3.601446996s","remote":"127.0.0.1:55402","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-19T11:03:59.518355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.554819575s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:03:59.518382Z","caller":"traceutil/trace.go:171","msg":"trace[1302771322] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1198; }","duration":"1.554872126s","start":"2026-04-19T11:03:57.9635Z","end":"2026-04-19T11:03:59.518372Z","steps":["trace[1302771322] 'agreement among raft nodes before linearized reading' (duration: 1.554829655s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:03:59.518403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:57.963486Z","time spent":"1.554911986s","remote":"127.0.0.1:55372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-04-19T11:03:59.51848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.825765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:03:59.518509Z","caller":"traceutil/trace.go:171","msg":"trace[1897482910] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1198; }","duration":"770.892307ms","start":"2026-04-19T11:03:58.747607Z","end":"2026-04-19T11:03:59.518499Z","steps":["trace[1897482910] 'agreement among raft nodes before linearized reading' (duration: 770.836226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:03:59.51853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:58.747593Z","time spent":"770.931297ms","remote":"127.0.0.1:60150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-19T11:03:59.518576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.093427395s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-19T11:03:59.518599Z","caller":"traceutil/trace.go:171","msg":"trace[1019367619] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1198; }","duration":"1.093479997s","start":"2026-04-19T11:03:58.425112Z","end":"2026-04-19T11:03:59.518592Z","steps":["trace[1019367619] 'agreement among raft nodes before linearized reading' (duration: 1.093422876s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:03:59.518618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:58.425097Z","time spent":"1.093516877s","remote":"127.0.0.1:55176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-04-19T11:03:59.518774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.580740873s","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-19T11:03:59.518816Z","caller":"traceutil/trace.go:171","msg":"trace[438681401] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1198; }","duration":"3.580799054s","start":"2026-04-19T11:03:55.938Z","end":"2026-04-19T11:03:59.518799Z","steps":["trace[438681401] 'agreement among raft nodes before linearized reading' (duration: 3.580708862s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:03:59.518854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T11:03:55.937989Z","time spent":"3.580854995s","remote":"127.0.0.1:55402","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-19T11:04:03.784397Z","caller":"traceutil/trace.go:171","msg":"trace[1758506122] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"156.297129ms","start":"2026-04-19T11:04:03.628076Z","end":"2026-04-19T11:04:03.784373Z","steps":["trace[1758506122] 'process raft request' (duration: 108.839842ms)","trace[1758506122] 'compare' (duration: 47.295894ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:04:03.784695Z","caller":"traceutil/trace.go:171","msg":"trace[983080311] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"156.584275ms","start":"2026-04-19T11:04:03.628085Z","end":"2026-04-19T11:04:03.784669Z","steps":["trace[983080311] 'process raft request' (duration: 156.252109ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:03.784858Z","caller":"traceutil/trace.go:171","msg":"trace[1195310653] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"154.76834ms","start":"2026-04-19T11:04:03.630068Z","end":"2026-04-19T11:04:03.784836Z","steps":["trace[1195310653] 'process raft request' (duration: 154.529586ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:21.725751Z","caller":"traceutil/trace.go:171","msg":"trace[925178602] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"123.846744ms","start":"2026-04-19T11:04:21.601879Z","end":"2026-04-19T11:04:21.725725Z","steps":["trace[925178602] 'process raft request' (duration: 118.20716ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:22.078119Z","caller":"traceutil/trace.go:171","msg":"trace[1045002964] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"202.631241ms","start":"2026-04-19T11:04:21.875456Z","end":"2026-04-19T11:04:22.078087Z","steps":["trace[1045002964] 'process raft request' (duration: 202.479758ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:22.079322Z","caller":"traceutil/trace.go:171","msg":"trace[69051323] linearizableReadLoop","detail":"{readStateIndex:1331; appliedIndex:1330; }","duration":"196.387305ms","start":"2026-04-19T11:04:21.882898Z","end":"2026-04-19T11:04:22.079286Z","steps":["trace[69051323] 'read index received' (duration: 195.308845ms)","trace[69051323] 'applied index is now lower than readState.Index' (duration: 1.07699ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:04:22.079689Z","caller":"traceutil/trace.go:171","msg":"trace[1663712826] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"203.979776ms","start":"2026-04-19T11:04:21.875691Z","end":"2026-04-19T11:04:22.079671Z","steps":["trace[1663712826] 'process raft request' (duration: 203.450757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:22.080046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.125569ms","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-19T11:04:22.080114Z","caller":"traceutil/trace.go:171","msg":"trace[1576003091] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1275; }","duration":"197.228731ms","start":"2026-04-19T11:04:21.882866Z","end":"2026-04-19T11:04:22.080095Z","steps":["trace[1576003091] 'agreement among raft nodes before linearized reading' (duration: 197.029388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:22.080286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.224931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:04:22.080357Z","caller":"traceutil/trace.go:171","msg":"trace[1563976142] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1275; }","duration":"197.310493ms","start":"2026-04-19T11:04:21.883029Z","end":"2026-04-19T11:04:22.080339Z","steps":["trace[1563976142] 'agreement among raft nodes before linearized reading' (duration: 197.194721ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:40.103338Z","caller":"traceutil/trace.go:171","msg":"trace[958232008] linearizableReadLoop","detail":"{readStateIndex:1427; appliedIndex:1426; }","duration":"186.380274ms","start":"2026-04-19T11:04:39.916936Z","end":"2026-04-19T11:04:40.103317Z","steps":["trace[958232008] 'read index received' (duration: 186.184371ms)","trace[958232008] 'applied index is now lower than readState.Index' (duration: 195.173µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T11:04:40.103421Z","caller":"traceutil/trace.go:171","msg":"trace[1172824963] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"195.518919ms","start":"2026-04-19T11:04:39.907874Z","end":"2026-04-19T11:04:40.103393Z","steps":["trace[1172824963] 'process raft request' (duration: 195.316286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:40.10352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.570048ms","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-19T11:04:40.103544Z","caller":"traceutil/trace.go:171","msg":"trace[1077699700] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1368; }","duration":"186.634989ms","start":"2026-04-19T11:04:39.916901Z","end":"2026-04-19T11:04:40.103536Z","steps":["trace[1077699700] 'agreement among raft nodes before linearized reading' (duration: 186.522807ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:40.103693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.395675ms","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-19T11:04:40.103725Z","caller":"traceutil/trace.go:171","msg":"trace[1656756530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1368; }","duration":"181.456855ms","start":"2026-04-19T11:04:39.922259Z","end":"2026-04-19T11:04:40.103715Z","steps":["trace[1656756530] 'agreement among raft nodes before linearized reading' (duration: 181.334403ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:41.579239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.244809ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-19T11:04:41.579348Z","caller":"traceutil/trace.go:171","msg":"trace[1091709808] linearizableReadLoop","detail":"{readStateIndex:1433; appliedIndex:1432; }","duration":"118.78087ms","start":"2026-04-19T11:04:41.460551Z","end":"2026-04-19T11:04:41.579332Z","steps":["trace[1091709808] 'read index received' (duration: 14.522961ms)","trace[1091709808] 'applied index is now lower than readState.Index' (duration: 104.256209ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:04:41.57967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.929143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T11:04:41.579855Z","caller":"traceutil/trace.go:171","msg":"trace[411388109] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1373; }","duration":"119.3237ms","start":"2026-04-19T11:04:41.460506Z","end":"2026-04-19T11:04:41.57983Z","steps":["trace[411388109] 'agreement among raft nodes before linearized reading' (duration: 118.888092ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:42.330926Z","caller":"traceutil/trace.go:171","msg":"trace[1604703876] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"137.471605ms","start":"2026-04-19T11:04:42.193414Z","end":"2026-04-19T11:04:42.330886Z","steps":["trace[1604703876] 'process raft request' (duration: 137.262111ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T11:04:42.331252Z","caller":"traceutil/trace.go:171","msg":"trace[201246036] linearizableReadLoop","detail":"{readStateIndex:1441; appliedIndex:1441; }","duration":"130.083451ms","start":"2026-04-19T11:04:42.201124Z","end":"2026-04-19T11:04:42.331207Z","steps":["trace[201246036] 'read index received' (duration: 130.058541ms)","trace[201246036] 'applied index is now lower than readState.Index' (duration: 20.97µs)"],"step_count":2} {"level":"warn","ts":"2026-04-19T11:04:42.331474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.339856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-19T11:04:42.331562Z","caller":"traceutil/trace.go:171","msg":"trace[1540519345] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1381; }","duration":"130.469609ms","start":"2026-04-19T11:04:42.201076Z","end":"2026-04-19T11:04:42.331545Z","steps":["trace[1540519345] 'agreement among raft nodes before linearized reading' (duration: 130.233554ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T11:04:42.331621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.431264ms","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-19T11:04:42.331668Z","caller":"traceutil/trace.go:171","msg":"trace[1917118300] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1381; }","duration":"107.561007ms","start":"2026-04-19T11:04:42.224094Z","end":"2026-04-19T11:04:42.331655Z","steps":["trace[1917118300] 'agreement among raft nodes before linearized reading' (duration: 107.325802ms)"],"step_count":1}