{"level":"warn","ts":"2026-04-08T00:35:56.22356Z","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-08T00:35:56.223816Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.153:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.153:2380","--initial-cluster=instance=https://199.204.45.153:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.153:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.153: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-08T00:35:56.223962Z","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-08T00:35:56.223986Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.153:2380"]} {"level":"info","ts":"2026-04-08T00:35:56.224028Z","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-08T00:35:56.225822Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"]} {"level":"info","ts":"2026-04-08T00:35:56.22615Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.153:2380"],"listen-peer-urls":["https://199.204.45.153:2380"],"advertise-client-urls":["https://199.204.45.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.153: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-08T00:35:56.231058Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.540413ms"} {"level":"info","ts":"2026-04-08T00:35:56.242102Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"355195d5aae85944","cluster-id":"e424441517bc0b32"} {"level":"info","ts":"2026-04-08T00:35:56.242263Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=()"} {"level":"info","ts":"2026-04-08T00:35:56.242295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became follower at term 0"} {"level":"info","ts":"2026-04-08T00:35:56.242314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 355195d5aae85944 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-08T00:35:56.242328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became follower at term 1"} {"level":"info","ts":"2026-04-08T00:35:56.242376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=(3842016702051670340)"} {"level":"warn","ts":"2026-04-08T00:35:56.249337Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-08T00:35:56.250856Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-08T00:35:56.252161Z","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-08T00:35:56.25496Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"355195d5aae85944","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-08T00:35:56.255151Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"355195d5aae85944","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-08T00:35:56.255789Z","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-08T00:35:56.25596Z","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-08T00:35:56.255984Z","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-08T00:35:56.256736Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=(3842016702051670340)"} {"level":"info","ts":"2026-04-08T00:35:56.256869Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e424441517bc0b32","local-member-id":"355195d5aae85944","added-peer-id":"355195d5aae85944","added-peer-peer-urls":["https://199.204.45.153:2380"]} {"level":"info","ts":"2026-04-08T00:35:56.260626Z","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-08T00:35:56.260813Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.153:2380"} {"level":"info","ts":"2026-04-08T00:35:56.260928Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.153:2380"} {"level":"info","ts":"2026-04-08T00:35:56.262016Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"355195d5aae85944","initial-advertise-peer-urls":["https://199.204.45.153:2380"],"listen-peer-urls":["https://199.204.45.153:2380"],"advertise-client-urls":["https://199.204.45.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-08T00:35:56.262067Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-08T00:35:56.442979Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 is starting a new election at term 1"} {"level":"info","ts":"2026-04-08T00:35:56.443105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-08T00:35:56.443206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 received MsgPreVoteResp from 355195d5aae85944 at term 1"} {"level":"info","ts":"2026-04-08T00:35:56.443298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became candidate at term 2"} {"level":"info","ts":"2026-04-08T00:35:56.4434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 received MsgVoteResp from 355195d5aae85944 at term 2"} {"level":"info","ts":"2026-04-08T00:35:56.443464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became leader at term 2"} {"level":"info","ts":"2026-04-08T00:35:56.443548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 355195d5aae85944 elected leader 355195d5aae85944 at term 2"} {"level":"info","ts":"2026-04-08T00:35:56.444803Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T00:35:56.445517Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"355195d5aae85944","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.153:2379]}","request-path":"/0/members/355195d5aae85944/attributes","cluster-id":"e424441517bc0b32","publish-timeout":"7s"} {"level":"info","ts":"2026-04-08T00:35:56.445546Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T00:35:56.445527Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T00:35:56.446152Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e424441517bc0b32","local-member-id":"355195d5aae85944","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T00:35:56.446449Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T00:35:56.446629Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T00:35:56.447138Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-08T00:35:56.447182Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-08T00:35:56.449457Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.153:2379"} {"level":"info","ts":"2026-04-08T00:35:56.449569Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-08T00:35:59.203225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.175097ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"warn","ts":"2026-04-08T00:35:59.203651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.83202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-08T00:35:59.20373Z","caller":"traceutil/trace.go:171","msg":"trace[579835586] transaction","detail":"{read_only:false; response_revision:82; number_of_response:1; }","duration":"403.294611ms","start":"2026-04-08T00:35:58.800125Z","end":"2026-04-08T00:35:59.203419Z","steps":["trace[579835586] 'process raft request' (duration: 251.006747ms)","trace[579835586] 'compare' (duration: 148.955819ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:35:59.203765Z","caller":"traceutil/trace.go:171","msg":"trace[862882353] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:82; }","duration":"356.022346ms","start":"2026-04-08T00:35:58.847714Z","end":"2026-04-08T00:35:59.203736Z","steps":["trace[862882353] 'agreement among raft nodes before linearized reading' (duration: 355.761927ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:35:59.203777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:35:58.800109Z","time spent":"403.647522ms","remote":"127.0.0.1:48272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T00:35:59.203863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:35:58.847689Z","time spent":"356.16314ms","remote":"127.0.0.1:47942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-08T00:35:59.203686Z","caller":"traceutil/trace.go:171","msg":"trace[515862345] linearizableReadLoop","detail":"{readStateIndex:86; appliedIndex:85; }","duration":"355.55222ms","start":"2026-04-08T00:35:58.847764Z","end":"2026-04-08T00:35:59.203317Z","steps":["trace[515862345] 'read index received' (duration: 203.288837ms)","trace[515862345] 'applied index is now lower than readState.Index' (duration: 152.262173ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:35:59.204191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.3168ms","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:4"} {"level":"info","ts":"2026-04-08T00:35:59.20424Z","caller":"traceutil/trace.go:171","msg":"trace[562028758] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:82; }","duration":"317.425664ms","start":"2026-04-08T00:35:58.886797Z","end":"2026-04-08T00:35:59.204223Z","steps":["trace[562028758] 'agreement among raft nodes before linearized reading' (duration: 317.3109ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:35:59.204276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:35:58.886774Z","time spent":"317.494575ms","remote":"127.0.0.1:48054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"warn","ts":"2026-04-08T00:36:00.980786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.293011ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:36:00.980984Z","caller":"traceutil/trace.go:171","msg":"trace[585817330] linearizableReadLoop","detail":"{readStateIndex:180; appliedIndex:179; }","duration":"1.133337567s","start":"2026-04-08T00:35:59.847617Z","end":"2026-04-08T00:36:00.980954Z","steps":["trace[585817330] 'read index received' (duration: 206.798568ms)","trace[585817330] 'applied index is now lower than readState.Index' (duration: 926.536399ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:36:00.981146Z","caller":"traceutil/trace.go:171","msg":"trace[1991351006] transaction","detail":"{read_only:false; response_revision:176; number_of_response:1; }","duration":"1.16272416s","start":"2026-04-08T00:35:59.818399Z","end":"2026-04-08T00:36:00.981123Z","steps":["trace[1991351006] 'process raft request' (duration: 235.995945ms)","trace[1991351006] 'compare' (duration: 926.114326ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:36:00.981225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:35:59.818363Z","time spent":"1.162821313s","remote":"127.0.0.1:48276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":764,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T00:36:00.9813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.133662997s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:36:00.981394Z","caller":"traceutil/trace.go:171","msg":"trace[1421915252] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:176; }","duration":"1.133789501s","start":"2026-04-08T00:35:59.847578Z","end":"2026-04-08T00:36:00.981368Z","steps":["trace[1421915252] 'agreement among raft nodes before linearized reading' (duration: 1.133570174s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:00.981436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:35:59.847563Z","time spent":"1.133864743s","remote":"127.0.0.1:47942","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-08T00:36:01.48142Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439248605598635,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T00:36:01.982147Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439248605598635,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T00:36:02.123969Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.344808625s","expected-duration":"1s"} {"level":"info","ts":"2026-04-08T00:36:02.124592Z","caller":"traceutil/trace.go:171","msg":"trace[245710586] linearizableReadLoop","detail":"{readStateIndex:181; appliedIndex:180; }","duration":"1.14347893s","start":"2026-04-08T00:36:00.981078Z","end":"2026-04-08T00:36:02.124557Z","steps":["trace[245710586] 'read index received' (duration: 1.143215932s)","trace[245710586] 'applied index is now lower than readState.Index' (duration: 261.628µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:36:02.124807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.1746865s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:36:02.124944Z","caller":"traceutil/trace.go:171","msg":"trace[2144031919] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:177; }","duration":"1.17498436s","start":"2026-04-08T00:36:00.949918Z","end":"2026-04-08T00:36:02.124902Z","steps":["trace[2144031919] 'agreement among raft nodes before linearized reading' (duration: 1.17467814s)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:02.124911Z","caller":"traceutil/trace.go:171","msg":"trace[447729100] transaction","detail":"{read_only:false; response_revision:177; number_of_response:1; }","duration":"1.345867709s","start":"2026-04-08T00:36:00.778997Z","end":"2026-04-08T00:36:02.124865Z","steps":["trace[447729100] 'process raft request' (duration: 1.345372773s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:02.124878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.439308835s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:36:02.125132Z","caller":"traceutil/trace.go:171","msg":"trace[168759476] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:177; }","duration":"1.439636045s","start":"2026-04-08T00:36:00.685474Z","end":"2026-04-08T00:36:02.125111Z","steps":["trace[168759476] 'agreement among raft nodes before linearized reading' (duration: 1.439301095s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:02.125156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:00.778964Z","time spent":"1.346099376s","remote":"127.0.0.1:48118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T00:36:02.125239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.142670775s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:36:02.125279Z","caller":"traceutil/trace.go:171","msg":"trace[2107286711] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:177; }","duration":"1.142740547s","start":"2026-04-08T00:36:00.982525Z","end":"2026-04-08T00:36:02.125266Z","steps":["trace[2107286711] 'agreement among raft nodes before linearized reading' (duration: 1.142689896s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:02.125325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:00.982506Z","time spent":"1.1428112s","remote":"127.0.0.1:47926","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-08T00:36:02.125373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:00.685439Z","time spent":"1.439857312s","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-08T00:36:02.125483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.141479378s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:service-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T00:36:02.125574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.852178055s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-08T00:36:02.125564Z","caller":"traceutil/trace.go:171","msg":"trace[384174963] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:service-controller; range_end:; response_count:0; response_revision:177; }","duration":"1.141638553s","start":"2026-04-08T00:36:00.983903Z","end":"2026-04-08T00:36:02.125541Z","steps":["trace[384174963] 'agreement among raft nodes before linearized reading' (duration: 1.141429006s)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:02.12565Z","caller":"traceutil/trace.go:171","msg":"trace[162210361] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:177; }","duration":"1.85229868s","start":"2026-04-08T00:36:00.273328Z","end":"2026-04-08T00:36:02.125627Z","steps":["trace[162210361] 'agreement among raft nodes before linearized reading' (duration: 1.851558456s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:02.125063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:00.949887Z","time spent":"1.175160555s","remote":"127.0.0.1:47942","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-08T00:36:02.125708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:00.273302Z","time spent":"1.852391222s","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-08T00:36:02.125711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:00.983876Z","time spent":"1.141815649s","remote":"127.0.0.1:48276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/clusterrolebindings/system:controller:service-controller\" "} {"level":"warn","ts":"2026-04-08T00:36:02.125151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.851679809s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:430"} {"level":"info","ts":"2026-04-08T00:36:02.126036Z","caller":"traceutil/trace.go:171","msg":"trace[1043867577] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:177; }","duration":"1.852388342s","start":"2026-04-08T00:36:00.273418Z","end":"2026-04-08T00:36:02.125806Z","steps":["trace[1043867577] 'agreement among raft nodes before linearized reading' (duration: 1.85168869s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:02.126111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:00.273392Z","time spent":"1.852700192s","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-08T00:36:02.34382Z","caller":"traceutil/trace.go:171","msg":"trace[1243935998] linearizableReadLoop","detail":"{readStateIndex:185; appliedIndex:182; }","duration":"196.39728ms","start":"2026-04-08T00:36:02.14739Z","end":"2026-04-08T00:36:02.343787Z","steps":["trace[1243935998] 'read index received' (duration: 4.504131ms)","trace[1243935998] 'applied index is now lower than readState.Index' (duration: 191.891579ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:36:02.344028Z","caller":"traceutil/trace.go:171","msg":"trace[19175959] transaction","detail":"{read_only:false; response_revision:181; number_of_response:1; }","duration":"211.794234ms","start":"2026-04-08T00:36:02.132221Z","end":"2026-04-08T00:36:02.344016Z","steps":["trace[19175959] 'process raft request' (duration: 211.528316ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:02.344229Z","caller":"traceutil/trace.go:171","msg":"trace[757446517] transaction","detail":"{read_only:false; response_revision:179; number_of_response:1; }","duration":"212.402944ms","start":"2026-04-08T00:36:02.131789Z","end":"2026-04-08T00:36:02.344192Z","steps":["trace[757446517] 'process raft request' (duration: 205.2744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:02.344451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.554737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:statefulset-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T00:36:02.344491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.107573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:36:02.344545Z","caller":"traceutil/trace.go:171","msg":"trace[899969604] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:181; }","duration":"197.204227ms","start":"2026-04-08T00:36:02.147321Z","end":"2026-04-08T00:36:02.344525Z","steps":["trace[899969604] 'agreement among raft nodes before linearized reading' (duration: 197.135684ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:02.344541Z","caller":"traceutil/trace.go:171","msg":"trace[2100871855] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:statefulset-controller; range_end:; response_count:0; response_revision:181; }","duration":"190.724192ms","start":"2026-04-08T00:36:02.153796Z","end":"2026-04-08T00:36:02.34452Z","steps":["trace[2100871855] 'agreement among raft nodes before linearized reading' (duration: 190.472584ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:02.344018Z","caller":"traceutil/trace.go:171","msg":"trace[347744040] transaction","detail":"{read_only:false; response_revision:180; number_of_response:1; }","duration":"211.833026ms","start":"2026-04-08T00:36:02.132144Z","end":"2026-04-08T00:36:02.343977Z","steps":["trace[347744040] 'process raft request' (duration: 211.489085ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:04.192334Z","caller":"traceutil/trace.go:171","msg":"trace[356383323] linearizableReadLoop","detail":"{readStateIndex:242; appliedIndex:241; }","duration":"160.203415ms","start":"2026-04-08T00:36:04.0321Z","end":"2026-04-08T00:36:04.192303Z","steps":["trace[356383323] 'read index received' (duration: 159.947087ms)","trace[356383323] 'applied index is now lower than readState.Index' (duration: 255.188µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:36:04.192455Z","caller":"traceutil/trace.go:171","msg":"trace[2066062131] transaction","detail":"{read_only:false; response_revision:236; number_of_response:1; }","duration":"277.974295ms","start":"2026-04-08T00:36:03.914403Z","end":"2026-04-08T00:36:04.192377Z","steps":["trace[2066062131] 'process raft request' (duration: 277.720847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:04.192545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.424732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3709"} {"level":"info","ts":"2026-04-08T00:36:04.192591Z","caller":"traceutil/trace.go:171","msg":"trace[1356067781] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:236; }","duration":"160.503274ms","start":"2026-04-08T00:36:04.032077Z","end":"2026-04-08T00:36:04.19258Z","steps":["trace[1356067781] 'agreement among raft nodes before linearized reading' (duration: 160.35422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:30.25606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.701348ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:36:30.256416Z","caller":"traceutil/trace.go:171","msg":"trace[1368906395] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"917.760047ms","start":"2026-04-08T00:36:29.338628Z","end":"2026-04-08T00:36:30.256388Z","steps":["trace[1368906395] 'process raft request' (duration: 764.359787ms)","trace[1368906395] 'compare' (duration: 152.425679ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:36:30.256515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:29.338611Z","time spent":"917.856539ms","remote":"127.0.0.1:47984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74745,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:36:30.386152Z","caller":"traceutil/trace.go:171","msg":"trace[2055973723] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"1.013856276s","start":"2026-04-08T00:36:29.372242Z","end":"2026-04-08T00:36:30.386098Z","steps":["trace[2055973723] 'process raft request' (duration: 990.10774ms)","trace[2055973723] 'compare' (duration: 23.210309ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:36:30.386344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:29.372208Z","time spent":"1.014033992s","remote":"127.0.0.1:47984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151221,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:36:30.387619Z","caller":"traceutil/trace.go:171","msg":"trace[1677349008] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"748.621362ms","start":"2026-04-08T00:36:29.638901Z","end":"2026-04-08T00:36:30.387523Z","steps":["trace[1677349008] 'process raft request' (duration: 747.483486ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:30.38775Z","caller":"traceutil/trace.go:171","msg":"trace[1833329811] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"118.275366ms","start":"2026-04-08T00:36:30.269451Z","end":"2026-04-08T00:36:30.387727Z","steps":["trace[1833329811] 'process raft request' (duration: 117.891444ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:30.387921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:29.63887Z","time spent":"748.834639ms","remote":"127.0.0.1:47984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T00:36:32.679313Z","caller":"traceutil/trace.go:171","msg":"trace[264898887] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"138.890864ms","start":"2026-04-08T00:36:32.540389Z","end":"2026-04-08T00:36:32.679279Z","steps":["trace[264898887] 'process raft request' (duration: 138.640876ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:32.808581Z","caller":"traceutil/trace.go:171","msg":"trace[585885745] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"267.648999ms","start":"2026-04-08T00:36:32.540895Z","end":"2026-04-08T00:36:32.808544Z","steps":["trace[585885745] 'process raft request' (duration: 267.04043ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:32.808693Z","caller":"traceutil/trace.go:171","msg":"trace[205668738] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:582; }","duration":"259.38306ms","start":"2026-04-08T00:36:32.549282Z","end":"2026-04-08T00:36:32.808665Z","steps":["trace[205668738] 'read index received' (duration: 129.880031ms)","trace[205668738] 'applied index is now lower than readState.Index' (duration: 129.502129ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:36:32.808814Z","caller":"traceutil/trace.go:171","msg":"trace[1619477972] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"267.269457ms","start":"2026-04-08T00:36:32.541535Z","end":"2026-04-08T00:36:32.808804Z","steps":["trace[1619477972] 'process raft request' (duration: 266.557025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:32.808979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.687839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.153\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-08T00:36:32.80902Z","caller":"traceutil/trace.go:171","msg":"trace[439030655] range","detail":"{range_begin:/registry/masterleases/199.204.45.153; range_end:; response_count:1; response_revision:562; }","duration":"259.738621ms","start":"2026-04-08T00:36:32.549269Z","end":"2026-04-08T00:36:32.809008Z","steps":["trace[439030655] 'agreement among raft nodes before linearized reading' (duration: 259.631937ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:32.809169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.967657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T00:36:32.809205Z","caller":"traceutil/trace.go:171","msg":"trace[94395603] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:562; }","duration":"224.0441ms","start":"2026-04-08T00:36:32.58515Z","end":"2026-04-08T00:36:32.809194Z","steps":["trace[94395603] 'agreement among raft nodes before linearized reading' (duration: 223.908055ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:32.809312Z","caller":"traceutil/trace.go:171","msg":"trace[1595222246] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"202.777121ms","start":"2026-04-08T00:36:32.606527Z","end":"2026-04-08T00:36:32.809304Z","steps":["trace[1595222246] 'process raft request' (duration: 201.878063ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:33.263798Z","caller":"traceutil/trace.go:171","msg":"trace[253985262] linearizableReadLoop","detail":"{readStateIndex:589; appliedIndex:587; }","duration":"393.358549ms","start":"2026-04-08T00:36:32.870403Z","end":"2026-04-08T00:36:33.263762Z","steps":["trace[253985262] 'read index received' (duration: 21.085152ms)","trace[253985262] 'applied index is now lower than readState.Index' (duration: 372.272057ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:36:33.263929Z","caller":"traceutil/trace.go:171","msg":"trace[541627465] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"431.225769ms","start":"2026-04-08T00:36:32.832626Z","end":"2026-04-08T00:36:33.263852Z","steps":["trace[541627465] 'process raft request' (duration: 379.486694ms)","trace[541627465] 'compare' (duration: 51.25205ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:36:33.263965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.547845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T00:36:33.264086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:32.832611Z","time spent":"431.392815ms","remote":"127.0.0.1:47976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:36:33.264229Z","caller":"traceutil/trace.go:171","msg":"trace[144182696] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:564; }","duration":"393.634848ms","start":"2026-04-08T00:36:32.870366Z","end":"2026-04-08T00:36:33.264001Z","steps":["trace[144182696] 'agreement among raft nodes before linearized reading' (duration: 393.515634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:33.264294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:32.870353Z","time spent":"393.929438ms","remote":"127.0.0.1:48132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-04-08T00:36:34.130773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.213554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:36:34.130873Z","caller":"traceutil/trace.go:171","msg":"trace[1317343839] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:566; }","duration":"294.364989ms","start":"2026-04-08T00:36:33.836484Z","end":"2026-04-08T00:36:34.130849Z","steps":["trace[1317343839] 'range keys from in-memory index tree' (duration: 294.116922ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:34.754132Z","caller":"traceutil/trace.go:171","msg":"trace[149246041] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"104.976778ms","start":"2026-04-08T00:36:34.649132Z","end":"2026-04-08T00:36:34.754108Z","steps":["trace[149246041] 'process raft request' (duration: 43.704203ms)","trace[149246041] 'compare' (duration: 61.132481ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:36:34.939911Z","caller":"traceutil/trace.go:171","msg":"trace[2053178203] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:593; }","duration":"177.001222ms","start":"2026-04-08T00:36:34.762887Z","end":"2026-04-08T00:36:34.939888Z","steps":["trace[2053178203] 'read index received' (duration: 176.781525ms)","trace[2053178203] 'applied index is now lower than readState.Index' (duration: 218.596µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:36:34.939979Z","caller":"traceutil/trace.go:171","msg":"trace[1542051857] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"182.808864ms","start":"2026-04-08T00:36:34.757156Z","end":"2026-04-08T00:36:34.939965Z","steps":["trace[1542051857] 'process raft request' (duration: 182.574327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:34.94005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.145126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:36:34.940094Z","caller":"traceutil/trace.go:171","msg":"trace[23912518] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:569; }","duration":"177.228949ms","start":"2026-04-08T00:36:34.762853Z","end":"2026-04-08T00:36:34.940082Z","steps":["trace[23912518] 'agreement among raft nodes before linearized reading' (duration: 177.153656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:34.940169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.456034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-08T00:36:34.940198Z","caller":"traceutil/trace.go:171","msg":"trace[1474476172] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:569; }","duration":"127.523777ms","start":"2026-04-08T00:36:34.812666Z","end":"2026-04-08T00:36:34.94019Z","steps":["trace[1474476172] 'agreement among raft nodes before linearized reading' (duration: 127.433694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:34.940448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.64089ms","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-08T00:36:34.940632Z","caller":"traceutil/trace.go:171","msg":"trace[331622689] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:569; }","duration":"127.864237ms","start":"2026-04-08T00:36:34.812744Z","end":"2026-04-08T00:36:34.940608Z","steps":["trace[331622689] 'agreement among raft nodes before linearized reading' (duration: 127.584588ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:36.364725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.377736ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:36:36.364899Z","caller":"traceutil/trace.go:171","msg":"trace[1651402813] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:573; }","duration":"932.567462ms","start":"2026-04-08T00:36:35.432308Z","end":"2026-04-08T00:36:36.364875Z","steps":["trace[1651402813] 'range keys from in-memory index tree' (duration: 932.357325ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:36.364941Z","caller":"traceutil/trace.go:171","msg":"trace[737065364] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"938.642933ms","start":"2026-04-08T00:36:35.426272Z","end":"2026-04-08T00:36:36.364915Z","steps":["trace[737065364] 'process raft request' (duration: 934.818003ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:36.365061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:35.426245Z","time spent":"938.759397ms","remote":"127.0.0.1:48264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1044,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T00:36:36.389423Z","caller":"traceutil/trace.go:171","msg":"trace[1211588358] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"851.166465ms","start":"2026-04-08T00:36:35.538222Z","end":"2026-04-08T00:36:36.389389Z","steps":["trace[1211588358] 'process raft request' (duration: 850.99989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:36.389614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:35.538198Z","time spent":"851.309819ms","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:36:38.551605Z","caller":"traceutil/trace.go:171","msg":"trace[92348213] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"248.705394ms","start":"2026-04-08T00:36:38.302865Z","end":"2026-04-08T00:36:38.551571Z","steps":["trace[92348213] 'process raft request' (duration: 248.007012ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:38.551693Z","caller":"traceutil/trace.go:171","msg":"trace[269873114] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"234.609051ms","start":"2026-04-08T00:36:38.317001Z","end":"2026-04-08T00:36:38.55161Z","steps":["trace[269873114] 'read index received' (duration: 233.770935ms)","trace[269873114] 'applied index is now lower than readState.Index' (duration: 835.926µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:36:38.551933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.89554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:36:38.55208Z","caller":"traceutil/trace.go:171","msg":"trace[1092293673] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:587; }","duration":"235.064486ms","start":"2026-04-08T00:36:38.316975Z","end":"2026-04-08T00:36:38.55204Z","steps":["trace[1092293673] 'agreement among raft nodes before linearized reading' (duration: 234.788417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:38.552001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.871723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:36:38.552425Z","caller":"traceutil/trace.go:171","msg":"trace[1083966407] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:587; }","duration":"188.306536ms","start":"2026-04-08T00:36:38.364098Z","end":"2026-04-08T00:36:38.552405Z","steps":["trace[1083966407] 'agreement among raft nodes before linearized reading' (duration: 187.753179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:38.552539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.133961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:36:38.552593Z","caller":"traceutil/trace.go:171","msg":"trace[133257559] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:587; }","duration":"188.328417ms","start":"2026-04-08T00:36:38.364251Z","end":"2026-04-08T00:36:38.55258Z","steps":["trace[133257559] 'agreement among raft nodes before linearized reading' (duration: 188.145731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:39.164952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.129494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:36:39.16503Z","caller":"traceutil/trace.go:171","msg":"trace[1374602452] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:596; }","duration":"366.234717ms","start":"2026-04-08T00:36:38.798781Z","end":"2026-04-08T00:36:39.165015Z","steps":["trace[1374602452] 'count revisions from in-memory index tree' (duration: 366.052291ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:39.165065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:38.798743Z","time spent":"366.31356ms","remote":"127.0.0.1:49656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-04-08T00:36:39.165049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.161897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T00:36:39.165085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.267532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T00:36:39.165128Z","caller":"traceutil/trace.go:171","msg":"trace[256332669] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:596; }","duration":"332.26692ms","start":"2026-04-08T00:36:38.83284Z","end":"2026-04-08T00:36:39.165107Z","steps":["trace[256332669] 'range keys from in-memory index tree' (duration: 332.076484ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:39.165152Z","caller":"traceutil/trace.go:171","msg":"trace[236880349] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:596; }","duration":"151.391167ms","start":"2026-04-08T00:36:39.013744Z","end":"2026-04-08T00:36:39.165135Z","steps":["trace[236880349] 'range keys from in-memory index tree' (duration: 151.017525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:39.165172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:38.832832Z","time spent":"332.329242ms","remote":"127.0.0.1:49680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-04-08T00:36:39.165271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.447538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:36:39.165294Z","caller":"traceutil/trace.go:171","msg":"trace[1122664475] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:596; }","duration":"390.50368ms","start":"2026-04-08T00:36:38.774783Z","end":"2026-04-08T00:36:39.165286Z","steps":["trace[1122664475] 'range keys from in-memory index tree' (duration: 390.243832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:39.165322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:38.774764Z","time spent":"390.546331ms","remote":"127.0.0.1:49650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-08T00:36:39.165416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.493179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:36:39.165434Z","caller":"traceutil/trace.go:171","msg":"trace[225555202] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:596; }","duration":"390.527001ms","start":"2026-04-08T00:36:38.774901Z","end":"2026-04-08T00:36:39.165428Z","steps":["trace[225555202] 'count revisions from in-memory index tree' (duration: 390.350185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:39.165426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.965881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T00:36:39.165452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:38.774891Z","time spent":"390.555492ms","remote":"127.0.0.1:49650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-08T00:36:39.165456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.171333ms","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-08T00:36:39.165465Z","caller":"traceutil/trace.go:171","msg":"trace[534741230] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:596; }","duration":"332.010182ms","start":"2026-04-08T00:36:38.833441Z","end":"2026-04-08T00:36:39.165451Z","steps":["trace[534741230] 'range keys from in-memory index tree' (duration: 331.938269ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:39.16549Z","caller":"traceutil/trace.go:171","msg":"trace[36284487] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:596; }","duration":"146.228885ms","start":"2026-04-08T00:36:39.019251Z","end":"2026-04-08T00:36:39.16548Z","steps":["trace[36284487] 'range keys from in-memory index tree' (duration: 146.053089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:39.165509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:38.833435Z","time spent":"332.061614ms","remote":"127.0.0.1:49668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 "} {"level":"warn","ts":"2026-04-08T00:36:39.165542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.883417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:36:39.165561Z","caller":"traceutil/trace.go:171","msg":"trace[1554014659] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:596; }","duration":"366.95035ms","start":"2026-04-08T00:36:38.798605Z","end":"2026-04-08T00:36:39.165555Z","steps":["trace[1554014659] 'range keys from in-memory index tree' (duration: 366.781834ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:39.16558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:38.798587Z","time spent":"366.986281ms","remote":"127.0.0.1:49656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"warn","ts":"2026-04-08T00:36:39.165632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.714884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:36:39.165717Z","caller":"traceutil/trace.go:171","msg":"trace[1421766636] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:596; }","duration":"332.793077ms","start":"2026-04-08T00:36:38.832885Z","end":"2026-04-08T00:36:39.165678Z","steps":["trace[1421766636] 'count revisions from in-memory index tree' (duration: 332.683953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:39.165522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.595463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T00:36:39.165764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:38.832881Z","time spent":"332.867669ms","remote":"127.0.0.1:49680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-04-08T00:36:39.165809Z","caller":"traceutil/trace.go:171","msg":"trace[864331946] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:596; }","duration":"390.881071ms","start":"2026-04-08T00:36:38.774906Z","end":"2026-04-08T00:36:39.165787Z","steps":["trace[864331946] 'range keys from in-memory index tree' (duration: 390.458329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:39.165648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.590125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-08T00:36:39.165869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.17851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:36:39.165893Z","caller":"traceutil/trace.go:171","msg":"trace[400934288] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:596; }","duration":"348.218151ms","start":"2026-04-08T00:36:38.817667Z","end":"2026-04-08T00:36:39.165885Z","steps":["trace[400934288] 'count revisions from in-memory index tree' (duration: 348.131819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:39.165883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:38.774898Z","time spent":"390.959685ms","remote":"127.0.0.1:49650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 "} {"level":"warn","ts":"2026-04-08T00:36:39.165724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.389929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-08T00:36:39.165913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:38.817658Z","time spent":"348.249862ms","remote":"127.0.0.1:49668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-08T00:36:39.165933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.258683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:36:39.165935Z","caller":"traceutil/trace.go:171","msg":"trace[1473960191] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:596; }","duration":"145.647127ms","start":"2026-04-08T00:36:39.020273Z","end":"2026-04-08T00:36:39.165921Z","steps":["trace[1473960191] 'range keys from in-memory index tree' (duration: 145.258955ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:39.165961Z","caller":"traceutil/trace.go:171","msg":"trace[1780352907] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:596; }","duration":"348.309225ms","start":"2026-04-08T00:36:38.817644Z","end":"2026-04-08T00:36:39.165953Z","steps":["trace[1780352907] 'range keys from in-memory index tree' (duration: 348.18174ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:39.165992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.119654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T00:36:39.165992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:38.817633Z","time spent":"348.350566ms","remote":"127.0.0.1:49668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"info","ts":"2026-04-08T00:36:39.166011Z","caller":"traceutil/trace.go:171","msg":"trace[1887627870] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:596; }","duration":"366.193766ms","start":"2026-04-08T00:36:38.799811Z","end":"2026-04-08T00:36:39.166005Z","steps":["trace[1887627870] 'range keys from in-memory index tree' (duration: 366.054822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:39.166028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:38.799801Z","time spent":"366.222527ms","remote":"127.0.0.1:49656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"info","ts":"2026-04-08T00:36:39.165889Z","caller":"traceutil/trace.go:171","msg":"trace[562543817] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:596; }","duration":"145.852283ms","start":"2026-04-08T00:36:39.020024Z","end":"2026-04-08T00:36:39.165876Z","steps":["trace[562543817] 'range keys from in-memory index tree' (duration: 145.485601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:39.166112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.554904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-hg6wn\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-08T00:36:39.166139Z","caller":"traceutil/trace.go:171","msg":"trace[2101420890] range","detail":"{range_begin:/registry/pods/kube-system/cilium-hg6wn; range_end:; response_count:1; response_revision:596; }","duration":"364.638647ms","start":"2026-04-08T00:36:38.801491Z","end":"2026-04-08T00:36:39.16613Z","steps":["trace[2101420890] 'range keys from in-memory index tree' (duration: 364.39427ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:39.166208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:38.801478Z","time spent":"364.680249ms","remote":"127.0.0.1:48118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-hg6wn\" "} {"level":"info","ts":"2026-04-08T00:36:39.970195Z","caller":"traceutil/trace.go:171","msg":"trace[1052226091] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"798.484048ms","start":"2026-04-08T00:36:39.171681Z","end":"2026-04-08T00:36:39.970165Z","steps":["trace[1052226091] 'process raft request' (duration: 798.292812ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:39.970384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:39.171667Z","time spent":"798.621643ms","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:36:40.680738Z","caller":"traceutil/trace.go:171","msg":"trace[1247903649] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"1.509018985s","start":"2026-04-08T00:36:39.171702Z","end":"2026-04-08T00:36:40.680721Z","steps":["trace[1247903649] 'process raft request' (duration: 1.50888457s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:40.680909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:39.171683Z","time spent":"1.509105237s","remote":"127.0.0.1:48200","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-08T00:36:40.681057Z","caller":"traceutil/trace.go:171","msg":"trace[762737438] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"1.508803637s","start":"2026-04-08T00:36:39.172246Z","end":"2026-04-08T00:36:40.681049Z","steps":["trace[762737438] 'process raft request' (duration: 1.508451236s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:40.681106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:39.172232Z","time spent":"1.508849549s","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:36:40.681085Z","caller":"traceutil/trace.go:171","msg":"trace[1928811579] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:623; }","duration":"368.823379ms","start":"2026-04-08T00:36:40.312245Z","end":"2026-04-08T00:36:40.681068Z","steps":["trace[1928811579] 'read index received' (duration: 368.323023ms)","trace[1928811579] 'applied index is now lower than readState.Index' (duration: 499.646µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:36:40.681177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.928282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:36:40.681215Z","caller":"traceutil/trace.go:171","msg":"trace[1986612726] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:601; }","duration":"369.040716ms","start":"2026-04-08T00:36:40.312166Z","end":"2026-04-08T00:36:40.681206Z","steps":["trace[1986612726] 'agreement among raft nodes before linearized reading' (duration: 368.954733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:40.681242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:40.312143Z","time spent":"369.093757ms","remote":"127.0.0.1:47942","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-08T00:36:40.681289Z","caller":"traceutil/trace.go:171","msg":"trace[457159087] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"1.508965052s","start":"2026-04-08T00:36:39.172315Z","end":"2026-04-08T00:36:40.68128Z","steps":["trace[457159087] 'process raft request' (duration: 1.50858673s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:40.681354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:39.172308Z","time spent":"1.509013875s","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:36:40.681394Z","caller":"traceutil/trace.go:171","msg":"trace[712910658] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"1.502383676s","start":"2026-04-08T00:36:39.179002Z","end":"2026-04-08T00:36:40.681386Z","steps":["trace[712910658] 'process raft request' (duration: 1.502026904s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:40.681457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:39.17899Z","time spent":"1.502421257s","remote":"127.0.0.1:48118","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-08T00:36:42.575841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.331592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:36:42.575914Z","caller":"traceutil/trace.go:171","msg":"trace[544014467] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:610; }","duration":"247.467355ms","start":"2026-04-08T00:36:42.328429Z","end":"2026-04-08T00:36:42.575897Z","steps":["trace[544014467] 'range keys from in-memory index tree' (duration: 247.257479ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:42.854796Z","caller":"traceutil/trace.go:171","msg":"trace[692802181] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"125.820684ms","start":"2026-04-08T00:36:42.728955Z","end":"2026-04-08T00:36:42.854775Z","steps":["trace[692802181] 'process raft request' (duration: 125.562965ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:42.854946Z","caller":"traceutil/trace.go:171","msg":"trace[1953996820] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"125.916446ms","start":"2026-04-08T00:36:42.728981Z","end":"2026-04-08T00:36:42.854897Z","steps":["trace[1953996820] 'process raft request' (duration: 125.729661ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:42.982934Z","caller":"traceutil/trace.go:171","msg":"trace[1891664362] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"114.054624ms","start":"2026-04-08T00:36:42.868853Z","end":"2026-04-08T00:36:42.982908Z","steps":["trace[1891664362] 'process raft request' (duration: 87.380885ms)","trace[1891664362] 'compare' (duration: 26.555305ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:36:57.082409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.6198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:36:57.082501Z","caller":"traceutil/trace.go:171","msg":"trace[1789519375] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:748; }","duration":"769.764426ms","start":"2026-04-08T00:36:56.312717Z","end":"2026-04-08T00:36:57.082481Z","steps":["trace[1789519375] 'range keys from in-memory index tree' (duration: 769.536888ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:57.082663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.016749326s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-04-08T00:36:57.08254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:56.312697Z","time spent":"769.834097ms","remote":"127.0.0.1:47942","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-08T00:36:57.082744Z","caller":"traceutil/trace.go:171","msg":"trace[2020043643] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:748; }","duration":"1.016868069s","start":"2026-04-08T00:36:56.065858Z","end":"2026-04-08T00:36:57.082726Z","steps":["trace[2020043643] 'range keys from in-memory index tree' (duration: 1.016501958s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:57.08303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.927627ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:36:57.083132Z","caller":"traceutil/trace.go:171","msg":"trace[1772349935] linearizableReadLoop","detail":"{readStateIndex:779; appliedIndex:778; }","duration":"318.758336ms","start":"2026-04-08T00:36:56.764355Z","end":"2026-04-08T00:36:57.083113Z","steps":["trace[1772349935] 'read index received' (duration: 129.456637ms)","trace[1772349935] 'applied index is now lower than readState.Index' (duration: 189.300188ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:36:57.083197Z","caller":"traceutil/trace.go:171","msg":"trace[494386041] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"609.092018ms","start":"2026-04-08T00:36:56.474073Z","end":"2026-04-08T00:36:57.083165Z","steps":["trace[494386041] 'process raft request' (duration: 419.740339ms)","trace[494386041] 'compare' (duration: 188.571995ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:36:57.083235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.88896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T00:36:57.083279Z","caller":"traceutil/trace.go:171","msg":"trace[1477429509] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:749; }","duration":"318.981872ms","start":"2026-04-08T00:36:56.764287Z","end":"2026-04-08T00:36:57.083269Z","steps":["trace[1477429509] 'agreement among raft nodes before linearized reading' (duration: 318.91082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:57.083315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:56.764263Z","time spent":"319.043135ms","remote":"127.0.0.1:48102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-04-08T00:36:57.083103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:56.065797Z","time spent":"1.017291953s","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-08T00:36:57.08336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:56.474009Z","time spent":"609.263324ms","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:36:57.175425Z","caller":"traceutil/trace.go:171","msg":"trace[1839309500] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"234.742936ms","start":"2026-04-08T00:36:56.940648Z","end":"2026-04-08T00:36:57.175391Z","steps":["trace[1839309500] 'process raft request' (duration: 234.54956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:57.175537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.445403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T00:36:57.175637Z","caller":"traceutil/trace.go:171","msg":"trace[761110591] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:750; }","duration":"224.564276ms","start":"2026-04-08T00:36:56.951039Z","end":"2026-04-08T00:36:57.175603Z","steps":["trace[761110591] 'agreement among raft nodes before linearized reading' (duration: 224.328778ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:57.17575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.258427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-5jtjb\" ","response":"range_response_count:1 size:5047"} {"level":"info","ts":"2026-04-08T00:36:57.175948Z","caller":"traceutil/trace.go:171","msg":"trace[1502838561] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-5jtjb; range_end:; response_count:1; response_revision:750; }","duration":"260.569007ms","start":"2026-04-08T00:36:56.915357Z","end":"2026-04-08T00:36:57.175926Z","steps":["trace[1502838561] 'agreement among raft nodes before linearized reading' (duration: 260.02811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:57.175719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.816878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-08T00:36:57.175798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.682165ms","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-08T00:36:57.176097Z","caller":"traceutil/trace.go:171","msg":"trace[1276001169] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:750; }","duration":"369.267922ms","start":"2026-04-08T00:36:56.806804Z","end":"2026-04-08T00:36:57.176072Z","steps":["trace[1276001169] 'agreement among raft nodes before linearized reading' (duration: 368.624352ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:57.176238Z","caller":"traceutil/trace.go:171","msg":"trace[118553293] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:750; }","duration":"229.060887ms","start":"2026-04-08T00:36:56.947086Z","end":"2026-04-08T00:36:57.176147Z","steps":["trace[118553293] 'agreement among raft nodes before linearized reading' (duration: 228.417577ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:57.176294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:56.806785Z","time spent":"369.49333ms","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-08T00:36:57.224859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.569127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:36:57.224941Z","caller":"traceutil/trace.go:171","msg":"trace[434507246] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:751; }","duration":"140.687ms","start":"2026-04-08T00:36:57.084237Z","end":"2026-04-08T00:36:57.224924Z","steps":["trace[434507246] 'agreement among raft nodes before linearized reading' (duration: 140.550206ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:57.225059Z","caller":"traceutil/trace.go:171","msg":"trace[900908482] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"137.456248ms","start":"2026-04-08T00:36:57.087566Z","end":"2026-04-08T00:36:57.225022Z","steps":["trace[900908482] 'process raft request' (duration: 133.225205ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:37:11.390185Z","caller":"traceutil/trace.go:171","msg":"trace[319891482] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"105.216448ms","start":"2026-04-08T00:37:11.284947Z","end":"2026-04-08T00:37:11.390163Z","steps":["trace[319891482] 'process raft request' (duration: 104.643051ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:37:11.500054Z","caller":"traceutil/trace.go:171","msg":"trace[788421774] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"103.280806ms","start":"2026-04-08T00:37:11.396746Z","end":"2026-04-08T00:37:11.500026Z","steps":["trace[788421774] 'process raft request' (duration: 103.203853ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:37:11.500099Z","caller":"traceutil/trace.go:171","msg":"trace[1694736201] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"105.872039ms","start":"2026-04-08T00:37:11.394201Z","end":"2026-04-08T00:37:11.500073Z","steps":["trace[1694736201] 'process raft request' (duration: 88.49173ms)","trace[1694736201] 'compare' (duration: 17.035882ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:37:11.500075Z","caller":"traceutil/trace.go:171","msg":"trace[170204612] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"105.109573ms","start":"2026-04-08T00:37:11.39494Z","end":"2026-04-08T00:37:11.50005Z","steps":["trace[170204612] 'process raft request' (duration: 104.917704ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:37:19.619089Z","caller":"traceutil/trace.go:171","msg":"trace[192386483] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"129.435469ms","start":"2026-04-08T00:37:19.489612Z","end":"2026-04-08T00:37:19.619048Z","steps":["trace[192386483] 'process raft request' (duration: 124.456414ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:37:24.255412Z","caller":"traceutil/trace.go:171","msg":"trace[1128159731] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"123.885378ms","start":"2026-04-08T00:37:24.131504Z","end":"2026-04-08T00:37:24.255389Z","steps":["trace[1128159731] 'process raft request' (duration: 123.71019ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:37:25.871472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.225363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-08T00:37:25.871507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.213273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T00:37:25.871568Z","caller":"traceutil/trace.go:171","msg":"trace[500534435] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:944; }","duration":"178.356627ms","start":"2026-04-08T00:37:25.693191Z","end":"2026-04-08T00:37:25.871547Z","steps":["trace[500534435] 'range keys from in-memory index tree' (duration: 178.075688ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:37:25.871597Z","caller":"traceutil/trace.go:171","msg":"trace[7097303] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:944; }","duration":"175.330457ms","start":"2026-04-08T00:37:25.696244Z","end":"2026-04-08T00:37:25.871575Z","steps":["trace[7097303] 'range keys from in-memory index tree' (duration: 175.069929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:37:25.871617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.39423ms","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-08T00:37:25.871548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.492723ms","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-08T00:37:25.871643Z","caller":"traceutil/trace.go:171","msg":"trace[2018776435] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:944; }","duration":"198.41681ms","start":"2026-04-08T00:37:25.673218Z","end":"2026-04-08T00:37:25.871635Z","steps":["trace[2018776435] 'range keys from in-memory index tree' (duration: 198.309526ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:37:25.871701Z","caller":"traceutil/trace.go:171","msg":"trace[1836567036] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:944; }","duration":"136.684519ms","start":"2026-04-08T00:37:25.734977Z","end":"2026-04-08T00:37:25.871661Z","steps":["trace[1836567036] 'range keys from in-memory index tree' (duration: 136.383239ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:37:26.024863Z","caller":"traceutil/trace.go:171","msg":"trace[1919857668] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"148.956085ms","start":"2026-04-08T00:37:25.875887Z","end":"2026-04-08T00:37:26.024843Z","steps":["trace[1919857668] 'process raft request' (duration: 148.852471ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:37:26.024933Z","caller":"traceutil/trace.go:171","msg":"trace[193997287] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"149.123261ms","start":"2026-04-08T00:37:25.875773Z","end":"2026-04-08T00:37:26.024896Z","steps":["trace[193997287] 'process raft request' (duration: 112.333064ms)","trace[193997287] 'compare' (duration: 36.516408ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:37:26.025117Z","caller":"traceutil/trace.go:171","msg":"trace[1031201106] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"149.12565ms","start":"2026-04-08T00:37:25.87596Z","end":"2026-04-08T00:37:26.025085Z","steps":["trace[1031201106] 'process raft request' (duration: 148.850371ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:37:32.876935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:37:32.55491Z","time spent":"322.015246ms","remote":"127.0.0.1:47976","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-08T00:37:33.215996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.707407ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:37:33.216092Z","caller":"traceutil/trace.go:171","msg":"trace[1907892843] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"337.674755ms","start":"2026-04-08T00:37:32.878399Z","end":"2026-04-08T00:37:33.216074Z","steps":["trace[1907892843] 'process raft request' (duration: 165.799672ms)","trace[1907892843] 'compare' (duration: 171.310824ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:37:33.21615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:37:32.878377Z","time spent":"337.746167ms","remote":"127.0.0.1:47976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:37:33.838335Z","caller":"traceutil/trace.go:171","msg":"trace[2144942010] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"200.007012ms","start":"2026-04-08T00:37:33.638306Z","end":"2026-04-08T00:37:33.838313Z","steps":["trace[2144942010] 'process raft request' (duration: 199.839897ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:37:34.810992Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439248605600348,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T00:37:35.311735Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439248605600348,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T00:37:35.413668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.403132482s","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-08T00:37:35.413807Z","caller":"traceutil/trace.go:171","msg":"trace[1775322131] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:975; }","duration":"1.403247616s","start":"2026-04-08T00:37:34.010496Z","end":"2026-04-08T00:37:35.413743Z","steps":["trace[1775322131] 'range keys from in-memory index tree' (duration: 1.402928734s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:37:35.413876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:37:34.010441Z","time spent":"1.403418762s","remote":"127.0.0.1:48200","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-08T00:37:35.414039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.247154934s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-08T00:37:35.414018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.247067892s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-08T00:37:35.414074Z","caller":"traceutil/trace.go:171","msg":"trace[1565957753] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:975; }","duration":"1.247204756s","start":"2026-04-08T00:37:34.16686Z","end":"2026-04-08T00:37:35.414065Z","steps":["trace[1565957753] 'range keys from in-memory index tree' (duration: 1.247070962s)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:37:35.414091Z","caller":"traceutil/trace.go:171","msg":"trace[110964066] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:975; }","duration":"1.247164275s","start":"2026-04-08T00:37:34.166909Z","end":"2026-04-08T00:37:35.414073Z","steps":["trace[110964066] 'range keys from in-memory index tree' (duration: 1.246986249s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:37:35.414129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:37:34.166899Z","time spent":"1.247222427s","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-08T00:37:35.414134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:37:34.166853Z","time spent":"1.247240547s","remote":"127.0.0.1:48200","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-08T00:37:35.414346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.247747334s","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-08T00:37:35.414411Z","caller":"traceutil/trace.go:171","msg":"trace[1524886994] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:975; }","duration":"1.248685085s","start":"2026-04-08T00:37:34.165684Z","end":"2026-04-08T00:37:35.414369Z","steps":["trace[1524886994] 'range keys from in-memory index tree' (duration: 1.247587009s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:37:35.414443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:37:34.165668Z","time spent":"1.248767768s","remote":"127.0.0.1:48200","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-08T00:37:35.812303Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439248605600348,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T00:37:36.157628Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.863750639s","expected-duration":"1s"} {"level":"info","ts":"2026-04-08T00:37:36.158018Z","caller":"traceutil/trace.go:171","msg":"trace[457902862] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"1.864310359s","start":"2026-04-08T00:37:34.293681Z","end":"2026-04-08T00:37:36.157992Z","steps":["trace[457902862] 'process raft request' (duration: 1.864160144s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:37:36.15823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:37:34.293645Z","time spent":"1.864445743s","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:37:36.194957Z","caller":"traceutil/trace.go:171","msg":"trace[1179549761] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"776.252884ms","start":"2026-04-08T00:37:35.418679Z","end":"2026-04-08T00:37:36.194932Z","steps":["trace[1179549761] 'process raft request' (duration: 776.086028ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:37:36.195117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:37:35.418657Z","time spent":"776.366288ms","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:37:36.195172Z","caller":"traceutil/trace.go:171","msg":"trace[1472316936] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"776.378358ms","start":"2026-04-08T00:37:35.418773Z","end":"2026-04-08T00:37:36.195151Z","steps":["trace[1472316936] 'process raft request' (duration: 776.11838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:37:36.195269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:37:35.418766Z","time spent":"776.45198ms","remote":"127.0.0.1:48200","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-08T00:37:36.195255Z","caller":"traceutil/trace.go:171","msg":"trace[1162563654] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"776.402199ms","start":"2026-04-08T00:37:35.418826Z","end":"2026-04-08T00:37:36.195229Z","steps":["trace[1162563654] 'process raft request' (duration: 776.262684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:37:36.195375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.884748554s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:37:36.19538Z","caller":"traceutil/trace.go:171","msg":"trace[1512600671] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"775.787388ms","start":"2026-04-08T00:37:35.419582Z","end":"2026-04-08T00:37:36.195369Z","steps":["trace[1512600671] 'process raft request' (duration: 775.601442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:37:36.195408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:37:35.41882Z","time spent":"776.500893ms","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:37:36.195418Z","caller":"traceutil/trace.go:171","msg":"trace[1262021019] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:980; }","duration":"1.884844978s","start":"2026-04-08T00:37:34.310563Z","end":"2026-04-08T00:37:36.195408Z","steps":["trace[1262021019] 'agreement among raft nodes before linearized reading' (duration: 1.884746244s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:37:36.195461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:37:34.310537Z","time spent":"1.88491645s","remote":"127.0.0.1:47942","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-08T00:37:36.195449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:37:35.419571Z","time spent":"775.845761ms","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:37:36.195249Z","caller":"traceutil/trace.go:171","msg":"trace[246474508] linearizableReadLoop","detail":"{readStateIndex:1020; appliedIndex:1015; }","duration":"1.88460224s","start":"2026-04-08T00:37:34.310621Z","end":"2026-04-08T00:37:36.195223Z","steps":["trace[246474508] 'read index received' (duration: 1.847249345s)","trace[246474508] 'applied index is now lower than readState.Index' (duration: 37.351904ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:37:36.195644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"929.142489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:37:36.195683Z","caller":"traceutil/trace.go:171","msg":"trace[138829275] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:980; }","duration":"929.195ms","start":"2026-04-08T00:37:35.266478Z","end":"2026-04-08T00:37:36.195673Z","steps":["trace[138829275] 'agreement among raft nodes before linearized reading' (duration: 929.111708ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:37:36.195721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:37:35.266421Z","time spent":"929.288794ms","remote":"127.0.0.1:48148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-04-08T00:37:36.195759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.236349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-08T00:37:36.195642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.550006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:37:36.195803Z","caller":"traceutil/trace.go:171","msg":"trace[844098199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:980; }","duration":"353.308201ms","start":"2026-04-08T00:37:35.84248Z","end":"2026-04-08T00:37:36.195788Z","steps":["trace[844098199] 'agreement among raft nodes before linearized reading' (duration: 353.187987ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:37:36.195855Z","caller":"traceutil/trace.go:171","msg":"trace[1717567856] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:980; }","duration":"669.777273ms","start":"2026-04-08T00:37:35.526041Z","end":"2026-04-08T00:37:36.195819Z","steps":["trace[1717567856] 'agreement among raft nodes before linearized reading' (duration: 669.565646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:37:36.195887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:37:35.526024Z","time spent":"669.854766ms","remote":"127.0.0.1:48394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-04-08T00:37:36.19588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.396946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-08T00:37:36.195894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:37:35.842461Z","time spent":"353.420935ms","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-08T00:37:36.195922Z","caller":"traceutil/trace.go:171","msg":"trace[1144908936] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:980; }","duration":"824.479748ms","start":"2026-04-08T00:37:35.37143Z","end":"2026-04-08T00:37:36.19591Z","steps":["trace[1144908936] 'agreement among raft nodes before linearized reading' (duration: 824.340924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:37:36.196019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:37:35.371405Z","time spent":"824.545151ms","remote":"127.0.0.1:48272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":68,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"info","ts":"2026-04-08T00:37:36.333761Z","caller":"traceutil/trace.go:171","msg":"trace[470063480] linearizableReadLoop","detail":"{readStateIndex:1021; appliedIndex:1020; }","duration":"128.315093ms","start":"2026-04-08T00:37:36.205426Z","end":"2026-04-08T00:37:36.333741Z","steps":["trace[470063480] 'read index received' (duration: 74.205283ms)","trace[470063480] 'applied index is now lower than readState.Index' (duration: 54.10914ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:37:36.333817Z","caller":"traceutil/trace.go:171","msg":"trace[860611450] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"133.664339ms","start":"2026-04-08T00:37:36.200123Z","end":"2026-04-08T00:37:36.333788Z","steps":["trace[860611450] 'process raft request' (duration: 79.585941ms)","trace[860611450] 'compare' (duration: 53.958154ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:37:36.33386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.416726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:37:36.333885Z","caller":"traceutil/trace.go:171","msg":"trace[1220229671] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:981; }","duration":"128.467298ms","start":"2026-04-08T00:37:36.20541Z","end":"2026-04-08T00:37:36.333877Z","steps":["trace[1220229671] 'agreement among raft nodes before linearized reading' (duration: 128.410796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:37:42.393059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.518697ms","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-08T00:37:42.393146Z","caller":"traceutil/trace.go:171","msg":"trace[95370519] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:997; }","duration":"101.653421ms","start":"2026-04-08T00:37:42.291476Z","end":"2026-04-08T00:37:42.393129Z","steps":["trace[95370519] 'range keys from in-memory index tree' (duration: 101.288699ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:37:42.836094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.374506ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:37:42.836297Z","caller":"traceutil/trace.go:171","msg":"trace[866321698] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"438.348193ms","start":"2026-04-08T00:37:42.397937Z","end":"2026-04-08T00:37:42.836286Z","steps":["trace[866321698] 'process raft request' (duration: 315.734089ms)","trace[866321698] 'compare' (duration: 122.256852ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:37:42.836291Z","caller":"traceutil/trace.go:171","msg":"trace[1893313880] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"437.995172ms","start":"2026-04-08T00:37:42.39827Z","end":"2026-04-08T00:37:42.836265Z","steps":["trace[1893313880] 'process raft request' (duration: 437.93769ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:37:42.836348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:37:42.397923Z","time spent":"438.401655ms","remote":"127.0.0.1:48200","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-08T00:37:42.836412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:37:42.398259Z","time spent":"438.084224ms","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:37:42.836247Z","caller":"traceutil/trace.go:171","msg":"trace[91309507] linearizableReadLoop","detail":"{readStateIndex:1042; appliedIndex:1039; }","duration":"281.736095ms","start":"2026-04-08T00:37:42.554498Z","end":"2026-04-08T00:37:42.836234Z","steps":["trace[91309507] 'read index received' (duration: 159.127511ms)","trace[91309507] 'applied index is now lower than readState.Index' (duration: 122.607813ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:37:42.8365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.993954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.153\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-08T00:37:42.836545Z","caller":"traceutil/trace.go:171","msg":"trace[397906619] range","detail":"{range_begin:/registry/masterleases/199.204.45.153; range_end:; response_count:1; response_revision:1001; }","duration":"282.077416ms","start":"2026-04-08T00:37:42.554454Z","end":"2026-04-08T00:37:42.836532Z","steps":["trace[397906619] 'agreement among raft nodes before linearized reading' (duration: 281.943142ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:37:42.836716Z","caller":"traceutil/trace.go:171","msg":"trace[150694207] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"438.693104ms","start":"2026-04-08T00:37:42.39801Z","end":"2026-04-08T00:37:42.836704Z","steps":["trace[150694207] 'process raft request' (duration: 438.160576ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:37:42.836854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:37:42.397991Z","time spent":"438.830149ms","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:37:42.998869Z","caller":"traceutil/trace.go:171","msg":"trace[1853488006] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"118.658902ms","start":"2026-04-08T00:37:42.880153Z","end":"2026-04-08T00:37:42.998812Z","steps":["trace[1853488006] 'process raft request' (duration: 67.737729ms)","trace[1853488006] 'compare' (duration: 50.801619ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:37:46.627461Z","caller":"traceutil/trace.go:171","msg":"trace[2044828543] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"110.354058ms","start":"2026-04-08T00:37:46.517083Z","end":"2026-04-08T00:37:46.627437Z","steps":["trace[2044828543] 'process raft request' (duration: 110.170752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:37:59.425891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.489336ms","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-08T00:37:59.425966Z","caller":"traceutil/trace.go:171","msg":"trace[692979521] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1051; }","duration":"473.6185ms","start":"2026-04-08T00:37:58.95233Z","end":"2026-04-08T00:37:59.425949Z","steps":["trace[692979521] 'range keys from in-memory index tree' (duration: 473.317361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:37:59.426018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:37:58.952304Z","time spent":"473.704963ms","remote":"127.0.0.1:48200","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-08T00:37:59.426416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.800788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-08T00:37:59.426529Z","caller":"traceutil/trace.go:171","msg":"trace[1140447505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1051; }","duration":"467.961484ms","start":"2026-04-08T00:37:58.958543Z","end":"2026-04-08T00:37:59.426504Z","steps":["trace[1140447505] 'range keys from in-memory index tree' (duration: 467.626393ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:37:59.426428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.218729ms","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-08T00:37:59.426593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:37:58.958527Z","time spent":"468.049216ms","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-08T00:37:59.426638Z","caller":"traceutil/trace.go:171","msg":"trace[1140940479] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1051; }","duration":"467.477577ms","start":"2026-04-08T00:37:58.959143Z","end":"2026-04-08T00:37:59.42662Z","steps":["trace[1140940479] 'range keys from in-memory index tree' (duration: 467.034563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:37:59.426697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:37:58.959124Z","time spent":"467.561571ms","remote":"127.0.0.1:48200","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-08T00:38:00.04256Z","caller":"traceutil/trace.go:171","msg":"trace[937012775] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"612.637149ms","start":"2026-04-08T00:37:59.429897Z","end":"2026-04-08T00:38:00.042534Z","steps":["trace[937012775] 'process raft request' (duration: 612.460793ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:38:00.04272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:37:59.429876Z","time spent":"612.761703ms","remote":"127.0.0.1:48200","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-08T00:38:00.202316Z","caller":"traceutil/trace.go:171","msg":"trace[53162065] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"771.798221ms","start":"2026-04-08T00:37:59.430497Z","end":"2026-04-08T00:38:00.202295Z","steps":["trace[53162065] 'process raft request' (duration: 708.225629ms)","trace[53162065] 'compare' (duration: 63.410746ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:38:00.202427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:37:59.430476Z","time spent":"771.894614ms","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:38:00.202519Z","caller":"traceutil/trace.go:171","msg":"trace[65700824] linearizableReadLoop","detail":"{readStateIndex:1099; appliedIndex:1096; }","duration":"187.905292ms","start":"2026-04-08T00:38:00.014592Z","end":"2026-04-08T00:38:00.202498Z","steps":["trace[65700824] 'read index received' (duration: 27.865721ms)","trace[65700824] 'applied index is now lower than readState.Index' (duration: 160.038011ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:38:00.202555Z","caller":"traceutil/trace.go:171","msg":"trace[1750119256] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"770.231989ms","start":"2026-04-08T00:37:59.432315Z","end":"2026-04-08T00:38:00.202547Z","steps":["trace[1750119256] 'process raft request' (duration: 769.94538ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:38:00.202611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.019957ms","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-08T00:38:00.202634Z","caller":"traceutil/trace.go:171","msg":"trace[148716216] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1054; }","duration":"188.081509ms","start":"2026-04-08T00:38:00.014546Z","end":"2026-04-08T00:38:00.202627Z","steps":["trace[148716216] 'agreement among raft nodes before linearized reading' (duration: 188.041897ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:38:00.202669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:37:59.4323Z","time spent":"770.28753ms","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:38:02.154353Z","caller":"traceutil/trace.go:171","msg":"trace[946334738] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"102.908942ms","start":"2026-04-08T00:38:02.051408Z","end":"2026-04-08T00:38:02.154317Z","steps":["trace[946334738] 'process raft request' (duration: 102.688345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:38:02.41721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.276294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:38:02.417266Z","caller":"traceutil/trace.go:171","msg":"trace[740231098] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1061; }","duration":"106.376767ms","start":"2026-04-08T00:38:02.310875Z","end":"2026-04-08T00:38:02.417252Z","steps":["trace[740231098] 'range keys from in-memory index tree' (duration: 106.186131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:38:04.832882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.42262ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:38:04.832978Z","caller":"traceutil/trace.go:171","msg":"trace[324967512] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"512.680055ms","start":"2026-04-08T00:38:04.32028Z","end":"2026-04-08T00:38:04.83296Z","steps":["trace[324967512] 'process raft request' (duration: 184.09467ms)","trace[324967512] 'compare' (duration: 328.280475ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:38:04.833034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:38:04.320255Z","time spent":"512.752417ms","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:38:04.991103Z","caller":"traceutil/trace.go:171","msg":"trace[117348657] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"462.805421ms","start":"2026-04-08T00:38:04.528271Z","end":"2026-04-08T00:38:04.991077Z","steps":["trace[117348657] 'process raft request' (duration: 441.609352ms)","trace[117348657] 'compare' (duration: 20.93243ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:38:04.991299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:38:04.52825Z","time spent":"462.953066ms","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:38:04.991459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.611247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-08T00:38:04.991566Z","caller":"traceutil/trace.go:171","msg":"trace[2081616043] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1069; }","duration":"278.767302ms","start":"2026-04-08T00:38:04.712772Z","end":"2026-04-08T00:38:04.991539Z","steps":["trace[2081616043] 'agreement among raft nodes before linearized reading' (duration: 278.455632ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:38:04.990987Z","caller":"traceutil/trace.go:171","msg":"trace[1251750565] linearizableReadLoop","detail":"{readStateIndex:1115; appliedIndex:1114; }","duration":"278.146582ms","start":"2026-04-08T00:38:04.712813Z","end":"2026-04-08T00:38:04.99096Z","steps":["trace[1251750565] 'read index received' (duration: 257.037276ms)","trace[1251750565] 'applied index is now lower than readState.Index' (duration: 21.107766ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:38:05.276647Z","caller":"traceutil/trace.go:171","msg":"trace[617807896] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"280.752608ms","start":"2026-04-08T00:38:04.995861Z","end":"2026-04-08T00:38:05.276614Z","steps":["trace[617807896] 'process raft request' (duration: 264.13141ms)","trace[617807896] 'compare' (duration: 16.452582ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:38:15.482591Z","caller":"traceutil/trace.go:171","msg":"trace[1672361852] linearizableReadLoop","detail":"{readStateIndex:1152; appliedIndex:1151; }","duration":"109.363476ms","start":"2026-04-08T00:38:15.373204Z","end":"2026-04-08T00:38:15.482568Z","steps":["trace[1672361852] 'read index received' (duration: 27.197406ms)","trace[1672361852] 'applied index is now lower than readState.Index' (duration: 82.16501ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:38:15.482663Z","caller":"traceutil/trace.go:171","msg":"trace[1958864869] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"160.108939ms","start":"2026-04-08T00:38:15.322533Z","end":"2026-04-08T00:38:15.482642Z","steps":["trace[1958864869] 'process raft request' (duration: 77.970521ms)","trace[1958864869] 'compare' (duration: 81.959562ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:38:15.482728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.4978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T00:38:15.48278Z","caller":"traceutil/trace.go:171","msg":"trace[2094682331] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1104; }","duration":"109.608835ms","start":"2026-04-08T00:38:15.37316Z","end":"2026-04-08T00:38:15.482769Z","steps":["trace[2094682331] 'agreement among raft nodes before linearized reading' (duration: 109.507701ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:38:15.688474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.107583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ceph-csi-rbd-nodeplugin\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:38:15.688582Z","caller":"traceutil/trace.go:171","msg":"trace[1308577050] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ceph-csi-rbd-nodeplugin; range_end:; response_count:0; response_revision:1104; }","duration":"104.286038ms","start":"2026-04-08T00:38:15.584268Z","end":"2026-04-08T00:38:15.688554Z","steps":["trace[1308577050] 'range keys from in-memory index tree' (duration: 103.950727ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:38:19.012674Z","caller":"traceutil/trace.go:171","msg":"trace[1976196005] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"103.552024ms","start":"2026-04-08T00:38:18.909098Z","end":"2026-04-08T00:38:19.01265Z","steps":["trace[1976196005] 'process raft request' (duration: 103.356958ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:38:19.686863Z","caller":"traceutil/trace.go:171","msg":"trace[2009048594] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"188.250168ms","start":"2026-04-08T00:38:19.498589Z","end":"2026-04-08T00:38:19.686839Z","steps":["trace[2009048594] 'process raft request' (duration: 188.111503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:38:20.810192Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439248605600825,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T00:38:21.310321Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439248605600825,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T00:38:21.412139Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.159053911s","expected-duration":"1s"} {"level":"info","ts":"2026-04-08T00:38:21.412532Z","caller":"traceutil/trace.go:171","msg":"trace[521188094] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"1.159534816s","start":"2026-04-08T00:38:20.252974Z","end":"2026-04-08T00:38:21.412509Z","steps":["trace[521188094] 'process raft request' (duration: 1.159400561s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:38:21.412713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:38:20.252949Z","time spent":"1.159686681s","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:38:22.310087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000092115s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-08T00:38:22.310188Z","caller":"traceutil/trace.go:171","msg":"trace[1287901695] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000214199s","start":"2026-04-08T00:38:20.309953Z","end":"2026-04-08T00:38:22.310168Z","steps":["trace[1287901695] 'agreement among raft nodes before linearized reading' (duration: 2.000089034s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:38:22.310238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:38:20.309902Z","time spent":"2.000326482s","remote":"127.0.0.1:47942","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-08T00:38:22.435543Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.000248023s","expected-duration":"1s"} {"level":"info","ts":"2026-04-08T00:38:22.435894Z","caller":"traceutil/trace.go:171","msg":"trace[907365522] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"1.479530317s","start":"2026-04-08T00:38:20.956336Z","end":"2026-04-08T00:38:22.435866Z","steps":["trace[907365522] 'process raft request' (duration: 1.479345861s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:38:22.435982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:38:20.956311Z","time spent":"1.479611239s","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:38:22.436083Z","caller":"traceutil/trace.go:171","msg":"trace[1375843558] linearizableReadLoop","detail":"{readStateIndex:1231; appliedIndex:1228; }","duration":"2.126117018s","start":"2026-04-08T00:38:20.30996Z","end":"2026-04-08T00:38:22.436077Z","steps":["trace[1375843558] 'read index received' (duration: 1.102400382s)","trace[1375843558] 'applied index is now lower than readState.Index' (duration: 1.023715276s)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:38:22.436157Z","caller":"traceutil/trace.go:171","msg":"trace[798383246] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"1.39046322s","start":"2026-04-08T00:38:21.045662Z","end":"2026-04-08T00:38:22.436126Z","steps":["trace[798383246] 'process raft request' (duration: 1.390139159s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:38:22.436211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.028100356s","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-08T00:38:22.436237Z","caller":"traceutil/trace.go:171","msg":"trace[504626367] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1180; }","duration":"2.028154398s","start":"2026-04-08T00:38:20.408075Z","end":"2026-04-08T00:38:22.436229Z","steps":["trace[504626367] 'agreement among raft nodes before linearized reading' (duration: 2.028045185s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:38:22.436255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:38:20.408062Z","time spent":"2.028188929s","remote":"127.0.0.1:48200","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-08T00:38:22.436341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.184052573s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-08T00:38:22.436358Z","caller":"traceutil/trace.go:171","msg":"trace[942617098] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1180; }","duration":"1.184105075s","start":"2026-04-08T00:38:21.252248Z","end":"2026-04-08T00:38:22.436353Z","steps":["trace[942617098] 'agreement among raft nodes before linearized reading' (duration: 1.184049354s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:38:22.43634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:38:21.045641Z","time spent":"1.390589164s","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:38:22.436373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:38:21.252229Z","time spent":"1.184140196s","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-08T00:38:22.436372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.774831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T00:38:22.436402Z","caller":"traceutil/trace.go:171","msg":"trace[378434345] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1180; }","duration":"745.832593ms","start":"2026-04-08T00:38:21.690561Z","end":"2026-04-08T00:38:22.436394Z","steps":["trace[378434345] 'agreement among raft nodes before linearized reading' (duration: 745.718019ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:38:22.436422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:38:21.69055Z","time spent":"745.867115ms","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-08T00:38:22.436452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.419655593s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-08T00:38:22.436471Z","caller":"traceutil/trace.go:171","msg":"trace[1298668084] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1180; }","duration":"1.419708714s","start":"2026-04-08T00:38:21.016756Z","end":"2026-04-08T00:38:22.436465Z","steps":["trace[1298668084] 'agreement among raft nodes before linearized reading' (duration: 1.419656262s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:38:22.436487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:38:21.016739Z","time spent":"1.419744186s","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-08T00:38:22.436455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.398931109s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:38:22.436537Z","caller":"traceutil/trace.go:171","msg":"trace[125746045] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1180; }","duration":"1.399053183s","start":"2026-04-08T00:38:21.03746Z","end":"2026-04-08T00:38:22.436513Z","steps":["trace[125746045] 'agreement among raft nodes before linearized reading' (duration: 1.398922658s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:38:22.436492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.575149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:38:22.436588Z","caller":"traceutil/trace.go:171","msg":"trace[773151231] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1180; }","duration":"380.712463ms","start":"2026-04-08T00:38:22.055864Z","end":"2026-04-08T00:38:22.436577Z","steps":["trace[773151231] 'agreement among raft nodes before linearized reading' (duration: 380.603569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:38:22.436585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:38:21.03744Z","time spent":"1.399134036s","remote":"127.0.0.1:40934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-08T00:38:22.436615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:38:22.055815Z","time spent":"380.790626ms","remote":"127.0.0.1:49650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-08T00:38:22.436488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.630172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:38:22.436722Z","caller":"traceutil/trace.go:171","msg":"trace[372512934] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1180; }","duration":"125.88992ms","start":"2026-04-08T00:38:22.310814Z","end":"2026-04-08T00:38:22.436704Z","steps":["trace[372512934] 'agreement among raft nodes before linearized reading' (duration: 125.646312ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:38:22.576731Z","caller":"traceutil/trace.go:171","msg":"trace[2065835421] linearizableReadLoop","detail":"{readStateIndex:1236; appliedIndex:1231; }","duration":"131.749104ms","start":"2026-04-08T00:38:22.44497Z","end":"2026-04-08T00:38:22.57672Z","steps":["trace[2065835421] 'read index received' (duration: 40.741603ms)","trace[2065835421] 'applied index is now lower than readState.Index' (duration: 91.006821ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:38:22.576749Z","caller":"traceutil/trace.go:171","msg":"trace[33563665] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"136.883563ms","start":"2026-04-08T00:38:22.439847Z","end":"2026-04-08T00:38:22.57673Z","steps":["trace[33563665] 'process raft request' (duration: 136.795491ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:38:22.576805Z","caller":"traceutil/trace.go:171","msg":"trace[611343196] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"136.45045ms","start":"2026-04-08T00:38:22.440349Z","end":"2026-04-08T00:38:22.5768Z","steps":["trace[611343196] 'process raft request' (duration: 136.331066ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:38:22.576891Z","caller":"traceutil/trace.go:171","msg":"trace[904349744] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"137.201454ms","start":"2026-04-08T00:38:22.439676Z","end":"2026-04-08T00:38:22.576878Z","steps":["trace[904349744] 'process raft request' (duration: 52.912455ms)","trace[904349744] 'compare' (duration: 83.966789ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:38:22.576932Z","caller":"traceutil/trace.go:171","msg":"trace[1767393249] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"136.387417ms","start":"2026-04-08T00:38:22.44054Z","end":"2026-04-08T00:38:22.576927Z","steps":["trace[1767393249] 'process raft request' (duration: 136.160339ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:38:22.577023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.051305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:38:22.577038Z","caller":"traceutil/trace.go:171","msg":"trace[1310877048] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1185; }","duration":"132.099336ms","start":"2026-04-08T00:38:22.444935Z","end":"2026-04-08T00:38:22.577034Z","steps":["trace[1310877048] 'agreement among raft nodes before linearized reading' (duration: 132.050434ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:38:22.77551Z","caller":"traceutil/trace.go:171","msg":"trace[242399851] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"191.291628ms","start":"2026-04-08T00:38:22.584196Z","end":"2026-04-08T00:38:22.775488Z","steps":["trace[242399851] 'process raft request' (duration: 177.791153ms)","trace[242399851] 'compare' (duration: 13.381371ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:38:24.743948Z","caller":"traceutil/trace.go:171","msg":"trace[1885527491] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"156.913184ms","start":"2026-04-08T00:38:24.587011Z","end":"2026-04-08T00:38:24.743924Z","steps":["trace[1885527491] 'process raft request' (duration: 156.7968ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:38:24.743982Z","caller":"traceutil/trace.go:171","msg":"trace[251758583] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"159.80993ms","start":"2026-04-08T00:38:24.584131Z","end":"2026-04-08T00:38:24.743941Z","steps":["trace[251758583] 'process raft request' (duration: 105.959964ms)","trace[251758583] 'compare' (duration: 53.323438ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:38:26.900009Z","caller":"traceutil/trace.go:171","msg":"trace[1298247085] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"148.827637ms","start":"2026-04-08T00:38:26.751159Z","end":"2026-04-08T00:38:26.899986Z","steps":["trace[1298247085] 'process raft request' (duration: 147.10845ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:38:35.930176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.451653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:38:35.930293Z","caller":"traceutil/trace.go:171","msg":"trace[348430362] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1226; }","duration":"144.621289ms","start":"2026-04-08T00:38:35.785652Z","end":"2026-04-08T00:38:35.930273Z","steps":["trace[348430362] 'count revisions from in-memory index tree' (duration: 144.36153ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:38:37.226795Z","caller":"traceutil/trace.go:171","msg":"trace[1684697074] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"431.562701ms","start":"2026-04-08T00:38:36.795201Z","end":"2026-04-08T00:38:37.226763Z","steps":["trace[1684697074] 'process raft request' (duration: 431.301182ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:38:37.226967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:38:36.795188Z","time spent":"431.683575ms","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:38:37.226991Z","caller":"traceutil/trace.go:171","msg":"trace[41688465] linearizableReadLoop","detail":"{readStateIndex:1290; appliedIndex:1289; }","duration":"343.150256ms","start":"2026-04-08T00:38:36.883813Z","end":"2026-04-08T00:38:37.226963Z","steps":["trace[41688465] 'read index received' (duration: 342.584777ms)","trace[41688465] 'applied index is now lower than readState.Index' (duration: 563.468µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:38:37.227037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.202377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5374"} {"level":"info","ts":"2026-04-08T00:38:37.227109Z","caller":"traceutil/trace.go:171","msg":"trace[714479025] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1236; }","duration":"343.327782ms","start":"2026-04-08T00:38:36.883764Z","end":"2026-04-08T00:38:37.227091Z","steps":["trace[714479025] 'agreement among raft nodes before linearized reading' (duration: 343.093644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:38:37.227145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:38:36.883744Z","time spent":"343.395123ms","remote":"127.0.0.1:48102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5397,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-08T00:38:37.227284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.975566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T00:38:37.227346Z","caller":"traceutil/trace.go:171","msg":"trace[543145283] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1236; }","duration":"278.06797ms","start":"2026-04-08T00:38:36.949261Z","end":"2026-04-08T00:38:37.227329Z","steps":["trace[543145283] 'agreement among raft nodes before linearized reading' (duration: 277.884314ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:38:38.717343Z","caller":"traceutil/trace.go:171","msg":"trace[218666804] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"146.744729ms","start":"2026-04-08T00:38:38.570564Z","end":"2026-04-08T00:38:38.717309Z","steps":["trace[218666804] 'process raft request' (duration: 146.598984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:38:41.321099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.327661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:38:41.321177Z","caller":"traceutil/trace.go:171","msg":"trace[1278071972] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1257; }","duration":"289.424204ms","start":"2026-04-08T00:38:41.031736Z","end":"2026-04-08T00:38:41.32116Z","steps":["trace[1278071972] 'count revisions from in-memory index tree' (duration: 289.144834ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:38:41.321224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.125828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-08T00:38:41.321256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.12918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-08T00:38:41.321266Z","caller":"traceutil/trace.go:171","msg":"trace[1049901609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1257; }","duration":"593.180781ms","start":"2026-04-08T00:38:40.728073Z","end":"2026-04-08T00:38:41.321254Z","steps":["trace[1049901609] 'range keys from in-memory index tree' (duration: 593.030495ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:38:41.321289Z","caller":"traceutil/trace.go:171","msg":"trace[860604085] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1257; }","duration":"600.194652ms","start":"2026-04-08T00:38:40.721084Z","end":"2026-04-08T00:38:41.321279Z","steps":["trace[860604085] 'range keys from in-memory index tree' (duration: 600.020976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:38:41.321295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:38:40.728067Z","time spent":"593.219482ms","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-08T00:38:41.321314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:38:40.721066Z","time spent":"600.241484ms","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-08T00:38:41.470879Z","caller":"traceutil/trace.go:171","msg":"trace[2035965015] linearizableReadLoop","detail":"{readStateIndex:1315; appliedIndex:1313; }","duration":"133.589575ms","start":"2026-04-08T00:38:41.337262Z","end":"2026-04-08T00:38:41.470852Z","steps":["trace[2035965015] 'read index received' (duration: 20.414443ms)","trace[2035965015] 'applied index is now lower than readState.Index' (duration: 113.173982ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:38:41.471257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.982388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T00:38:41.471256Z","caller":"traceutil/trace.go:171","msg":"trace[783073990] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"145.963124ms","start":"2026-04-08T00:38:41.325239Z","end":"2026-04-08T00:38:41.471202Z","steps":["trace[783073990] 'process raft request' (duration: 101.825518ms)","trace[783073990] 'compare' (duration: 41.997645ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:38:41.471298Z","caller":"traceutil/trace.go:171","msg":"trace[1193697368] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1260; }","duration":"134.080211ms","start":"2026-04-08T00:38:41.337207Z","end":"2026-04-08T00:38:41.471287Z","steps":["trace[1193697368] 'agreement among raft nodes before linearized reading' (duration: 133.899375ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:38:41.471531Z","caller":"traceutil/trace.go:171","msg":"trace[429376934] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"145.56872ms","start":"2026-04-08T00:38:41.325951Z","end":"2026-04-08T00:38:41.47152Z","steps":["trace[429376934] 'process raft request' (duration: 144.837156ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:38:42.9339Z","caller":"traceutil/trace.go:171","msg":"trace[192673921] linearizableReadLoop","detail":"{readStateIndex:1320; appliedIndex:1319; }","duration":"376.083072ms","start":"2026-04-08T00:38:42.557798Z","end":"2026-04-08T00:38:42.933881Z","steps":["trace[192673921] 'read index received' (duration: 375.879425ms)","trace[192673921] 'applied index is now lower than readState.Index' (duration: 202.967µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:38:42.933925Z","caller":"traceutil/trace.go:171","msg":"trace[1559274454] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"597.975079ms","start":"2026-04-08T00:38:42.335923Z","end":"2026-04-08T00:38:42.933898Z","steps":["trace[1559274454] 'process raft request' (duration: 597.817523ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:38:42.934031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:38:42.335901Z","time spent":"598.077602ms","remote":"127.0.0.1:47998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":791,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T00:38:42.93408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.276978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.153\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-08T00:38:42.934108Z","caller":"traceutil/trace.go:171","msg":"trace[1763483033] range","detail":"{range_begin:/registry/masterleases/199.204.45.153; range_end:; response_count:1; response_revision:1265; }","duration":"376.35634ms","start":"2026-04-08T00:38:42.557744Z","end":"2026-04-08T00:38:42.9341Z","steps":["trace[1763483033] 'agreement among raft nodes before linearized reading' (duration: 376.221276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:38:42.934134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:38:42.557723Z","time spent":"376.404702ms","remote":"127.0.0.1:47976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.153\" "} {"level":"info","ts":"2026-04-08T00:38:42.957028Z","caller":"traceutil/trace.go:171","msg":"trace[1171029722] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"208.999472ms","start":"2026-04-08T00:38:42.748001Z","end":"2026-04-08T00:38:42.957001Z","steps":["trace[1171029722] 'process raft request' (duration: 208.914019ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:38:42.957024Z","caller":"traceutil/trace.go:171","msg":"trace[1533187115] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"352.998211ms","start":"2026-04-08T00:38:42.603987Z","end":"2026-04-08T00:38:42.956985Z","steps":["trace[1533187115] 'process raft request' (duration: 352.834426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:38:42.957054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.592895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:38:42.95719Z","caller":"traceutil/trace.go:171","msg":"trace[60486068] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1267; }","duration":"274.74992ms","start":"2026-04-08T00:38:42.682422Z","end":"2026-04-08T00:38:42.957172Z","steps":["trace[60486068] 'agreement among raft nodes before linearized reading' (duration: 274.538343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:38:42.95721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:38:42.603968Z","time spent":"353.141175ms","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:38:43.948063Z","caller":"traceutil/trace.go:171","msg":"trace[371608458] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"104.895209ms","start":"2026-04-08T00:38:43.843141Z","end":"2026-04-08T00:38:43.948036Z","steps":["trace[371608458] 'process raft request' (duration: 104.733564ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:38:45.305927Z","caller":"traceutil/trace.go:171","msg":"trace[1616893319] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"343.085654ms","start":"2026-04-08T00:38:44.962766Z","end":"2026-04-08T00:38:45.305851Z","steps":["trace[1616893319] 'process raft request' (duration: 342.737123ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:38:45.306074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:38:44.96275Z","time spent":"343.249969ms","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:38:45.535678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.679776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:38:45.535757Z","caller":"traceutil/trace.go:171","msg":"trace[2002138951] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1275; }","duration":"223.794849ms","start":"2026-04-08T00:38:45.311943Z","end":"2026-04-08T00:38:45.535738Z","steps":["trace[2002138951] 'range keys from in-memory index tree' (duration: 223.606903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:38:45.535757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.624777ms","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-08T00:38:45.535875Z","caller":"traceutil/trace.go:171","msg":"trace[1664377625] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1275; }","duration":"110.758212ms","start":"2026-04-08T00:38:45.425061Z","end":"2026-04-08T00:38:45.53582Z","steps":["trace[1664377625] 'range keys from in-memory index tree' (duration: 110.489083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:38:45.536258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.893589ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:38:45.537283Z","caller":"traceutil/trace.go:171","msg":"trace[1999940015] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1275; }","duration":"105.85532ms","start":"2026-04-08T00:38:45.431334Z","end":"2026-04-08T00:38:45.53719Z","steps":["trace[1999940015] 'range keys from in-memory index tree' (duration: 104.880398ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:38:46.736533Z","caller":"traceutil/trace.go:171","msg":"trace[41732503] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"103.807754ms","start":"2026-04-08T00:38:46.632704Z","end":"2026-04-08T00:38:46.736512Z","steps":["trace[41732503] 'process raft request' (duration: 103.423521ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:38:50.300855Z","caller":"traceutil/trace.go:171","msg":"trace[2075708483] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"296.598791ms","start":"2026-04-08T00:38:50.004223Z","end":"2026-04-08T00:38:50.300822Z","steps":["trace[2075708483] 'process raft request' (duration: 296.420475ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:38:50.813526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.35741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:38:50.81363Z","caller":"traceutil/trace.go:171","msg":"trace[3995016] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1329; }","duration":"433.534686ms","start":"2026-04-08T00:38:50.38007Z","end":"2026-04-08T00:38:50.813605Z","steps":["trace[3995016] 'count revisions from in-memory index tree' (duration: 433.208125ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:38:50.81369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:38:50.38004Z","time spent":"433.633539ms","remote":"127.0.0.1:48432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-04-08T00:38:51.500019Z","caller":"traceutil/trace.go:171","msg":"trace[1432145243] linearizableReadLoop","detail":"{readStateIndex:1387; appliedIndex:1386; }","duration":"190.084068ms","start":"2026-04-08T00:38:51.309911Z","end":"2026-04-08T00:38:51.499995Z","steps":["trace[1432145243] 'read index received' (duration: 189.893042ms)","trace[1432145243] 'applied index is now lower than readState.Index' (duration: 190.066µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:38:51.500174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.254344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:38:51.50024Z","caller":"traceutil/trace.go:171","msg":"trace[293714068] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1330; }","duration":"190.386638ms","start":"2026-04-08T00:38:51.309837Z","end":"2026-04-08T00:38:51.500223Z","steps":["trace[293714068] 'agreement among raft nodes before linearized reading' (duration: 190.267474ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:38:51.500078Z","caller":"traceutil/trace.go:171","msg":"trace[1909350054] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"631.963528ms","start":"2026-04-08T00:38:50.868094Z","end":"2026-04-08T00:38:51.500057Z","steps":["trace[1909350054] 'process raft request' (duration: 631.759091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:38:51.500489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:38:50.868053Z","time spent":"632.358182ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":606,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:38:51.657842Z","caller":"traceutil/trace.go:171","msg":"trace[266301734] linearizableReadLoop","detail":"{readStateIndex:1388; appliedIndex:1387; }","duration":"156.390337ms","start":"2026-04-08T00:38:51.501425Z","end":"2026-04-08T00:38:51.657815Z","steps":["trace[266301734] 'read index received' (duration: 155.383534ms)","trace[266301734] 'applied index is now lower than readState.Index' (duration: 1.005793ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:38:51.658099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.480704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-04-08T00:38:51.657968Z","caller":"traceutil/trace.go:171","msg":"trace[1437609326] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"328.195812ms","start":"2026-04-08T00:38:51.329739Z","end":"2026-04-08T00:38:51.657935Z","steps":["trace[1437609326] 'process raft request' (duration: 327.122287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:38:51.658079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.634715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:38:51.658436Z","caller":"traceutil/trace.go:171","msg":"trace[484198539] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1331; }","duration":"157.06983ms","start":"2026-04-08T00:38:51.501345Z","end":"2026-04-08T00:38:51.658415Z","steps":["trace[484198539] 'agreement among raft nodes before linearized reading' (duration: 156.610025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:38:51.658299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:38:51.329718Z","time spent":"328.470531ms","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:38:51.65814Z","caller":"traceutil/trace.go:171","msg":"trace[1476904887] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1331; }","duration":"154.575158ms","start":"2026-04-08T00:38:51.503554Z","end":"2026-04-08T00:38:51.658129Z","steps":["trace[1476904887] 'agreement among raft nodes before linearized reading' (duration: 154.412482ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:39:13.981687Z","caller":"traceutil/trace.go:171","msg":"trace[1595304333] transaction","detail":"{read_only:false; response_revision:1447; number_of_response:1; }","duration":"156.233493ms","start":"2026-04-08T00:39:13.825416Z","end":"2026-04-08T00:39:13.98165Z","steps":["trace[1595304333] 'process raft request' (duration: 101.053782ms)","trace[1595304333] 'compare' (duration: 54.935673ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:39:14.2088Z","caller":"traceutil/trace.go:171","msg":"trace[50294] linearizableReadLoop","detail":"{readStateIndex:1513; appliedIndex:1510; }","duration":"169.824959ms","start":"2026-04-08T00:39:14.038953Z","end":"2026-04-08T00:39:14.208778Z","steps":["trace[50294] 'read index received' (duration: 168.990522ms)","trace[50294] 'applied index is now lower than readState.Index' (duration: 833.197µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:39:14.209019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.046606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-04-08T00:39:14.209079Z","caller":"traceutil/trace.go:171","msg":"trace[1290828992] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1451; }","duration":"170.15342ms","start":"2026-04-08T00:39:14.038912Z","end":"2026-04-08T00:39:14.209065Z","steps":["trace[1290828992] 'agreement among raft nodes before linearized reading' (duration: 169.965653ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:39:14.209059Z","caller":"traceutil/trace.go:171","msg":"trace[1422980482] transaction","detail":"{read_only:false; response_revision:1450; number_of_response:1; }","duration":"221.820957ms","start":"2026-04-08T00:39:13.987208Z","end":"2026-04-08T00:39:14.209029Z","steps":["trace[1422980482] 'process raft request' (duration: 221.466976ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:39:14.209059Z","caller":"traceutil/trace.go:171","msg":"trace[594554666] transaction","detail":"{read_only:false; response_revision:1449; number_of_response:1; }","duration":"221.963572ms","start":"2026-04-08T00:39:13.987056Z","end":"2026-04-08T00:39:14.20902Z","steps":["trace[594554666] 'process raft request' (duration: 220.948329ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:39:14.209296Z","caller":"traceutil/trace.go:171","msg":"trace[1221318210] transaction","detail":"{read_only:false; response_revision:1451; number_of_response:1; }","duration":"221.994023ms","start":"2026-04-08T00:39:13.987278Z","end":"2026-04-08T00:39:14.209272Z","steps":["trace[1221318210] 'process raft request' (duration: 221.461526ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:41:45.302961Z","caller":"traceutil/trace.go:171","msg":"trace[1798995099] transaction","detail":"{read_only:false; response_revision:2216; number_of_response:1; }","duration":"156.42287ms","start":"2026-04-08T00:41:45.146506Z","end":"2026-04-08T00:41:45.302929Z","steps":["trace[1798995099] 'process raft request' (duration: 113.143521ms)","trace[1798995099] 'compare' (duration: 43.171775ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:42:06.456684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.508622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:42:06.456748Z","caller":"traceutil/trace.go:171","msg":"trace[995066032] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2345; }","duration":"144.644385ms","start":"2026-04-08T00:42:06.31209Z","end":"2026-04-08T00:42:06.456735Z","steps":["trace[995066032] 'range keys from in-memory index tree' (duration: 144.378847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:42:12.664861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.442138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-04-08T00:42:12.664968Z","caller":"traceutil/trace.go:171","msg":"trace[1948085347] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2380; }","duration":"111.567742ms","start":"2026-04-08T00:42:12.553363Z","end":"2026-04-08T00:42:12.664931Z","steps":["trace[1948085347] 'range keys from in-memory index tree' (duration: 111.247182ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:42:13.4047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.410325ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:42:13.404783Z","caller":"traceutil/trace.go:171","msg":"trace[326238963] linearizableReadLoop","detail":"{readStateIndex:2486; appliedIndex:2485; }","duration":"116.433939ms","start":"2026-04-08T00:42:13.288337Z","end":"2026-04-08T00:42:13.40477Z","steps":["trace[326238963] 'read index received' (duration: 48.992µs)","trace[326238963] 'applied index is now lower than readState.Index' (duration: 116.383887ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:42:13.404925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.595133ms","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-08T00:42:13.404954Z","caller":"traceutil/trace.go:171","msg":"trace[675144493] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2387; }","duration":"116.667896ms","start":"2026-04-08T00:42:13.28828Z","end":"2026-04-08T00:42:13.404948Z","steps":["trace[675144493] 'agreement among raft nodes before linearized reading' (duration: 116.564813ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:42:13.4049Z","caller":"traceutil/trace.go:171","msg":"trace[1935676474] transaction","detail":"{read_only:false; response_revision:2387; number_of_response:1; }","duration":"414.429176ms","start":"2026-04-08T00:42:12.990393Z","end":"2026-04-08T00:42:13.404823Z","steps":["trace[1935676474] 'process raft request' (duration: 206.786875ms)","trace[1935676474] 'compare' (duration: 207.107164ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:42:13.405339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:42:12.990378Z","time spent":"414.911361ms","remote":"127.0.0.1:48200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}