{"level":"warn","ts":"2026-03-27T01:55:16.310393Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-27T01:55:16.310496Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.35: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.35:2380","--initial-cluster=instance=https://199.204.45.35:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.35:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.35: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-03-27T01:55:16.31058Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-27T01:55:16.310596Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-03-27T01:55:16.310619Z","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-03-27T01:55:16.311487Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"]} {"level":"info","ts":"2026-03-27T01:55:16.311578Z","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.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.35: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-03-27T01:55:16.315922Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.071256ms"} {"level":"info","ts":"2026-03-27T01:55:16.323226Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fb85913747d92cac","cluster-id":"636a84d5a3ba9c31"} {"level":"info","ts":"2026-03-27T01:55:16.323333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=()"} {"level":"info","ts":"2026-03-27T01:55:16.323366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 0"} {"level":"info","ts":"2026-03-27T01:55:16.323382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fb85913747d92cac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-27T01:55:16.3234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 1"} {"level":"info","ts":"2026-03-27T01:55:16.323455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"warn","ts":"2026-03-27T01:55:16.333924Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-27T01:55:16.336755Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-27T01:55:16.337639Z","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-03-27T01:55:16.34078Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fb85913747d92cac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-27T01:55:16.341578Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fb85913747d92cac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-27T01:55:16.341762Z","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-03-27T01:55:16.341874Z","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-03-27T01:55:16.341889Z","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-03-27T01:55:16.346971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"info","ts":"2026-03-27T01:55:16.347147Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","added-peer-id":"fb85913747d92cac","added-peer-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-03-27T01:55:16.352341Z","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-03-27T01:55:16.352482Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-03-27T01:55:16.352558Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-03-27T01:55:16.352693Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fb85913747d92cac","initial-advertise-peer-urls":["https://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-27T01:55:16.352729Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-27T01:55:16.924564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac is starting a new election at term 1"} {"level":"info","ts":"2026-03-27T01:55:16.924663Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became pre-candidate at term 1"} {"level":"info","ts":"2026-03-27T01:55:16.924698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgPreVoteResp from fb85913747d92cac at term 1"} {"level":"info","ts":"2026-03-27T01:55:16.924718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became candidate at term 2"} {"level":"info","ts":"2026-03-27T01:55:16.924735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgVoteResp from fb85913747d92cac at term 2"} {"level":"info","ts":"2026-03-27T01:55:16.924751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became leader at term 2"} {"level":"info","ts":"2026-03-27T01:55:16.924769Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fb85913747d92cac elected leader fb85913747d92cac at term 2"} {"level":"info","ts":"2026-03-27T01:55:16.926087Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-27T01:55:16.927458Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","cluster-version":"3.5"} {"level":"info","ts":"2026-03-27T01:55:16.927554Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-27T01:55:16.927587Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-27T01:55:16.927625Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fb85913747d92cac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.35:2379]}","request-path":"/0/members/fb85913747d92cac/attributes","cluster-id":"636a84d5a3ba9c31","publish-timeout":"7s"} {"level":"info","ts":"2026-03-27T01:55:16.927814Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-27T01:55:16.92824Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-27T01:55:16.928483Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-27T01:55:16.92853Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-27T01:55:16.93141Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.35:2379"} {"level":"info","ts":"2026-03-27T01:55:16.931885Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-27T01:55:22.972356Z","caller":"traceutil/trace.go:171","msg":"trace[1366943675] linearizableReadLoop","detail":"{readStateIndex:326; appliedIndex:325; }","duration":"275.127443ms","start":"2026-03-27T01:55:22.69719Z","end":"2026-03-27T01:55:22.972317Z","steps":["trace[1366943675] 'read index received' (duration: 202.97127ms)","trace[1366943675] 'applied index is now lower than readState.Index' (duration: 72.154263ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:55:22.972387Z","caller":"traceutil/trace.go:171","msg":"trace[225238921] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"284.483365ms","start":"2026-03-27T01:55:22.687869Z","end":"2026-03-27T01:55:22.972352Z","steps":["trace[225238921] 'process raft request' (duration: 212.346832ms)","trace[225238921] 'compare' (duration: 71.797407ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:55:22.972609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.299586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cronjob-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-27T01:55:22.97265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.623699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-27T01:55:22.972682Z","caller":"traceutil/trace.go:171","msg":"trace[399234783] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cronjob-controller; range_end:; response_count:0; response_revision:317; }","duration":"275.51826ms","start":"2026-03-27T01:55:22.697151Z","end":"2026-03-27T01:55:22.972669Z","steps":["trace[399234783] 'agreement among raft nodes before linearized reading' (duration: 275.300166ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:55:22.972693Z","caller":"traceutil/trace.go:171","msg":"trace[1568884947] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:317; }","duration":"211.752951ms","start":"2026-03-27T01:55:22.760927Z","end":"2026-03-27T01:55:22.97268Z","steps":["trace[1568884947] 'agreement among raft nodes before linearized reading' (duration: 211.583108ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:55:23.15902Z","caller":"traceutil/trace.go:171","msg":"trace[639158760] linearizableReadLoop","detail":"{readStateIndex:329; appliedIndex:328; }","duration":"141.760638ms","start":"2026-03-27T01:55:23.017233Z","end":"2026-03-27T01:55:23.158994Z","steps":["trace[639158760] 'read index received' (duration: 118.427557ms)","trace[639158760] 'applied index is now lower than readState.Index' (duration: 23.332181ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:55:23.159121Z","caller":"traceutil/trace.go:171","msg":"trace[1444588685] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"143.380898ms","start":"2026-03-27T01:55:23.015691Z","end":"2026-03-27T01:55:23.159072Z","steps":["trace[1444588685] 'process raft request' (duration: 120.002786ms)","trace[1444588685] 'compare' (duration: 23.208859ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:55:23.159186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.934271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ephemeral-volume-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:55:23.159237Z","caller":"traceutil/trace.go:171","msg":"trace[1354428290] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ephemeral-volume-controller; range_end:; response_count:0; response_revision:320; }","duration":"142.002192ms","start":"2026-03-27T01:55:23.01722Z","end":"2026-03-27T01:55:23.159222Z","steps":["trace[1354428290] 'agreement among raft nodes before linearized reading' (duration: 141.86943ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:55:25.868199Z","caller":"traceutil/trace.go:171","msg":"trace[534966522] linearizableReadLoop","detail":"{readStateIndex:365; appliedIndex:364; }","duration":"119.792114ms","start":"2026-03-27T01:55:25.74838Z","end":"2026-03-27T01:55:25.868172Z","steps":["trace[534966522] 'read index received' (duration: 111.343767ms)","trace[534966522] 'applied index is now lower than readState.Index' (duration: 8.447026ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:55:25.868386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.984897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-03-27T01:55:25.868401Z","caller":"traceutil/trace.go:171","msg":"trace[1862992883] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"162.384769ms","start":"2026-03-27T01:55:25.705984Z","end":"2026-03-27T01:55:25.868369Z","steps":["trace[1862992883] 'process raft request' (duration: 153.740419ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:55:25.868505Z","caller":"traceutil/trace.go:171","msg":"trace[1705226435] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:356; }","duration":"120.16238ms","start":"2026-03-27T01:55:25.748324Z","end":"2026-03-27T01:55:25.868487Z","steps":["trace[1705226435] 'agreement among raft nodes before linearized reading' (duration: 119.987937ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:55:32.05342Z","caller":"traceutil/trace.go:171","msg":"trace[840359739] linearizableReadLoop","detail":"{readStateIndex:420; appliedIndex:419; }","duration":"330.865472ms","start":"2026-03-27T01:55:31.722521Z","end":"2026-03-27T01:55:32.053386Z","steps":["trace[840359739] 'read index received' (duration: 330.685918ms)","trace[840359739] 'applied index is now lower than readState.Index' (duration: 178.504µs)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:55:32.053461Z","caller":"traceutil/trace.go:171","msg":"trace[1332510564] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"341.410086ms","start":"2026-03-27T01:55:31.712032Z","end":"2026-03-27T01:55:32.053442Z","steps":["trace[1332510564] 'process raft request' (duration: 341.235933ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:55:32.0536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.060106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:55:32.053648Z","caller":"traceutil/trace.go:171","msg":"trace[2050405699] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:409; }","duration":"331.158498ms","start":"2026-03-27T01:55:31.722474Z","end":"2026-03-27T01:55:32.053633Z","steps":["trace[2050405699] 'agreement among raft nodes before linearized reading' (duration: 331.060686ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:55:32.053687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:55:31.722465Z","time spent":"331.211338ms","remote":"127.0.0.1:49578","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-03-27T01:55:32.05397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:55:31.712006Z","time spent":"341.511359ms","remote":"127.0.0.1:49812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-27T01:55:33.056492Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219120650274530959,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-27T01:55:33.557711Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219120650274530959,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-27T01:55:34.05843Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219120650274530959,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-27T01:55:34.559546Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219120650274530959,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-27T01:55:35.06055Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219120650274530959,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-27T01:55:35.152585Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.088411039s","expected-duration":"1s"} {"level":"info","ts":"2026-03-27T01:55:35.153078Z","caller":"traceutil/trace.go:171","msg":"trace[1668921793] linearizableReadLoop","detail":"{readStateIndex:421; appliedIndex:420; }","duration":"2.596777086s","start":"2026-03-27T01:55:32.556272Z","end":"2026-03-27T01:55:35.153049Z","steps":["trace[1668921793] 'read index received' (duration: 2.596510852s)","trace[1668921793] 'applied index is now lower than readState.Index' (duration: 265.274µs)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:55:35.153203Z","caller":"traceutil/trace.go:171","msg":"trace[1149941145] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"3.089119763s","start":"2026-03-27T01:55:32.064071Z","end":"2026-03-27T01:55:35.153191Z","steps":["trace[1149941145] 'process raft request' (duration: 3.088800136s)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:55:35.15334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:55:32.06405Z","time spent":"3.089184894s","remote":"127.0.0.1:49722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4307,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-27T01:55:35.153402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.758628835s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"warn","ts":"2026-03-27T01:55:35.153422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.50594337s","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-03-27T01:55:35.153453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.597167535s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-27T01:55:35.153472Z","caller":"traceutil/trace.go:171","msg":"trace[117144553] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:410; }","duration":"1.758777578s","start":"2026-03-27T01:55:33.394674Z","end":"2026-03-27T01:55:35.153452Z","steps":["trace[117144553] 'agreement among raft nodes before linearized reading' (duration: 1.758637695s)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:55:35.153494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.430354152s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:55:35.153512Z","caller":"traceutil/trace.go:171","msg":"trace[77253322] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:410; }","duration":"2.506045472s","start":"2026-03-27T01:55:32.647444Z","end":"2026-03-27T01:55:35.15349Z","steps":["trace[77253322] 'agreement among raft nodes before linearized reading' (duration: 2.50592444s)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:55:35.153517Z","caller":"traceutil/trace.go:171","msg":"trace[370462989] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:410; }","duration":"2.597265896s","start":"2026-03-27T01:55:32.556232Z","end":"2026-03-27T01:55:35.153498Z","steps":["trace[370462989] 'agreement among raft nodes before linearized reading' (duration: 2.597118814s)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:55:35.153517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:55:33.39464Z","time spent":"1.75886946s","remote":"127.0.0.1:50026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4079,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"info","ts":"2026-03-27T01:55:35.153553Z","caller":"traceutil/trace.go:171","msg":"trace[71134516] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:410; }","duration":"1.430451964s","start":"2026-03-27T01:55:33.723084Z","end":"2026-03-27T01:55:35.153536Z","steps":["trace[71134516] 'agreement among raft nodes before linearized reading' (duration: 1.430355932s)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:55:35.153569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:55:32.647428Z","time spent":"2.506122103s","remote":"127.0.0.1:49812","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-03-27T01:55:35.153514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.096734042s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-27T01:55:35.153561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:55:32.556211Z","time spent":"2.597341268s","remote":"127.0.0.1:49812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-27T01:55:35.153659Z","caller":"traceutil/trace.go:171","msg":"trace[764116046] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:410; }","duration":"2.096957695s","start":"2026-03-27T01:55:33.056666Z","end":"2026-03-27T01:55:35.153623Z","steps":["trace[764116046] 'agreement among raft nodes before linearized reading' (duration: 2.096652519s)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:55:35.153745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:55:33.056646Z","time spent":"2.097086718s","remote":"127.0.0.1:49812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-27T01:55:35.153624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:55:33.723061Z","time spent":"1.430534865s","remote":"127.0.0.1:49578","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-03-27T01:55:35.153339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.594874493s","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-03-27T01:55:35.1541Z","caller":"traceutil/trace.go:171","msg":"trace[2133447279] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:410; }","duration":"2.595677538s","start":"2026-03-27T01:55:32.558405Z","end":"2026-03-27T01:55:35.154082Z","steps":["trace[2133447279] 'agreement among raft nodes before linearized reading' (duration: 2.594794852s)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:55:35.154142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:55:32.558394Z","time spent":"2.595738169s","remote":"127.0.0.1:49812","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":"info","ts":"2026-03-27T01:55:40.153774Z","caller":"traceutil/trace.go:171","msg":"trace[1375711397] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"126.343967ms","start":"2026-03-27T01:55:40.027408Z","end":"2026-03-27T01:55:40.153752Z","steps":["trace[1375711397] 'process raft request' (duration: 125.629735ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:55:49.227337Z","caller":"traceutil/trace.go:171","msg":"trace[2133610874] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"386.981081ms","start":"2026-03-27T01:55:48.840332Z","end":"2026-03-27T01:55:49.227313Z","steps":["trace[2133610874] 'process raft request' (duration: 386.853018ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:55:49.227556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:55:48.840312Z","time spent":"387.104123ms","remote":"127.0.0.1:49616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-27T01:55:49.243861Z","caller":"traceutil/trace.go:171","msg":"trace[1421421911] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"393.664594ms","start":"2026-03-27T01:55:48.850174Z","end":"2026-03-27T01:55:49.243839Z","steps":["trace[1421421911] 'process raft request' (duration: 393.256947ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:55:49.243941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:55:48.850163Z","time spent":"393.741725ms","remote":"127.0.0.1:49616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-27T01:55:49.243935Z","caller":"traceutil/trace.go:171","msg":"trace[1194821217] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"400.278906ms","start":"2026-03-27T01:55:48.843623Z","end":"2026-03-27T01:55:49.243901Z","steps":["trace[1194821217] 'process raft request' (duration: 399.582674ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:55:49.24412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:55:48.843596Z","time spent":"400.45775ms","remote":"127.0.0.1:49616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-27T01:55:49.243898Z","caller":"traceutil/trace.go:171","msg":"trace[975945190] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"393.679234ms","start":"2026-03-27T01:55:48.850192Z","end":"2026-03-27T01:55:49.243871Z","steps":["trace[975945190] 'process raft request' (duration: 393.40847ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:55:49.244293Z","caller":"traceutil/trace.go:171","msg":"trace[1228208115] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"389.182101ms","start":"2026-03-27T01:55:48.855092Z","end":"2026-03-27T01:55:49.244274Z","steps":["trace[1228208115] 'process raft request' (duration: 388.606059ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:55:49.24432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:55:48.850184Z","time spent":"394.025141ms","remote":"127.0.0.1:49616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-27T01:55:49.244434Z","caller":"traceutil/trace.go:171","msg":"trace[1499799833] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"389.266682ms","start":"2026-03-27T01:55:48.855149Z","end":"2026-03-27T01:55:49.244415Z","steps":["trace[1499799833] 'process raft request' (duration: 388.62079ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:55:49.244158Z","caller":"traceutil/trace.go:171","msg":"trace[824339460] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"400.450139ms","start":"2026-03-27T01:55:48.843695Z","end":"2026-03-27T01:55:49.244145Z","steps":["trace[824339460] 'process raft request' (duration: 399.663624ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:55:49.24455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:55:48.843689Z","time spent":"400.833326ms","remote":"127.0.0.1:49616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-27T01:55:49.244783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:55:48.855075Z","time spent":"389.257093ms","remote":"127.0.0.1:49616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-27T01:55:49.244851Z","caller":"traceutil/trace.go:171","msg":"trace[814429252] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"387.905888ms","start":"2026-03-27T01:55:48.856932Z","end":"2026-03-27T01:55:49.244838Z","steps":["trace[814429252] 'process raft request' (duration: 387.745965ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:55:49.244909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:55:48.856912Z","time spent":"387.975909ms","remote":"127.0.0.1:49616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-27T01:55:49.244997Z","caller":"traceutil/trace.go:171","msg":"trace[1754983568] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"383.930373ms","start":"2026-03-27T01:55:48.861054Z","end":"2026-03-27T01:55:49.244984Z","steps":["trace[1754983568] 'process raft request' (duration: 383.705079ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:55:49.24506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:55:48.861045Z","time spent":"383.990254ms","remote":"127.0.0.1:49616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-27T01:55:49.245083Z","caller":"traceutil/trace.go:171","msg":"trace[913812780] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"380.909968ms","start":"2026-03-27T01:55:48.864163Z","end":"2026-03-27T01:55:49.245073Z","steps":["trace[913812780] 'process raft request' (duration: 380.733355ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:55:49.245134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:55:48.864146Z","time spent":"380.966959ms","remote":"127.0.0.1:49616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-27T01:55:49.245153Z","caller":"traceutil/trace.go:171","msg":"trace[625304642] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"356.482135ms","start":"2026-03-27T01:55:48.88866Z","end":"2026-03-27T01:55:49.245143Z","steps":["trace[625304642] 'process raft request' (duration: 356.309872ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:55:49.245209Z","caller":"traceutil/trace.go:171","msg":"trace[1208408118] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"252.40131ms","start":"2026-03-27T01:55:48.9928Z","end":"2026-03-27T01:55:49.245201Z","steps":["trace[1208408118] 'process raft request' (duration: 252.257228ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:55:49.245209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:55:48.888633Z","time spent":"356.541887ms","remote":"127.0.0.1:49616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-27T01:55:49.244803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:55:48.855145Z","time spent":"389.335674ms","remote":"127.0.0.1:49616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-27T01:55:49.867698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.850766ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-27T01:55:49.867941Z","caller":"traceutil/trace.go:171","msg":"trace[1144657663] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:567; }","duration":"146.034992ms","start":"2026-03-27T01:55:49.721894Z","end":"2026-03-27T01:55:49.867929Z","steps":["trace[1144657663] 'read index received' (duration: 126.671213ms)","trace[1144657663] 'applied index is now lower than readState.Index' (duration: 19.363079ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:55:49.868003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.108723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:55:49.868015Z","caller":"traceutil/trace.go:171","msg":"trace[1757742355] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:548; }","duration":"146.155364ms","start":"2026-03-27T01:55:49.721856Z","end":"2026-03-27T01:55:49.868011Z","steps":["trace[1757742355] 'agreement among raft nodes before linearized reading' (duration: 146.102393ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:55:49.868145Z","caller":"traceutil/trace.go:171","msg":"trace[420072581] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"403.660568ms","start":"2026-03-27T01:55:49.464479Z","end":"2026-03-27T01:55:49.868139Z","steps":["trace[420072581] 'process raft request' (duration: 222.219352ms)","trace[420072581] 'compare' (duration: 180.778195ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:55:49.868174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:55:49.464453Z","time spent":"403.703779ms","remote":"127.0.0.1:49616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31121,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-27T01:55:49.868217Z","caller":"traceutil/trace.go:171","msg":"trace[1882262192] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"400.728675ms","start":"2026-03-27T01:55:49.467485Z","end":"2026-03-27T01:55:49.868214Z","steps":["trace[1882262192] 'process raft request' (duration: 400.303917ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:55:49.868239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:55:49.467465Z","time spent":"400.760405ms","remote":"127.0.0.1:49616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-27T01:55:49.868274Z","caller":"traceutil/trace.go:171","msg":"trace[2110240442] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"391.31211ms","start":"2026-03-27T01:55:49.476959Z","end":"2026-03-27T01:55:49.868271Z","steps":["trace[2110240442] 'process raft request' (duration: 390.907652ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:55:49.868293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:55:49.476931Z","time spent":"391.351671ms","remote":"127.0.0.1:49616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-27T01:55:50.373322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.474212ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-27T01:55:50.373594Z","caller":"traceutil/trace.go:171","msg":"trace[549649643] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"493.928609ms","start":"2026-03-27T01:55:49.879628Z","end":"2026-03-27T01:55:50.373557Z","steps":["trace[549649643] 'process raft request' (duration: 267.974839ms)","trace[549649643] 'compare' (duration: 225.327649ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:55:50.373705Z","caller":"traceutil/trace.go:171","msg":"trace[1917524074] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:570; }","duration":"376.249441ms","start":"2026-03-27T01:55:49.997438Z","end":"2026-03-27T01:55:50.373687Z","steps":["trace[1917524074] 'read index received' (duration: 150.207108ms)","trace[1917524074] 'applied index is now lower than readState.Index' (duration: 226.041443ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:55:50.373738Z","caller":"traceutil/trace.go:171","msg":"trace[385456796] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"491.907721ms","start":"2026-03-27T01:55:49.881807Z","end":"2026-03-27T01:55:50.373714Z","steps":["trace[385456796] 'process raft request' (duration: 491.677457ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:55:50.373751Z","caller":"traceutil/trace.go:171","msg":"trace[925566924] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"453.367028ms","start":"2026-03-27T01:55:49.920355Z","end":"2026-03-27T01:55:50.373722Z","steps":["trace[925566924] 'process raft request' (duration: 453.278527ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:55:50.373765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:55:49.879611Z","time spent":"494.061541ms","remote":"127.0.0.1:49616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23057,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-27T01:55:50.373807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.367974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.35\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-27T01:55:50.373837Z","caller":"traceutil/trace.go:171","msg":"trace[1405325694] range","detail":"{range_begin:/registry/masterleases/199.204.45.35; range_end:; response_count:1; response_revision:551; }","duration":"376.433484ms","start":"2026-03-27T01:55:49.997394Z","end":"2026-03-27T01:55:50.373828Z","steps":["trace[1405325694] 'agreement among raft nodes before linearized reading' (duration: 376.346943ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:55:50.373844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:55:49.881791Z","time spent":"491.999703ms","remote":"127.0.0.1:49616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-27T01:55:50.373853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:55:49.920328Z","time spent":"453.478691ms","remote":"127.0.0.1:49616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64392,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-27T01:55:50.373869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:55:49.997377Z","time spent":"376.485385ms","remote":"127.0.0.1:49602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.35\" "} {"level":"warn","ts":"2026-03-27T01:55:50.717994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.614834ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-27T01:55:50.71833Z","caller":"traceutil/trace.go:171","msg":"trace[1347960510] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"323.625558ms","start":"2026-03-27T01:55:50.394686Z","end":"2026-03-27T01:55:50.718311Z","steps":["trace[1347960510] 'process raft request' (duration: 323.435195ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:55:50.718411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:55:50.394668Z","time spent":"323.707129ms","remote":"127.0.0.1:49616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23066,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-27T01:55:50.718569Z","caller":"traceutil/trace.go:171","msg":"trace[1406577272] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"314.107142ms","start":"2026-03-27T01:55:50.404452Z","end":"2026-03-27T01:55:50.718559Z","steps":["trace[1406577272] 'process raft request' (duration: 313.811506ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:55:50.718563Z","caller":"traceutil/trace.go:171","msg":"trace[858638017] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"265.88095ms","start":"2026-03-27T01:55:50.452666Z","end":"2026-03-27T01:55:50.718547Z","steps":["trace[858638017] 'process raft request' (duration: 265.806479ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:55:50.718623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:55:50.404443Z","time spent":"314.153573ms","remote":"127.0.0.1:49616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29546,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-27T01:55:50.719835Z","caller":"traceutil/trace.go:171","msg":"trace[1113579680] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"335.70561ms","start":"2026-03-27T01:55:50.382518Z","end":"2026-03-27T01:55:50.718223Z","steps":["trace[1113579680] 'process raft request' (duration: 96.7619ms)","trace[1113579680] 'compare' (duration: 238.446722ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:55:50.720041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:55:50.382494Z","time spent":"337.439124ms","remote":"127.0.0.1:49602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-27T01:55:50.962437Z","caller":"traceutil/trace.go:171","msg":"trace[1701737807] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"212.113395ms","start":"2026-03-27T01:55:50.7503Z","end":"2026-03-27T01:55:50.962414Z","steps":["trace[1701737807] 'process raft request' (duration: 212.062104ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:55:50.962551Z","caller":"traceutil/trace.go:171","msg":"trace[2095962152] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"221.549029ms","start":"2026-03-27T01:55:50.740967Z","end":"2026-03-27T01:55:50.962516Z","steps":["trace[2095962152] 'process raft request' (duration: 154.747113ms)","trace[2095962152] 'compare' (duration: 66.48755ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:55:51.202314Z","caller":"traceutil/trace.go:171","msg":"trace[239661099] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"205.85379ms","start":"2026-03-27T01:55:50.996433Z","end":"2026-03-27T01:55:51.202287Z","steps":["trace[239661099] 'process raft request' (duration: 123.815972ms)","trace[239661099] 'compare' (duration: 81.859354ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:55:51.427273Z","caller":"traceutil/trace.go:171","msg":"trace[2103092570] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"199.918319ms","start":"2026-03-27T01:55:51.227319Z","end":"2026-03-27T01:55:51.427237Z","steps":["trace[2103092570] 'process raft request' (duration: 113.309397ms)","trace[2103092570] 'compare' (duration: 86.305367ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:55:51.427467Z","caller":"traceutil/trace.go:171","msg":"trace[657929766] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:584; }","duration":"137.442403ms","start":"2026-03-27T01:55:51.289951Z","end":"2026-03-27T01:55:51.427393Z","steps":["trace[657929766] 'read index received' (duration: 50.623637ms)","trace[657929766] 'applied index is now lower than readState.Index' (duration: 86.817886ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:55:51.42766Z","caller":"traceutil/trace.go:171","msg":"trace[285289420] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"190.146178ms","start":"2026-03-27T01:55:51.237503Z","end":"2026-03-27T01:55:51.427649Z","steps":["trace[285289420] 'process raft request' (duration: 189.741701ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:55:51.427922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.784121ms","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-03-27T01:55:51.427974Z","caller":"traceutil/trace.go:171","msg":"trace[912889640] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:563; }","duration":"122.880503ms","start":"2026-03-27T01:55:51.305079Z","end":"2026-03-27T01:55:51.427959Z","steps":["trace[912889640] 'agreement among raft nodes before linearized reading' (duration: 122.612848ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:55:51.428184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.957895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-27T01:55:51.428239Z","caller":"traceutil/trace.go:171","msg":"trace[308116829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:563; }","duration":"123.034796ms","start":"2026-03-27T01:55:51.305191Z","end":"2026-03-27T01:55:51.428226Z","steps":["trace[308116829] 'agreement among raft nodes before linearized reading' (duration: 122.870063ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:55:51.428378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.433651ms","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-03-27T01:55:51.428421Z","caller":"traceutil/trace.go:171","msg":"trace[359618700] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:563; }","duration":"138.519183ms","start":"2026-03-27T01:55:51.28989Z","end":"2026-03-27T01:55:51.428409Z","steps":["trace[359618700] 'agreement among raft nodes before linearized reading' (duration: 138.428901ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:55:51.428194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.005755ms","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-03-27T01:55:51.428509Z","caller":"traceutil/trace.go:171","msg":"trace[117754527] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:563; }","duration":"123.331832ms","start":"2026-03-27T01:55:51.305165Z","end":"2026-03-27T01:55:51.428497Z","steps":["trace[117754527] 'agreement among raft nodes before linearized reading' (duration: 122.992045ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:55:51.658637Z","caller":"traceutil/trace.go:171","msg":"trace[1726300109] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"218.973672ms","start":"2026-03-27T01:55:51.439625Z","end":"2026-03-27T01:55:51.658598Z","steps":["trace[1726300109] 'process raft request' (duration: 120.229994ms)","trace[1726300109] 'compare' (duration: 98.614355ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:55:51.658996Z","caller":"traceutil/trace.go:171","msg":"trace[686870684] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"217.979093ms","start":"2026-03-27T01:55:51.440999Z","end":"2026-03-27T01:55:51.658979Z","steps":["trace[686870684] 'process raft request' (duration: 217.81371ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:55:51.659062Z","caller":"traceutil/trace.go:171","msg":"trace[2105622279] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"209.520006ms","start":"2026-03-27T01:55:51.449527Z","end":"2026-03-27T01:55:51.659047Z","steps":["trace[2105622279] 'process raft request' (duration: 209.396414ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:55:51.659162Z","caller":"traceutil/trace.go:171","msg":"trace[724715397] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"209.71593ms","start":"2026-03-27T01:55:51.449437Z","end":"2026-03-27T01:55:51.659153Z","steps":["trace[724715397] 'process raft request' (duration: 209.466865ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:55:51.659197Z","caller":"traceutil/trace.go:171","msg":"trace[870621749] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"131.204127ms","start":"2026-03-27T01:55:51.527974Z","end":"2026-03-27T01:55:51.659178Z","steps":["trace[870621749] 'process raft request' (duration: 131.176187ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:55:51.65944Z","caller":"traceutil/trace.go:171","msg":"trace[307332500] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"194.354687ms","start":"2026-03-27T01:55:51.465079Z","end":"2026-03-27T01:55:51.659434Z","steps":["trace[307332500] 'process raft request' (duration: 193.883697ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:55:51.659535Z","caller":"traceutil/trace.go:171","msg":"trace[435815347] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"148.439667ms","start":"2026-03-27T01:55:51.511083Z","end":"2026-03-27T01:55:51.659522Z","steps":["trace[435815347] 'process raft request' (duration: 147.961458ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:55:51.973943Z","caller":"traceutil/trace.go:171","msg":"trace[1401127185] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"135.512457ms","start":"2026-03-27T01:55:51.838415Z","end":"2026-03-27T01:55:51.973928Z","steps":["trace[1401127185] 'process raft request' (duration: 125.456641ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:55:51.974155Z","caller":"traceutil/trace.go:171","msg":"trace[337755462] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"126.056033ms","start":"2026-03-27T01:55:51.848093Z","end":"2026-03-27T01:55:51.974149Z","steps":["trace[337755462] 'process raft request' (duration: 125.805869ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:55:52.488629Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219120650274531242,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-27T01:55:52.784836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.463107ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-27T01:55:52.785033Z","caller":"traceutil/trace.go:171","msg":"trace[489521294] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"799.661677ms","start":"2026-03-27T01:55:51.98535Z","end":"2026-03-27T01:55:52.785012Z","steps":["trace[489521294] 'process raft request' (duration: 564.925544ms)","trace[489521294] 'compare' (duration: 234.296404ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:55:52.785203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:55:51.985327Z","time spent":"799.81993ms","remote":"127.0.0.1:49616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18041,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-27T01:55:53.244551Z","caller":"traceutil/trace.go:171","msg":"trace[876019460] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:599; }","duration":"1.256660382s","start":"2026-03-27T01:55:51.987862Z","end":"2026-03-27T01:55:53.244523Z","steps":["trace[876019460] 'read index received' (duration: 562.402276ms)","trace[876019460] 'applied index is now lower than readState.Index' (duration: 694.256866ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:55:53.244628Z","caller":"traceutil/trace.go:171","msg":"trace[162137886] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"1.244223332s","start":"2026-03-27T01:55:52.000359Z","end":"2026-03-27T01:55:53.244582Z","steps":["trace[162137886] 'process raft request' (duration: 1.243790524s)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:55:53.244871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.256995578s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:10870"} {"level":"info","ts":"2026-03-27T01:55:53.244949Z","caller":"traceutil/trace.go:171","msg":"trace[1789562388] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:578; }","duration":"1.257139751s","start":"2026-03-27T01:55:51.98779Z","end":"2026-03-27T01:55:53.24493Z","steps":["trace[1789562388] 'agreement among raft nodes before linearized reading' (duration: 1.256825085s)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:55:53.245026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:55:51.987745Z","time spent":"1.257270183s","remote":"127.0.0.1:49616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":10893,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-03-27T01:55:53.245045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:55:52.000326Z","time spent":"1.244464936s","remote":"127.0.0.1:49616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":98689,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-27T01:55:53.25292Z","caller":"traceutil/trace.go:171","msg":"trace[761200214] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"472.668186ms","start":"2026-03-27T01:55:52.780221Z","end":"2026-03-27T01:55:53.252889Z","steps":["trace[761200214] 'process raft request' (duration: 472.482383ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:55:53.253126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:55:52.780165Z","time spent":"472.839549ms","remote":"127.0.0.1:49812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-27T01:55:53.253109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.786905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:55:53.253177Z","caller":"traceutil/trace.go:171","msg":"trace[997288371] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"451.149628ms","start":"2026-03-27T01:55:52.801996Z","end":"2026-03-27T01:55:53.253146Z","steps":["trace[997288371] 'process raft request' (duration: 450.914904ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:55:53.253202Z","caller":"traceutil/trace.go:171","msg":"trace[1498999527] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"915.923868ms","start":"2026-03-27T01:55:52.337261Z","end":"2026-03-27T01:55:53.253185Z","steps":["trace[1498999527] 'agreement among raft nodes before linearized reading' (duration: 915.784625ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:55:53.253286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:55:52.801969Z","time spent":"451.250329ms","remote":"127.0.0.1:49616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31111,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-27T01:55:53.253354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:55:52.337243Z","time spent":"915.990028ms","remote":"127.0.0.1:49578","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-03-27T01:55:57.282765Z","caller":"traceutil/trace.go:171","msg":"trace[1476568666] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"535.018209ms","start":"2026-03-27T01:55:56.747722Z","end":"2026-03-27T01:55:57.28274Z","steps":["trace[1476568666] 'process raft request' (duration: 534.882757ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:55:57.282924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:55:56.747697Z","time spent":"535.148581ms","remote":"127.0.0.1:49722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-27T01:55:57.979187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.384144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:55:57.979286Z","caller":"traceutil/trace.go:171","msg":"trace[1057017807] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:605; }","duration":"256.547327ms","start":"2026-03-27T01:55:57.722712Z","end":"2026-03-27T01:55:57.979259Z","steps":["trace[1057017807] 'range keys from in-memory index tree' (duration: 256.222551ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:55:57.979327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.297954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-27T01:55:57.979409Z","caller":"traceutil/trace.go:171","msg":"trace[577732874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:605; }","duration":"295.388746ms","start":"2026-03-27T01:55:57.68399Z","end":"2026-03-27T01:55:57.979379Z","steps":["trace[577732874] 'range keys from in-memory index tree' (duration: 295.162511ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:55:57.979189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.931579ms","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-03-27T01:55:57.979642Z","caller":"traceutil/trace.go:171","msg":"trace[981777850] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:605; }","duration":"294.404278ms","start":"2026-03-27T01:55:57.685221Z","end":"2026-03-27T01:55:57.979625Z","steps":["trace[981777850] 'range keys from in-memory index tree' (duration: 293.790456ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:55:57.980075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.243805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-27T01:55:57.980219Z","caller":"traceutil/trace.go:171","msg":"trace[452825907] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:605; }","duration":"294.461829ms","start":"2026-03-27T01:55:57.685732Z","end":"2026-03-27T01:55:57.980194Z","steps":["trace[452825907] 'range keys from in-memory index tree' (duration: 294.00824ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:55:57.98052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.836705ms","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-03-27T01:55:57.98064Z","caller":"traceutil/trace.go:171","msg":"trace[511669963] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:605; }","duration":"295.043299ms","start":"2026-03-27T01:55:57.685576Z","end":"2026-03-27T01:55:57.980619Z","steps":["trace[511669963] 'range keys from in-memory index tree' (duration: 293.759605ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:55:58.424802Z","caller":"traceutil/trace.go:171","msg":"trace[408564787] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"440.859507ms","start":"2026-03-27T01:55:57.983914Z","end":"2026-03-27T01:55:58.424773Z","steps":["trace[408564787] 'process raft request' (duration: 440.811705ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:55:58.42485Z","caller":"traceutil/trace.go:171","msg":"trace[374243865] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"440.906378ms","start":"2026-03-27T01:55:57.983911Z","end":"2026-03-27T01:55:58.424818Z","steps":["trace[374243865] 'process raft request' (duration: 440.641722ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:55:58.425144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:55:57.983891Z","time spent":"441.073491ms","remote":"127.0.0.1:49812","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-03-27T01:55:58.425175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:55:57.983892Z","time spent":"441.146903ms","remote":"127.0.0.1:49812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-27T01:55:58.524984Z","caller":"traceutil/trace.go:171","msg":"trace[1312219388] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"540.709125ms","start":"2026-03-27T01:55:57.984246Z","end":"2026-03-27T01:55:58.524955Z","steps":["trace[1312219388] 'process raft request' (duration: 506.977071ms)","trace[1312219388] 'compare' (duration: 33.548861ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:55:58.525351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:55:57.984236Z","time spent":"540.832247ms","remote":"127.0.0.1:49812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-27T01:55:58.525546Z","caller":"traceutil/trace.go:171","msg":"trace[17314716] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"540.666024ms","start":"2026-03-27T01:55:57.984805Z","end":"2026-03-27T01:55:58.525471Z","steps":["trace[17314716] 'process raft request' (duration: 540.105334ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:55:58.52578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:55:57.984794Z","time spent":"540.856269ms","remote":"127.0.0.1:49812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-27T01:56:00.691557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.030144523s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:56:00.69163Z","caller":"traceutil/trace.go:171","msg":"trace[2118357725] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway; range_end:; response_count:0; response_revision:609; }","duration":"2.030251546s","start":"2026-03-27T01:55:58.661359Z","end":"2026-03-27T01:56:00.691611Z","steps":["trace[2118357725] 'range keys from in-memory index tree' (duration: 2.030047762s)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:56:00.691667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:55:58.661338Z","time spent":"2.030320698s","remote":"127.0.0.1:49734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" "} {"level":"warn","ts":"2026-03-27T01:56:00.69189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.968360091s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:56:00.691968Z","caller":"traceutil/trace.go:171","msg":"trace[1980693987] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:609; }","duration":"1.968467212s","start":"2026-03-27T01:55:58.72348Z","end":"2026-03-27T01:56:00.691947Z","steps":["trace[1980693987] 'range keys from in-memory index tree' (duration: 1.968306129s)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:56:00.692006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:55:58.723468Z","time spent":"1.968530374s","remote":"127.0.0.1:49578","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-03-27T01:56:00.692332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.52685ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-27T01:56:00.692438Z","caller":"traceutil/trace.go:171","msg":"trace[1053201829] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:633; }","duration":"694.833457ms","start":"2026-03-27T01:55:59.997587Z","end":"2026-03-27T01:56:00.69242Z","steps":["trace[1053201829] 'read index received' (duration: 32.561µs)","trace[1053201829] 'applied index is now lower than readState.Index' (duration: 694.799275ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:56:00.69246Z","caller":"traceutil/trace.go:171","msg":"trace[719427692] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"1.37437959s","start":"2026-03-27T01:55:59.318062Z","end":"2026-03-27T01:56:00.692441Z","steps":["trace[719427692] 'process raft request' (duration: 671.618367ms)","trace[719427692] 'compare' (duration: 702.136262ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:56:00.692558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:55:59.318033Z","time spent":"1.374460451s","remote":"127.0.0.1:49812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-27T01:56:00.692716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.123652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.35\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-27T01:56:00.692753Z","caller":"traceutil/trace.go:171","msg":"trace[1325649890] range","detail":"{range_begin:/registry/masterleases/199.204.45.35; range_end:; response_count:1; response_revision:610; }","duration":"695.161032ms","start":"2026-03-27T01:55:59.997581Z","end":"2026-03-27T01:56:00.692742Z","steps":["trace[1325649890] 'agreement among raft nodes before linearized reading' (duration: 694.923208ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:56:00.692789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:55:59.997525Z","time spent":"695.256164ms","remote":"127.0.0.1:49602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.35\" "} {"level":"warn","ts":"2026-03-27T01:56:00.692921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.019236ms","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-03-27T01:56:00.692955Z","caller":"traceutil/trace.go:171","msg":"trace[533662502] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:610; }","duration":"263.090117ms","start":"2026-03-27T01:56:00.429855Z","end":"2026-03-27T01:56:00.692945Z","steps":["trace[533662502] 'agreement among raft nodes before linearized reading' (duration: 262.969175ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:56:00.693115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.922943ms","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-03-27T01:56:00.69314Z","caller":"traceutil/trace.go:171","msg":"trace[178475083] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:610; }","duration":"163.983274ms","start":"2026-03-27T01:56:00.529149Z","end":"2026-03-27T01:56:00.693133Z","steps":["trace[178475083] 'agreement among raft nodes before linearized reading' (duration: 163.908913ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:56:00.693206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.215829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-27T01:56:00.693235Z","caller":"traceutil/trace.go:171","msg":"trace[884328409] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:610; }","duration":"164.26341ms","start":"2026-03-27T01:56:00.528962Z","end":"2026-03-27T01:56:00.693226Z","steps":["trace[884328409] 'agreement among raft nodes before linearized reading' (duration: 164.196039ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:56:00.693136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.159829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-27T01:56:00.69329Z","caller":"traceutil/trace.go:171","msg":"trace[1498931017] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:610; }","duration":"263.336323ms","start":"2026-03-27T01:56:00.429944Z","end":"2026-03-27T01:56:00.69328Z","steps":["trace[1498931017] 'agreement among raft nodes before linearized reading' (duration: 263.124039ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:56:00.827012Z","caller":"traceutil/trace.go:171","msg":"trace[463718203] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"130.004575ms","start":"2026-03-27T01:56:00.696988Z","end":"2026-03-27T01:56:00.826992Z","steps":["trace[463718203] 'process raft request' (duration: 129.826442ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:56:00.827406Z","caller":"traceutil/trace.go:171","msg":"trace[1437392763] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"130.380993ms","start":"2026-03-27T01:56:00.696984Z","end":"2026-03-27T01:56:00.827365Z","steps":["trace[1437392763] 'process raft request' (duration: 129.972205ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:56:00.827554Z","caller":"traceutil/trace.go:171","msg":"trace[1774633714] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"129.024208ms","start":"2026-03-27T01:56:00.698518Z","end":"2026-03-27T01:56:00.827543Z","steps":["trace[1774633714] 'process raft request' (duration: 128.807373ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:56:00.827428Z","caller":"traceutil/trace.go:171","msg":"trace[1026449053] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:634; }","duration":"129.353734ms","start":"2026-03-27T01:56:00.698019Z","end":"2026-03-27T01:56:00.827373Z","steps":["trace[1026449053] 'read index received' (duration: 107.104692ms)","trace[1026449053] 'applied index is now lower than readState.Index' (duration: 22.247852ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:56:00.827626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.580178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/envoy-gateway-system/envoy-gateway-config\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:56:00.827722Z","caller":"traceutil/trace.go:171","msg":"trace[2072066540] range","detail":"{range_begin:/registry/configmaps/envoy-gateway-system/envoy-gateway-config; range_end:; response_count:0; response_revision:614; }","duration":"129.714701ms","start":"2026-03-27T01:56:00.697984Z","end":"2026-03-27T01:56:00.827698Z","steps":["trace[2072066540] 'agreement among raft nodes before linearized reading' (duration: 129.543518ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:56:00.827496Z","caller":"traceutil/trace.go:171","msg":"trace[1710827174] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"130.102857ms","start":"2026-03-27T01:56:00.697374Z","end":"2026-03-27T01:56:00.827477Z","steps":["trace[1710827174] 'process raft request' (duration: 129.7901ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:56:00.828888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.342397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:56:00.828948Z","caller":"traceutil/trace.go:171","msg":"trace[1419198846] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:615; }","duration":"127.434378ms","start":"2026-03-27T01:56:00.701498Z","end":"2026-03-27T01:56:00.828933Z","steps":["trace[1419198846] 'agreement among raft nodes before linearized reading' (duration: 127.278845ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:56:01.94138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.551588ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-27T01:56:01.941477Z","caller":"traceutil/trace.go:171","msg":"trace[529716929] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"106.195646ms","start":"2026-03-27T01:56:01.835265Z","end":"2026-03-27T01:56:01.94146Z","steps":["trace[529716929] 'compare' (duration: 101.422916ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:56:02.383948Z","caller":"traceutil/trace.go:171","msg":"trace[321235986] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"156.320522ms","start":"2026-03-27T01:56:02.227601Z","end":"2026-03-27T01:56:02.383921Z","steps":["trace[321235986] 'process raft request' (duration: 154.777453ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:56:06.105554Z","caller":"traceutil/trace.go:171","msg":"trace[2011985542] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"255.13915ms","start":"2026-03-27T01:56:05.850379Z","end":"2026-03-27T01:56:06.105519Z","steps":["trace[2011985542] 'process raft request' (duration: 239.541062ms)","trace[2011985542] 'compare' (duration: 15.247952ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:56:06.56583Z","caller":"traceutil/trace.go:171","msg":"trace[957173895] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"202.63125ms","start":"2026-03-27T01:56:06.363173Z","end":"2026-03-27T01:56:06.565804Z","steps":["trace[957173895] 'process raft request' (duration: 202.436407ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:56:06.572094Z","caller":"traceutil/trace.go:171","msg":"trace[1742377862] linearizableReadLoop","detail":"{readStateIndex:738; appliedIndex:737; }","duration":"152.770177ms","start":"2026-03-27T01:56:06.419311Z","end":"2026-03-27T01:56:06.572081Z","steps":["trace[1742377862] 'read index received' (duration: 146.481001ms)","trace[1742377862] 'applied index is now lower than readState.Index' (duration: 6.288516ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:56:06.572124Z","caller":"traceutil/trace.go:171","msg":"trace[1133923764] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"160.896617ms","start":"2026-03-27T01:56:06.411218Z","end":"2026-03-27T01:56:06.572115Z","steps":["trace[1133923764] 'process raft request' (duration: 160.680802ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:56:06.572259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.93398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-03-27T01:56:06.57229Z","caller":"traceutil/trace.go:171","msg":"trace[270319815] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:711; }","duration":"153.006201ms","start":"2026-03-27T01:56:06.419276Z","end":"2026-03-27T01:56:06.572282Z","steps":["trace[270319815] 'agreement among raft nodes before linearized reading' (duration: 152.892449ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:56:10.863575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.896754ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-27T01:56:10.86376Z","caller":"traceutil/trace.go:171","msg":"trace[1311701835] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"159.588733ms","start":"2026-03-27T01:56:10.704148Z","end":"2026-03-27T01:56:10.863737Z","steps":["trace[1311701835] 'process raft request' (duration: 56.394024ms)","trace[1311701835] 'compare' (duration: 102.546707ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:56:11.145542Z","caller":"traceutil/trace.go:171","msg":"trace[273137691] linearizableReadLoop","detail":"{readStateIndex:770; appliedIndex:769; }","duration":"263.172099ms","start":"2026-03-27T01:56:10.882339Z","end":"2026-03-27T01:56:11.145511Z","steps":["trace[273137691] 'read index received' (duration: 228.747132ms)","trace[273137691] 'applied index is now lower than readState.Index' (duration: 34.423727ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:56:11.145965Z","caller":"traceutil/trace.go:171","msg":"trace[1477786044] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"263.831281ms","start":"2026-03-27T01:56:10.882115Z","end":"2026-03-27T01:56:11.145946Z","steps":["trace[1477786044] 'process raft request' (duration: 228.956136ms)","trace[1477786044] 'compare' (duration: 34.335776ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:56:11.146269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.899252ms","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-03-27T01:56:11.146472Z","caller":"traceutil/trace.go:171","msg":"trace[790962280] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:740; }","duration":"264.133567ms","start":"2026-03-27T01:56:10.882317Z","end":"2026-03-27T01:56:11.14645Z","steps":["trace[790962280] 'agreement among raft nodes before linearized reading' (duration: 263.610937ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:56:11.176463Z","caller":"traceutil/trace.go:171","msg":"trace[1988137097] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"293.625853ms","start":"2026-03-27T01:56:10.882815Z","end":"2026-03-27T01:56:11.176441Z","steps":["trace[1988137097] 'process raft request' (duration: 293.344358ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:56:11.176531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.547653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-27T01:56:11.176664Z","caller":"traceutil/trace.go:171","msg":"trace[1980013546] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:741; }","duration":"292.733146ms","start":"2026-03-27T01:56:10.88388Z","end":"2026-03-27T01:56:11.176613Z","steps":["trace[1980013546] 'agreement among raft nodes before linearized reading' (duration: 292.443031ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:56:17.439387Z","caller":"traceutil/trace.go:171","msg":"trace[2035346172] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"104.635097ms","start":"2026-03-27T01:56:17.334728Z","end":"2026-03-27T01:56:17.439363Z","steps":["trace[2035346172] 'process raft request' (duration: 80.174185ms)","trace[2035346172] 'compare' (duration: 24.337369ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:56:19.3168Z","caller":"traceutil/trace.go:171","msg":"trace[308265060] linearizableReadLoop","detail":"{readStateIndex:812; appliedIndex:811; }","duration":"105.785717ms","start":"2026-03-27T01:56:19.210984Z","end":"2026-03-27T01:56:19.31677Z","steps":["trace[308265060] 'read index received' (duration: 17.154918ms)","trace[308265060] 'applied index is now lower than readState.Index' (duration: 88.629429ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:56:19.316917Z","caller":"traceutil/trace.go:171","msg":"trace[2127730891] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"131.255419ms","start":"2026-03-27T01:56:19.185604Z","end":"2026-03-27T01:56:19.316859Z","steps":["trace[2127730891] 'process raft request' (duration: 42.582039ms)","trace[2127730891] 'compare' (duration: 88.434346ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:56:19.317128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.120884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-27T01:56:19.317179Z","caller":"traceutil/trace.go:171","msg":"trace[119197011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:780; }","duration":"106.236886ms","start":"2026-03-27T01:56:19.210929Z","end":"2026-03-27T01:56:19.317166Z","steps":["trace[119197011] 'agreement among raft nodes before linearized reading' (duration: 106.052972ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:56:19.998812Z","caller":"traceutil/trace.go:171","msg":"trace[598656939] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"137.166328ms","start":"2026-03-27T01:56:19.861614Z","end":"2026-03-27T01:56:19.99878Z","steps":["trace[598656939] 'process raft request' (duration: 136.950284ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:56:20.171668Z","caller":"traceutil/trace.go:171","msg":"trace[38136405] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"164.350182ms","start":"2026-03-27T01:56:20.007283Z","end":"2026-03-27T01:56:20.171633Z","steps":["trace[38136405] 'process raft request' (duration: 164.269729ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:56:20.171715Z","caller":"traceutil/trace.go:171","msg":"trace[969413721] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"166.241896ms","start":"2026-03-27T01:56:20.00543Z","end":"2026-03-27T01:56:20.171672Z","steps":["trace[969413721] 'process raft request' (duration: 113.088043ms)","trace[969413721] 'compare' (duration: 52.836457ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:56:20.467522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.306208ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-27T01:56:20.467782Z","caller":"traceutil/trace.go:171","msg":"trace[1554282140] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"399.703125ms","start":"2026-03-27T01:56:20.068038Z","end":"2026-03-27T01:56:20.467741Z","steps":["trace[1554282140] 'process raft request' (duration: 287.02378ms)","trace[1554282140] 'compare' (duration: 112.146935ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:56:20.467932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:56:20.068016Z","time spent":"399.851338ms","remote":"127.0.0.1:49602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-27T01:56:20.467796Z","caller":"traceutil/trace.go:171","msg":"trace[713303331] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"390.483035ms","start":"2026-03-27T01:56:20.077293Z","end":"2026-03-27T01:56:20.467776Z","steps":["trace[713303331] 'process raft request' (duration: 390.366273ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:56:20.46832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:56:20.077284Z","time spent":"390.848122ms","remote":"127.0.0.1:49722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4856,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-27T01:56:20.749732Z","caller":"traceutil/trace.go:171","msg":"trace[2078466680] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"275.709661ms","start":"2026-03-27T01:56:20.473974Z","end":"2026-03-27T01:56:20.749684Z","steps":["trace[2078466680] 'process raft request' (duration: 236.341903ms)","trace[2078466680] 'compare' (duration: 39.221406ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:56:20.749845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.568528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-03-27T01:56:20.749916Z","caller":"traceutil/trace.go:171","msg":"trace[2008533126] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:789; }","duration":"275.664921ms","start":"2026-03-27T01:56:20.474236Z","end":"2026-03-27T01:56:20.749901Z","steps":["trace[2008533126] 'agreement among raft nodes before linearized reading' (duration: 275.505907ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:56:20.749685Z","caller":"traceutil/trace.go:171","msg":"trace[2133071240] linearizableReadLoop","detail":"{readStateIndex:822; appliedIndex:821; }","duration":"275.415736ms","start":"2026-03-27T01:56:20.47424Z","end":"2026-03-27T01:56:20.749656Z","steps":["trace[2133071240] 'read index received' (duration: 236.105689ms)","trace[2133071240] 'applied index is now lower than readState.Index' (duration: 39.308427ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:56:20.878963Z","caller":"traceutil/trace.go:171","msg":"trace[559828461] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"404.129348ms","start":"2026-03-27T01:56:20.474806Z","end":"2026-03-27T01:56:20.878935Z","steps":["trace[559828461] 'process raft request' (duration: 393.258886ms)","trace[559828461] 'compare' (duration: 10.25631ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:56:20.878984Z","caller":"traceutil/trace.go:171","msg":"trace[660557375] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:822; }","duration":"128.573909ms","start":"2026-03-27T01:56:20.750384Z","end":"2026-03-27T01:56:20.878958Z","steps":["trace[660557375] 'read index received' (duration: 117.685927ms)","trace[660557375] 'applied index is now lower than readState.Index' (duration: 10.887012ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:56:20.879115Z","caller":"traceutil/trace.go:171","msg":"trace[323584296] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"401.304155ms","start":"2026-03-27T01:56:20.477783Z","end":"2026-03-27T01:56:20.879087Z","steps":["trace[323584296] 'process raft request' (duration: 401.055141ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:56:20.879634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.687261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-27T01:56:20.879131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:56:20.474798Z","time spent":"404.23646ms","remote":"127.0.0.1:49822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1290,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-27T01:56:20.879714Z","caller":"traceutil/trace.go:171","msg":"trace[711631881] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:791; }","duration":"155.917994ms","start":"2026-03-27T01:56:20.723782Z","end":"2026-03-27T01:56:20.8797Z","steps":["trace[711631881] 'agreement among raft nodes before linearized reading' (duration: 155.69576ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:56:20.879721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:56:20.477772Z","time spent":"401.817005ms","remote":"127.0.0.1:50044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3852,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-27T01:56:20.879765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.728898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-27T01:56:20.879842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.497316ms","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-03-27T01:56:20.879852Z","caller":"traceutil/trace.go:171","msg":"trace[583415458] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:791; }","duration":"157.885421ms","start":"2026-03-27T01:56:20.721947Z","end":"2026-03-27T01:56:20.879832Z","steps":["trace[583415458] 'agreement among raft nodes before linearized reading' (duration: 157.708777ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:56:20.879892Z","caller":"traceutil/trace.go:171","msg":"trace[819695472] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:791; }","duration":"122.549246ms","start":"2026-03-27T01:56:20.75733Z","end":"2026-03-27T01:56:20.879879Z","steps":["trace[819695472] 'agreement among raft nodes before linearized reading' (duration: 122.391344ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:56:21.164821Z","caller":"traceutil/trace.go:171","msg":"trace[421931804] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"142.62631ms","start":"2026-03-27T01:56:21.022171Z","end":"2026-03-27T01:56:21.164797Z","steps":["trace[421931804] 'process raft request' (duration: 142.465627ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:56:21.429641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.567409ms","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-03-27T01:56:21.429723Z","caller":"traceutil/trace.go:171","msg":"trace[1776638289] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:794; }","duration":"108.699442ms","start":"2026-03-27T01:56:21.321005Z","end":"2026-03-27T01:56:21.429705Z","steps":["trace[1776638289] 'range keys from in-memory index tree' (duration: 108.402956ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:56:21.429947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.265004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:56:21.429994Z","caller":"traceutil/trace.go:171","msg":"trace[1052451781] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:794; }","duration":"100.368226ms","start":"2026-03-27T01:56:21.329616Z","end":"2026-03-27T01:56:21.429984Z","steps":["trace[1052451781] 'count revisions from in-memory index tree' (duration: 100.142172ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:56:23.383119Z","caller":"traceutil/trace.go:171","msg":"trace[1705833007] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"218.277579ms","start":"2026-03-27T01:56:23.164817Z","end":"2026-03-27T01:56:23.383094Z","steps":["trace[1705833007] 'process raft request' (duration: 218.115076ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:56:26.890316Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219120650274531830,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-27T01:56:27.186546Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.297474597s","expected-duration":"1s"} {"level":"info","ts":"2026-03-27T01:56:27.186904Z","caller":"traceutil/trace.go:171","msg":"trace[725908862] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"1.297894015s","start":"2026-03-27T01:56:25.888982Z","end":"2026-03-27T01:56:27.186876Z","steps":["trace[725908862] 'process raft request' (duration: 1.297752963s)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:56:27.187018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:56:25.888964Z","time spent":"1.297983787s","remote":"127.0.0.1:49722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-27T01:56:27.187469Z","caller":"traceutil/trace.go:171","msg":"trace[336717841] linearizableReadLoop","detail":"{readStateIndex:853; appliedIndex:853; }","duration":"798.167738ms","start":"2026-03-27T01:56:26.38925Z","end":"2026-03-27T01:56:27.187417Z","steps":["trace[336717841] 'read index received' (duration: 798.158248ms)","trace[336717841] 'applied index is now lower than readState.Index' (duration: 7.9µs)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:56:27.187671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"798.388572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:56:27.187769Z","caller":"traceutil/trace.go:171","msg":"trace[1184409948] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:819; }","duration":"798.515656ms","start":"2026-03-27T01:56:26.389228Z","end":"2026-03-27T01:56:27.187744Z","steps":["trace[1184409948] 'agreement among raft nodes before linearized reading' (duration: 798.367212ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:56:27.187826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:56:26.389215Z","time spent":"798.594857ms","remote":"127.0.0.1:32914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 "} {"level":"warn","ts":"2026-03-27T01:56:27.188058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"798.671009ms","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-03-27T01:56:27.188138Z","caller":"traceutil/trace.go:171","msg":"trace[1026943488] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:819; }","duration":"798.804901ms","start":"2026-03-27T01:56:26.389317Z","end":"2026-03-27T01:56:27.188122Z","steps":["trace[1026943488] 'agreement among raft nodes before linearized reading' (duration: 798.635338ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:56:27.188242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.82448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:56:27.188293Z","caller":"traceutil/trace.go:171","msg":"trace[2099783234] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:819; }","duration":"665.903352ms","start":"2026-03-27T01:56:26.522375Z","end":"2026-03-27T01:56:27.188278Z","steps":["trace[2099783234] 'agreement among raft nodes before linearized reading' (duration: 665.822021ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:56:27.188349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:56:26.522368Z","time spent":"665.967713ms","remote":"127.0.0.1:32918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} {"level":"warn","ts":"2026-03-27T01:56:27.18827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:56:26.389295Z","time spent":"798.960054ms","remote":"127.0.0.1:32914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-03-27T01:56:27.188583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.585599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:56:27.188675Z","caller":"traceutil/trace.go:171","msg":"trace[1153775520] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:819; }","duration":"649.693701ms","start":"2026-03-27T01:56:26.538968Z","end":"2026-03-27T01:56:27.188661Z","steps":["trace[1153775520] 'agreement among raft nodes before linearized reading' (duration: 649.573759ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:56:27.188719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:56:26.53896Z","time spent":"649.745042ms","remote":"127.0.0.1:32924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 "} {"level":"warn","ts":"2026-03-27T01:56:27.188791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.886657ms","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-03-27T01:56:27.18897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.411521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-27T01:56:27.18897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.742387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:56:27.189046Z","caller":"traceutil/trace.go:171","msg":"trace[2078602019] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:819; }","duration":"799.507684ms","start":"2026-03-27T01:56:26.389523Z","end":"2026-03-27T01:56:27.18903Z","steps":["trace[2078602019] 'agreement among raft nodes before linearized reading' (duration: 799.387232ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:56:27.189089Z","caller":"traceutil/trace.go:171","msg":"trace[1046936662] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:819; }","duration":"666.860449ms","start":"2026-03-27T01:56:26.522181Z","end":"2026-03-27T01:56:27.189041Z","steps":["trace[1046936662] 'agreement among raft nodes before linearized reading' (duration: 666.710616ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:56:27.189103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:56:26.389518Z","time spent":"799.569636ms","remote":"127.0.0.1:32914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 "} {"level":"info","ts":"2026-03-27T01:56:27.18902Z","caller":"traceutil/trace.go:171","msg":"trace[1802138331] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:819; }","duration":"261.282604ms","start":"2026-03-27T01:56:26.927718Z","end":"2026-03-27T01:56:27.189Z","steps":["trace[1802138331] 'agreement among raft nodes before linearized reading' (duration: 260.721694ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:56:27.189182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:56:26.522168Z","time spent":"666.994062ms","remote":"127.0.0.1:32918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-03-27T01:56:27.189242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.040936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:56:27.189282Z","caller":"traceutil/trace.go:171","msg":"trace[1936056847] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:819; }","duration":"651.100008ms","start":"2026-03-27T01:56:26.53817Z","end":"2026-03-27T01:56:27.18927Z","steps":["trace[1936056847] 'agreement among raft nodes before linearized reading' (duration: 651.032137ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:56:27.189332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:56:26.53816Z","time spent":"651.157889ms","remote":"127.0.0.1:32924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-03-27T01:56:27.189287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.054277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-27T01:56:27.188087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.778579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:56:27.189453Z","caller":"traceutil/trace.go:171","msg":"trace[1044130638] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:819; }","duration":"634.173134ms","start":"2026-03-27T01:56:26.555266Z","end":"2026-03-27T01:56:27.189439Z","steps":["trace[1044130638] 'agreement among raft nodes before linearized reading' (duration: 632.763328ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:56:27.18951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:56:26.55526Z","time spent":"634.229625ms","remote":"127.0.0.1:32938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 "} {"level":"warn","ts":"2026-03-27T01:56:27.189718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.331179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-27T01:56:27.189859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.519251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:56:27.189888Z","caller":"traceutil/trace.go:171","msg":"trace[147685079] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:819; }","duration":"651.191809ms","start":"2026-03-27T01:56:26.538203Z","end":"2026-03-27T01:56:27.189395Z","steps":["trace[147685079] 'agreement among raft nodes before linearized reading' (duration: 651.010656ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:56:27.189122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.818039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-27T01:56:27.189992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:56:26.538194Z","time spent":"651.776629ms","remote":"127.0.0.1:32924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"warn","ts":"2026-03-27T01:56:27.189998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.421389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-27T01:56:27.190014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.450693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-27T01:56:27.190048Z","caller":"traceutil/trace.go:171","msg":"trace[2086095908] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:819; }","duration":"634.513591ms","start":"2026-03-27T01:56:26.555522Z","end":"2026-03-27T01:56:27.190035Z","steps":["trace[2086095908] 'agreement among raft nodes before linearized reading' (duration: 634.436389ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:56:27.190152Z","caller":"traceutil/trace.go:171","msg":"trace[2065574352] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:819; }","duration":"525.633805ms","start":"2026-03-27T01:56:26.664494Z","end":"2026-03-27T01:56:27.190128Z","steps":["trace[2065574352] 'agreement among raft nodes before linearized reading' (duration: 525.435002ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:56:27.190171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:56:26.555499Z","time spent":"634.653723ms","remote":"127.0.0.1:32938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-03-27T01:56:27.190203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:56:26.664481Z","time spent":"525.710487ms","remote":"127.0.0.1:50050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"info","ts":"2026-03-27T01:56:27.190025Z","caller":"traceutil/trace.go:171","msg":"trace[1589552817] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:819; }","duration":"667.727665ms","start":"2026-03-27T01:56:26.522283Z","end":"2026-03-27T01:56:27.19001Z","steps":["trace[1589552817] 'agreement among raft nodes before linearized reading' (duration: 666.652625ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:56:27.18993Z","caller":"traceutil/trace.go:171","msg":"trace[1927265169] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:819; }","duration":"634.600772ms","start":"2026-03-27T01:56:26.555312Z","end":"2026-03-27T01:56:27.189913Z","steps":["trace[1927265169] 'agreement among raft nodes before linearized reading' (duration: 634.46552ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:56:27.189888Z","caller":"traceutil/trace.go:171","msg":"trace[817720492] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:819; }","duration":"466.537653ms","start":"2026-03-27T01:56:26.723333Z","end":"2026-03-27T01:56:27.189871Z","steps":["trace[817720492] 'agreement among raft nodes before linearized reading' (duration: 466.341219ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:56:27.19045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:56:26.723315Z","time spent":"467.122003ms","remote":"127.0.0.1:49578","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-03-27T01:56:27.190465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:56:26.555296Z","time spent":"635.07172ms","remote":"127.0.0.1:32938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-03-27T01:56:27.192352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:56:26.52226Z","time spent":"668.081982ms","remote":"127.0.0.1:32918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 "} {"level":"info","ts":"2026-03-27T01:56:35.714114Z","caller":"traceutil/trace.go:171","msg":"trace[962534545] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"119.997689ms","start":"2026-03-27T01:56:35.594093Z","end":"2026-03-27T01:56:35.71409Z","steps":["trace[962534545] 'process raft request' (duration: 119.052772ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:56:35.71454Z","caller":"traceutil/trace.go:171","msg":"trace[1360888491] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"119.733386ms","start":"2026-03-27T01:56:35.594777Z","end":"2026-03-27T01:56:35.71451Z","steps":["trace[1360888491] 'process raft request' (duration: 119.275767ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:57:02.100207Z","caller":"traceutil/trace.go:171","msg":"trace[380849565] linearizableReadLoop","detail":"{readStateIndex:1073; appliedIndex:1072; }","duration":"379.082928ms","start":"2026-03-27T01:57:01.7211Z","end":"2026-03-27T01:57:02.100183Z","steps":["trace[380849565] 'read index received' (duration: 378.941666ms)","trace[380849565] 'applied index is now lower than readState.Index' (duration: 139.952µs)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:57:02.100249Z","caller":"traceutil/trace.go:171","msg":"trace[2090763577] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"392.895683ms","start":"2026-03-27T01:57:01.707327Z","end":"2026-03-27T01:57:02.100223Z","steps":["trace[2090763577] 'process raft request' (duration: 392.690859ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:57:02.100377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:57:01.707307Z","time spent":"393.004884ms","remote":"127.0.0.1:49812","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-03-27T01:57:02.100377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.251902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:57:02.100413Z","caller":"traceutil/trace.go:171","msg":"trace[1347262999] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1031; }","duration":"379.327643ms","start":"2026-03-27T01:57:01.721075Z","end":"2026-03-27T01:57:02.100403Z","steps":["trace[1347262999] 'agreement among raft nodes before linearized reading' (duration: 379.251682ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:57:02.100453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:57:01.721061Z","time spent":"379.384474ms","remote":"127.0.0.1:49578","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-03-27T01:57:02.600483Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219120650274532287,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-27T01:57:02.787182Z","caller":"traceutil/trace.go:171","msg":"trace[1385480498] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"715.584373ms","start":"2026-03-27T01:57:02.071556Z","end":"2026-03-27T01:57:02.78714Z","steps":["trace[1385480498] 'process raft request' (duration: 715.472231ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:57:02.787324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:57:02.071529Z","time spent":"715.710566ms","remote":"127.0.0.1:49812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-27T01:57:02.902498Z","caller":"traceutil/trace.go:171","msg":"trace[1945648801] linearizableReadLoop","detail":"{readStateIndex:1075; appliedIndex:1074; }","duration":"802.184888ms","start":"2026-03-27T01:57:02.100293Z","end":"2026-03-27T01:57:02.902478Z","steps":["trace[1945648801] 'read index received' (duration: 686.857674ms)","trace[1945648801] 'applied index is now lower than readState.Index' (duration: 115.326184ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:57:02.902706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.064179572s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-27T01:57:02.902744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.177753151s","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-03-27T01:57:02.902756Z","caller":"traceutil/trace.go:171","msg":"trace[705075959] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1033; }","duration":"1.064278473s","start":"2026-03-27T01:57:01.838464Z","end":"2026-03-27T01:57:02.902742Z","steps":["trace[705075959] 'agreement among raft nodes before linearized reading' (duration: 1.06410918s)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:57:02.902776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"796.585115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-27T01:57:02.902776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.186275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:57:02.902781Z","caller":"traceutil/trace.go:171","msg":"trace[2023465338] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1033; }","duration":"1.177833023s","start":"2026-03-27T01:57:01.724938Z","end":"2026-03-27T01:57:02.902771Z","steps":["trace[2023465338] 'agreement among raft nodes before linearized reading' (duration: 1.17766815s)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:57:02.902756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.632381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:57:02.902818Z","caller":"traceutil/trace.go:171","msg":"trace[2139907897] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1033; }","duration":"796.652516ms","start":"2026-03-27T01:57:02.106151Z","end":"2026-03-27T01:57:02.902804Z","steps":["trace[2139907897] 'agreement among raft nodes before linearized reading' (duration: 796.596395ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:57:02.902825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:57:01.724921Z","time spent":"1.177895864s","remote":"127.0.0.1:49812","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":"info","ts":"2026-03-27T01:57:02.902839Z","caller":"traceutil/trace.go:171","msg":"trace[695011018] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1033; }","duration":"453.792794ms","start":"2026-03-27T01:57:02.449035Z","end":"2026-03-27T01:57:02.902827Z","steps":["trace[695011018] 'agreement among raft nodes before linearized reading' (duration: 453.601381ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:57:02.902789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:57:01.838447Z","time spent":"1.064336844s","remote":"127.0.0.1:49812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-27T01:57:02.90282Z","caller":"traceutil/trace.go:171","msg":"trace[1786459489] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1033; }","duration":"232.269866ms","start":"2026-03-27T01:57:02.670538Z","end":"2026-03-27T01:57:02.902808Z","steps":["trace[1786459489] 'agreement among raft nodes before linearized reading' (duration: 232.210455ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:57:02.902851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:57:02.106125Z","time spent":"796.716528ms","remote":"127.0.0.1:46118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"info","ts":"2026-03-27T01:57:02.902593Z","caller":"traceutil/trace.go:171","msg":"trace[636936623] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"557.26634ms","start":"2026-03-27T01:57:02.345297Z","end":"2026-03-27T01:57:02.902564Z","steps":["trace[636936623] 'process raft request' (duration: 555.021918ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:57:02.902694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.059061238s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-27T01:57:02.902977Z","caller":"traceutil/trace.go:171","msg":"trace[1757211871] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1033; }","duration":"1.059360853s","start":"2026-03-27T01:57:01.843608Z","end":"2026-03-27T01:57:02.902969Z","steps":["trace[1757211871] 'agreement among raft nodes before linearized reading' (duration: 1.058921115s)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:57:02.903016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:57:01.843599Z","time spent":"1.059395634s","remote":"127.0.0.1:49812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-27T01:57:02.902695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.2412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:57:02.903066Z","caller":"traceutil/trace.go:171","msg":"trace[1554372471] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1033; }","duration":"801.658087ms","start":"2026-03-27T01:57:02.101397Z","end":"2026-03-27T01:57:02.903055Z","steps":["trace[1554372471] 'agreement among raft nodes before linearized reading' (duration: 801.22947ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:57:02.903094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:57:02.101387Z","time spent":"801.700369ms","remote":"127.0.0.1:49590","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-03-27T01:57:02.902885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:57:02.449016Z","time spent":"453.860005ms","remote":"127.0.0.1:49786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-03-27T01:57:02.903406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:57:02.345278Z","time spent":"557.679647ms","remote":"127.0.0.1:49812","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-03-27T01:57:24.534353Z","caller":"traceutil/trace.go:171","msg":"trace[1696347208] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"170.663682ms","start":"2026-03-27T01:57:24.363656Z","end":"2026-03-27T01:57:24.53432Z","steps":["trace[1696347208] 'process raft request' (duration: 170.354816ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:57:25.053062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.616466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:57:25.053169Z","caller":"traceutil/trace.go:171","msg":"trace[209046503] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1105; }","duration":"330.767388ms","start":"2026-03-27T01:57:24.722367Z","end":"2026-03-27T01:57:25.053135Z","steps":["trace[209046503] 'range keys from in-memory index tree' (duration: 330.529445ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:57:25.053331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:57:24.722343Z","time spent":"330.972793ms","remote":"127.0.0.1:49578","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-03-27T01:57:25.078626Z","caller":"traceutil/trace.go:171","msg":"trace[2018340055] linearizableReadLoop","detail":"{readStateIndex:1152; appliedIndex:1151; }","duration":"207.615491ms","start":"2026-03-27T01:57:24.870992Z","end":"2026-03-27T01:57:25.078607Z","steps":["trace[2018340055] 'read index received' (duration: 207.431738ms)","trace[2018340055] 'applied index is now lower than readState.Index' (duration: 182.843µs)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:57:25.078705Z","caller":"traceutil/trace.go:171","msg":"trace[1742901551] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"216.596417ms","start":"2026-03-27T01:57:24.862083Z","end":"2026-03-27T01:57:25.07868Z","steps":["trace[1742901551] 'process raft request' (duration: 216.377853ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:57:25.078811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.757815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-27T01:57:25.078908Z","caller":"traceutil/trace.go:171","msg":"trace[205613964] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1106; }","duration":"207.935428ms","start":"2026-03-27T01:57:24.87096Z","end":"2026-03-27T01:57:25.078896Z","steps":["trace[205613964] 'agreement among raft nodes before linearized reading' (duration: 207.753784ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:57:26.738235Z","caller":"traceutil/trace.go:171","msg":"trace[556021950] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"532.208308ms","start":"2026-03-27T01:57:26.206Z","end":"2026-03-27T01:57:26.738208Z","steps":["trace[556021950] 'process raft request' (duration: 531.994273ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:57:26.738382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:57:26.205977Z","time spent":"532.324111ms","remote":"127.0.0.1:49812","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-03-27T01:57:27.419268Z","caller":"traceutil/trace.go:171","msg":"trace[2031855997] linearizableReadLoop","detail":"{readStateIndex:1159; appliedIndex:1158; }","duration":"317.915563ms","start":"2026-03-27T01:57:27.101322Z","end":"2026-03-27T01:57:27.419238Z","steps":["trace[2031855997] 'read index received' (duration: 317.596378ms)","trace[2031855997] 'applied index is now lower than readState.Index' (duration: 318.115µs)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:57:27.419291Z","caller":"traceutil/trace.go:171","msg":"trace[826375047] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"334.340116ms","start":"2026-03-27T01:57:27.084925Z","end":"2026-03-27T01:57:27.419265Z","steps":["trace[826375047] 'process raft request' (duration: 334.151002ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:57:27.419547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.975857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-27T01:57:27.419569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.248209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-27T01:57:27.419611Z","caller":"traceutil/trace.go:171","msg":"trace[481906734] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1112; }","duration":"291.070069ms","start":"2026-03-27T01:57:27.128525Z","end":"2026-03-27T01:57:27.419595Z","steps":["trace[481906734] 'agreement among raft nodes before linearized reading' (duration: 290.895905ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:57:27.419576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:57:27.084884Z","time spent":"334.5624ms","remote":"127.0.0.1:49812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-27T01:57:27.419621Z","caller":"traceutil/trace.go:171","msg":"trace[187732730] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1112; }","duration":"318.342661ms","start":"2026-03-27T01:57:27.101264Z","end":"2026-03-27T01:57:27.419606Z","steps":["trace[187732730] 'agreement among raft nodes before linearized reading' (duration: 318.076136ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:57:27.419808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:57:27.101242Z","time spent":"318.554114ms","remote":"127.0.0.1:49812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-27T01:57:27.419814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.215471ms","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-03-27T01:57:27.419942Z","caller":"traceutil/trace.go:171","msg":"trace[1347105522] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1112; }","duration":"291.357984ms","start":"2026-03-27T01:57:27.128567Z","end":"2026-03-27T01:57:27.419925Z","steps":["trace[1347105522] 'agreement among raft nodes before linearized reading' (duration: 291.036809ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:57:27.41956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.452635ms","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-03-27T01:57:27.420077Z","caller":"traceutil/trace.go:171","msg":"trace[1634384705] 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:1112; }","duration":"167.063896ms","start":"2026-03-27T01:57:27.252994Z","end":"2026-03-27T01:57:27.420058Z","steps":["trace[1634384705] 'agreement among raft nodes before linearized reading' (duration: 166.460184ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:57:29.667245Z","caller":"traceutil/trace.go:171","msg":"trace[1159511658] linearizableReadLoop","detail":"{readStateIndex:1165; appliedIndex:1164; }","duration":"229.715019ms","start":"2026-03-27T01:57:29.437506Z","end":"2026-03-27T01:57:29.667221Z","steps":["trace[1159511658] 'read index received' (duration: 229.589686ms)","trace[1159511658] 'applied index is now lower than readState.Index' (duration: 124.343µs)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:57:29.667291Z","caller":"traceutil/trace.go:171","msg":"trace[253389413] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"242.343122ms","start":"2026-03-27T01:57:29.424918Z","end":"2026-03-27T01:57:29.667261Z","steps":["trace[253389413] 'process raft request' (duration: 242.168358ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:57:29.667533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.004045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-27T01:57:29.667612Z","caller":"traceutil/trace.go:171","msg":"trace[448206752] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1118; }","duration":"230.124527ms","start":"2026-03-27T01:57:29.437469Z","end":"2026-03-27T01:57:29.667594Z","steps":["trace[448206752] 'agreement among raft nodes before linearized reading' (duration: 229.877513ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:57:29.667624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.689222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-27T01:57:29.66778Z","caller":"traceutil/trace.go:171","msg":"trace[768445658] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1118; }","duration":"164.894566ms","start":"2026-03-27T01:57:29.502872Z","end":"2026-03-27T01:57:29.667767Z","steps":["trace[768445658] 'agreement among raft nodes before linearized reading' (duration: 164.486959ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:57:29.668151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.011507ms","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-03-27T01:57:29.668202Z","caller":"traceutil/trace.go:171","msg":"trace[1319467009] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1118; }","duration":"165.066438ms","start":"2026-03-27T01:57:29.503123Z","end":"2026-03-27T01:57:29.66819Z","steps":["trace[1319467009] 'agreement among raft nodes before linearized reading' (duration: 164.332275ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:57:35.900994Z","caller":"traceutil/trace.go:171","msg":"trace[1212115811] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"100.594622ms","start":"2026-03-27T01:57:35.800374Z","end":"2026-03-27T01:57:35.900968Z","steps":["trace[1212115811] 'process raft request' (duration: 100.551282ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:57:35.901005Z","caller":"traceutil/trace.go:171","msg":"trace[1067198718] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"100.867466ms","start":"2026-03-27T01:57:35.800107Z","end":"2026-03-27T01:57:35.900975Z","steps":["trace[1067198718] 'process raft request' (duration: 99.012592ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:57:35.901227Z","caller":"traceutil/trace.go:171","msg":"trace[19261434] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"100.824847ms","start":"2026-03-27T01:57:35.80021Z","end":"2026-03-27T01:57:35.901035Z","steps":["trace[19261434] 'process raft request' (duration: 100.673204ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:57:43.159465Z","caller":"traceutil/trace.go:171","msg":"trace[921319922] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"294.617583ms","start":"2026-03-27T01:57:42.864827Z","end":"2026-03-27T01:57:43.159445Z","steps":["trace[921319922] 'process raft request' (duration: 294.490961ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:57:44.130377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.068453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:57:44.130491Z","caller":"traceutil/trace.go:171","msg":"trace[178554406] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1214; }","duration":"407.236357ms","start":"2026-03-27T01:57:43.723231Z","end":"2026-03-27T01:57:44.130468Z","steps":["trace[178554406] 'range keys from in-memory index tree' (duration: 406.985592ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:57:44.130445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.827158ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-27T01:57:44.130542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:57:43.723216Z","time spent":"407.315698ms","remote":"127.0.0.1:49578","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-03-27T01:57:44.13058Z","caller":"traceutil/trace.go:171","msg":"trace[386890096] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1214; }","duration":"161.990113ms","start":"2026-03-27T01:57:43.968568Z","end":"2026-03-27T01:57:44.130558Z","steps":["trace[386890096] 'range keys from in-memory index tree' (duration: 161.800528ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:57:44.130725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.813271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:57:44.130778Z","caller":"traceutil/trace.go:171","msg":"trace[930040867] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1214; }","duration":"393.900832ms","start":"2026-03-27T01:57:43.736862Z","end":"2026-03-27T01:57:44.130763Z","steps":["trace[930040867] 'count revisions from in-memory index tree' (duration: 393.703779ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:57:44.13081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:57:43.736805Z","time spent":"393.995543ms","remote":"127.0.0.1:46156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-03-27T01:57:44.130939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.882033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-27T01:57:44.13103Z","caller":"traceutil/trace.go:171","msg":"trace[153172854] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1214; }","duration":"455.994925ms","start":"2026-03-27T01:57:43.675012Z","end":"2026-03-27T01:57:44.131007Z","steps":["trace[153172854] 'count revisions from in-memory index tree' (duration: 455.747791ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:57:44.131074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:57:43.674973Z","time spent":"456.090477ms","remote":"127.0.0.1:48872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":4,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-03-27T01:57:44.174773Z","caller":"traceutil/trace.go:171","msg":"trace[527440188] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"437.769669ms","start":"2026-03-27T01:57:43.736988Z","end":"2026-03-27T01:57:44.174758Z","steps":["trace[527440188] 'process raft request' (duration: 437.352742ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:57:44.174911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:57:43.736936Z","time spent":"437.900392ms","remote":"127.0.0.1:49812","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-03-27T01:57:44.175063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.562486ms","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-03-27T01:57:44.175106Z","caller":"traceutil/trace.go:171","msg":"trace[1342368026] linearizableReadLoop","detail":"{readStateIndex:1267; appliedIndex:1266; }","duration":"341.993375ms","start":"2026-03-27T01:57:43.832495Z","end":"2026-03-27T01:57:44.174488Z","steps":["trace[1342368026] 'read index received' (duration: 341.825773ms)","trace[1342368026] 'applied index is now lower than readState.Index' (duration: 166.532µs)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:57:44.175123Z","caller":"traceutil/trace.go:171","msg":"trace[1706995316] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1215; }","duration":"342.677027ms","start":"2026-03-27T01:57:43.832431Z","end":"2026-03-27T01:57:44.175108Z","steps":["trace[1706995316] 'agreement among raft nodes before linearized reading' (duration: 342.498774ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:57:44.175162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:57:43.832371Z","time spent":"342.78157ms","remote":"127.0.0.1:49812","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":"info","ts":"2026-03-27T01:57:44.175508Z","caller":"traceutil/trace.go:171","msg":"trace[1938289912] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"209.284493ms","start":"2026-03-27T01:57:43.966212Z","end":"2026-03-27T01:57:44.175497Z","steps":["trace[1938289912] 'process raft request' (duration: 209.225092ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:57:44.17571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.592374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-27T01:57:44.175733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.962722ms","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-03-27T01:57:44.175749Z","caller":"traceutil/trace.go:171","msg":"trace[868871624] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1216; }","duration":"237.675386ms","start":"2026-03-27T01:57:43.938062Z","end":"2026-03-27T01:57:44.175738Z","steps":["trace[868871624] 'agreement among raft nodes before linearized reading' (duration: 237.536223ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:57:44.175765Z","caller":"traceutil/trace.go:171","msg":"trace[960649017] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1216; }","duration":"230.997923ms","start":"2026-03-27T01:57:43.944758Z","end":"2026-03-27T01:57:44.175756Z","steps":["trace[960649017] 'agreement among raft nodes before linearized reading' (duration: 230.84841ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:57:44.175764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.28441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-27T01:57:44.175821Z","caller":"traceutil/trace.go:171","msg":"trace[1159680765] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1216; }","duration":"236.377172ms","start":"2026-03-27T01:57:43.939423Z","end":"2026-03-27T01:57:44.1758Z","steps":["trace[1159680765] 'agreement among raft nodes before linearized reading' (duration: 236.180388ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:57:55.639167Z","caller":"traceutil/trace.go:171","msg":"trace[1515669002] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"128.460001ms","start":"2026-03-27T01:57:55.510681Z","end":"2026-03-27T01:57:55.639141Z","steps":["trace[1515669002] 'process raft request' (duration: 128.294238ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:57:58.342122Z","caller":"traceutil/trace.go:171","msg":"trace[2132890769] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"124.131742ms","start":"2026-03-27T01:57:58.217961Z","end":"2026-03-27T01:57:58.342092Z","steps":["trace[2132890769] 'process raft request' (duration: 123.965599ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:57:58.427083Z","caller":"traceutil/trace.go:171","msg":"trace[2123825511] linearizableReadLoop","detail":"{readStateIndex:1334; appliedIndex:1332; }","duration":"186.93555ms","start":"2026-03-27T01:57:58.240121Z","end":"2026-03-27T01:57:58.427057Z","steps":["trace[2123825511] 'read index received' (duration: 101.809917ms)","trace[2123825511] 'applied index is now lower than readState.Index' (duration: 85.124583ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:57:58.42712Z","caller":"traceutil/trace.go:171","msg":"trace[1936745887] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"206.107249ms","start":"2026-03-27T01:57:58.220982Z","end":"2026-03-27T01:57:58.42709Z","steps":["trace[1936745887] 'process raft request' (duration: 205.922225ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:57:58.427313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.175964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-27T01:57:58.427377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.574081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-27T01:57:58.427383Z","caller":"traceutil/trace.go:171","msg":"trace[1534100517] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1279; }","duration":"187.300637ms","start":"2026-03-27T01:57:58.240067Z","end":"2026-03-27T01:57:58.427367Z","steps":["trace[1534100517] 'agreement among raft nodes before linearized reading' (duration: 187.087403ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:57:58.427413Z","caller":"traceutil/trace.go:171","msg":"trace[2107909446] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1279; }","duration":"161.641703ms","start":"2026-03-27T01:57:58.265758Z","end":"2026-03-27T01:57:58.4274Z","steps":["trace[2107909446] 'agreement among raft nodes before linearized reading' (duration: 161.486569ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:57:58.427534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.504443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-27T01:57:58.427588Z","caller":"traceutil/trace.go:171","msg":"trace[1285238552] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1279; }","duration":"165.648825ms","start":"2026-03-27T01:57:58.261924Z","end":"2026-03-27T01:57:58.427573Z","steps":["trace[1285238552] 'agreement among raft nodes before linearized reading' (duration: 165.37271ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:57:58.427992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.333745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-27T01:57:58.428034Z","caller":"traceutil/trace.go:171","msg":"trace[1417187975] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1279; }","duration":"167.412627ms","start":"2026-03-27T01:57:58.260612Z","end":"2026-03-27T01:57:58.428025Z","steps":["trace[1417187975] 'agreement among raft nodes before linearized reading' (duration: 166.600542ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:00.097128Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219120650274532869,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-27T01:58:00.461604Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.226141992s","expected-duration":"1s"} {"level":"info","ts":"2026-03-27T01:58:00.461902Z","caller":"traceutil/trace.go:171","msg":"trace[859105530] linearizableReadLoop","detail":"{readStateIndex:1342; appliedIndex:1341; }","duration":"865.559742ms","start":"2026-03-27T01:57:59.596323Z","end":"2026-03-27T01:58:00.461883Z","steps":["trace[859105530] 'read index received' (duration: 865.44958ms)","trace[859105530] 'applied index is now lower than readState.Index' (duration: 109.282µs)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:58:00.462049Z","caller":"traceutil/trace.go:171","msg":"trace[1860380060] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"1.226692242s","start":"2026-03-27T01:57:59.235325Z","end":"2026-03-27T01:58:00.462018Z","steps":["trace[1860380060] 'process raft request' (duration: 1.226438208s)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:00.462161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"865.787755ms","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-03-27T01:58:00.46229Z","caller":"traceutil/trace.go:171","msg":"trace[538587933] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1287; }","duration":"865.957219ms","start":"2026-03-27T01:57:59.596288Z","end":"2026-03-27T01:58:00.462246Z","steps":["trace[538587933] 'agreement among raft nodes before linearized reading' (duration: 865.726765ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:00.462368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:57:59.596273Z","time spent":"866.05865ms","remote":"127.0.0.1:32914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-03-27T01:58:00.462391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:57:59.235304Z","time spent":"1.226818224s","remote":"127.0.0.1:49812","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-03-27T01:58:00.921759Z","caller":"traceutil/trace.go:171","msg":"trace[274646711] linearizableReadLoop","detail":"{readStateIndex:1343; appliedIndex:1342; }","duration":"459.7919ms","start":"2026-03-27T01:58:00.461944Z","end":"2026-03-27T01:58:00.921735Z","steps":["trace[274646711] 'read index received' (duration: 401.213257ms)","trace[274646711] 'applied index is now lower than readState.Index' (duration: 58.575723ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:58:00.922017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.611442ms","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-03-27T01:58:00.922021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.035232ms","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-03-27T01:58:00.922058Z","caller":"traceutil/trace.go:171","msg":"trace[1308845111] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1288; }","duration":"478.709744ms","start":"2026-03-27T01:58:00.443339Z","end":"2026-03-27T01:58:00.922049Z","steps":["trace[1308845111] 'agreement among raft nodes before linearized reading' (duration: 478.539042ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:00.922097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:58:00.443317Z","time spent":"478.770715ms","remote":"127.0.0.1:49812","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-03-27T01:58:00.922248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.582819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-27T01:58:00.92228Z","caller":"traceutil/trace.go:171","msg":"trace[1524511355] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1288; }","duration":"479.6525ms","start":"2026-03-27T01:58:00.442619Z","end":"2026-03-27T01:58:00.922272Z","steps":["trace[1524511355] 'agreement among raft nodes before linearized reading' (duration: 479.572389ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:00.922305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:58:00.442595Z","time spent":"479.705132ms","remote":"127.0.0.1:49812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-27T01:58:00.92231Z","caller":"traceutil/trace.go:171","msg":"trace[1591171778] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"575.038861ms","start":"2026-03-27T01:58:00.347256Z","end":"2026-03-27T01:58:00.922295Z","steps":["trace[1591171778] 'process raft request' (duration: 515.855487ms)","trace[1591171778] 'compare' (duration: 58.484622ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:58:00.922404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:58:00.347232Z","time spent":"575.114213ms","remote":"127.0.0.1:49812","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-03-27T01:58:00.922472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.976023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-27T01:58:00.922506Z","caller":"traceutil/trace.go:171","msg":"trace[2066409991] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1288; }","duration":"493.041674ms","start":"2026-03-27T01:58:00.429457Z","end":"2026-03-27T01:58:00.922498Z","steps":["trace[2066409991] 'agreement among raft nodes before linearized reading' (duration: 492.981443ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:00.922535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:58:00.429439Z","time spent":"493.088646ms","remote":"127.0.0.1:49998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-03-27T01:58:00.922636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.586288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-27T01:58:00.922674Z","caller":"traceutil/trace.go:171","msg":"trace[318827468] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1288; }","duration":"475.648239ms","start":"2026-03-27T01:58:00.447016Z","end":"2026-03-27T01:58:00.922664Z","steps":["trace[318827468] 'agreement among raft nodes before linearized reading' (duration: 475.545727ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:00.922711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:58:00.447002Z","time spent":"475.70237ms","remote":"127.0.0.1:49812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-27T01:58:00.922729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.460748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.35\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-27T01:58:00.922756Z","caller":"traceutil/trace.go:171","msg":"trace[686124999] range","detail":"{range_begin:/registry/masterleases/199.204.45.35; range_end:; response_count:1; response_revision:1288; }","duration":"920.524799ms","start":"2026-03-27T01:58:00.002223Z","end":"2026-03-27T01:58:00.922748Z","steps":["trace[686124999] 'agreement among raft nodes before linearized reading' (duration: 920.430667ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:00.922789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:58:00.002202Z","time spent":"920.58188ms","remote":"127.0.0.1:49602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.35\" "} {"level":"warn","ts":"2026-03-27T01:58:00.92293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.231718994s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:58:00.922967Z","caller":"traceutil/trace.go:171","msg":"trace[2041342029] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1288; }","duration":"1.231794345s","start":"2026-03-27T01:57:59.691164Z","end":"2026-03-27T01:58:00.922959Z","steps":["trace[2041342029] 'agreement among raft nodes before linearized reading' (duration: 1.231742264s)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:00.922996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:57:59.691139Z","time spent":"1.231848636s","remote":"127.0.0.1:46206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-03-27T01:58:00.922091Z","caller":"traceutil/trace.go:171","msg":"trace[396306032] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1288; }","duration":"478.147863ms","start":"2026-03-27T01:58:00.443924Z","end":"2026-03-27T01:58:00.922072Z","steps":["trace[396306032] 'agreement among raft nodes before linearized reading' (duration: 477.883088ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:00.923088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:58:00.443905Z","time spent":"479.173252ms","remote":"127.0.0.1:49812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-27T01:58:00.923224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.840918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:58:00.923263Z","caller":"traceutil/trace.go:171","msg":"trace[97454857] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1288; }","duration":"289.915199ms","start":"2026-03-27T01:58:00.633336Z","end":"2026-03-27T01:58:00.923251Z","steps":["trace[97454857] 'agreement among raft nodes before linearized reading' (duration: 289.856998ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:00.923454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.77914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:58:00.92349Z","caller":"traceutil/trace.go:171","msg":"trace[324232306] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1288; }","duration":"201.848051ms","start":"2026-03-27T01:58:00.721633Z","end":"2026-03-27T01:58:00.923481Z","steps":["trace[324232306] 'agreement among raft nodes before linearized reading' (duration: 201.800161ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:58:01.171161Z","caller":"traceutil/trace.go:171","msg":"trace[503700841] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"173.276144ms","start":"2026-03-27T01:58:00.99786Z","end":"2026-03-27T01:58:01.171136Z","steps":["trace[503700841] 'process raft request' (duration: 91.688244ms)","trace[503700841] 'compare' (duration: 81.364436ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:58:01.228139Z","caller":"traceutil/trace.go:171","msg":"trace[1706557660] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"199.273705ms","start":"2026-03-27T01:58:01.02884Z","end":"2026-03-27T01:58:01.228114Z","steps":["trace[1706557660] 'process raft request' (duration: 199.120392ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:58:01.619717Z","caller":"traceutil/trace.go:171","msg":"trace[1514520585] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"134.914698ms","start":"2026-03-27T01:58:01.484779Z","end":"2026-03-27T01:58:01.619694Z","steps":["trace[1514520585] 'process raft request' (duration: 95.523473ms)","trace[1514520585] 'compare' (duration: 39.222781ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:58:01.619726Z","caller":"traceutil/trace.go:171","msg":"trace[1385347529] linearizableReadLoop","detail":"{readStateIndex:1352; appliedIndex:1351; }","duration":"124.116852ms","start":"2026-03-27T01:58:01.495537Z","end":"2026-03-27T01:58:01.619654Z","steps":["trace[1385347529] 'read index received' (duration: 84.724206ms)","trace[1385347529] 'applied index is now lower than readState.Index' (duration: 39.391746ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:58:01.620035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.909459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:58:01.620068Z","caller":"traceutil/trace.go:171","msg":"trace[1087563275] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1296; }","duration":"101.96113ms","start":"2026-03-27T01:58:01.5181Z","end":"2026-03-27T01:58:01.620061Z","steps":["trace[1087563275] 'agreement among raft nodes before linearized reading' (duration: 101.852078ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:01.620096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.325105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-27T01:58:01.620065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.140779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-27T01:58:01.620124Z","caller":"traceutil/trace.go:171","msg":"trace[1762051817] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1296; }","duration":"124.617571ms","start":"2026-03-27T01:58:01.495498Z","end":"2026-03-27T01:58:01.620115Z","steps":["trace[1762051817] 'agreement among raft nodes before linearized reading' (duration: 124.323045ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:58:01.620229Z","caller":"traceutil/trace.go:171","msg":"trace[1183645225] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1296; }","duration":"110.316742ms","start":"2026-03-27T01:58:01.509811Z","end":"2026-03-27T01:58:01.620127Z","steps":["trace[1183645225] 'agreement among raft nodes before linearized reading' (duration: 110.125018ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:03.826094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.102747923s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:58:03.826157Z","caller":"traceutil/trace.go:171","msg":"trace[166719551] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1301; }","duration":"1.102859565s","start":"2026-03-27T01:58:02.723282Z","end":"2026-03-27T01:58:03.826141Z","steps":["trace[166719551] 'range keys from in-memory index tree' (duration: 1.102658002s)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:03.826188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:58:02.72326Z","time spent":"1.102921726s","remote":"127.0.0.1:49590","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-03-27T01:58:03.826328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.883999ms","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-03-27T01:58:03.826401Z","caller":"traceutil/trace.go:171","msg":"trace[1235113099] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1301; }","duration":"908.012282ms","start":"2026-03-27T01:58:02.918371Z","end":"2026-03-27T01:58:03.826383Z","steps":["trace[1235113099] 'count revisions from in-memory index tree' (duration: 907.772618ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:03.826447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:58:02.918348Z","time spent":"908.087714ms","remote":"127.0.0.1:49746","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-03-27T01:58:03.826493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.790765ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-27T01:58:03.826536Z","caller":"traceutil/trace.go:171","msg":"trace[2066925391] linearizableReadLoop","detail":"{readStateIndex:1358; appliedIndex:1357; }","duration":"810.271509ms","start":"2026-03-27T01:58:03.016258Z","end":"2026-03-27T01:58:03.82653Z","steps":["trace[2066925391] 'read index received' (duration: 400.289752ms)","trace[2066925391] 'applied index is now lower than readState.Index' (duration: 409.980527ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:58:03.83015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.254675ms","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-03-27T01:58:03.830207Z","caller":"traceutil/trace.go:171","msg":"trace[1144368364] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1302; }","duration":"381.330117ms","start":"2026-03-27T01:58:03.448855Z","end":"2026-03-27T01:58:03.830185Z","steps":["trace[1144368364] 'agreement among raft nodes before linearized reading' (duration: 381.249495ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:03.830229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:58:03.44884Z","time spent":"381.382718ms","remote":"127.0.0.1:50010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-03-27T01:58:03.830307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.840917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-27T01:58:03.830462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.666115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-27T01:58:03.830512Z","caller":"traceutil/trace.go:171","msg":"trace[2143982246] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1302; }","duration":"430.088053ms","start":"2026-03-27T01:58:03.400364Z","end":"2026-03-27T01:58:03.830452Z","steps":["trace[2143982246] 'agreement among raft nodes before linearized reading' (duration: 429.498271ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:58:03.830547Z","caller":"traceutil/trace.go:171","msg":"trace[1104328412] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1302; }","duration":"321.806048ms","start":"2026-03-27T01:58:03.508707Z","end":"2026-03-27T01:58:03.830513Z","steps":["trace[1104328412] 'agreement among raft nodes before linearized reading' (duration: 321.318568ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:03.830594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:58:03.508694Z","time spent":"321.88882ms","remote":"127.0.0.1:49636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":139,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-03-27T01:58:03.830618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:58:03.400343Z","time spent":"430.240475ms","remote":"127.0.0.1:48846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-03-27T01:58:03.831263Z","caller":"traceutil/trace.go:171","msg":"trace[750530574] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"902.253966ms","start":"2026-03-27T01:58:02.928938Z","end":"2026-03-27T01:58:03.831192Z","steps":["trace[750530574] 'process raft request' (duration: 487.714747ms)","trace[750530574] 'compare' (duration: 409.56313ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:58:03.831514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:58:02.928904Z","time spent":"902.472641ms","remote":"127.0.0.1:49812","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-03-27T01:58:03.83238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.095574ms","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-03-27T01:58:03.832494Z","caller":"traceutil/trace.go:171","msg":"trace[1919429786] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1302; }","duration":"816.260067ms","start":"2026-03-27T01:58:03.016206Z","end":"2026-03-27T01:58:03.832466Z","steps":["trace[1919429786] 'agreement among raft nodes before linearized reading' (duration: 810.95501ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:03.832562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:58:03.016185Z","time spent":"816.353959ms","remote":"127.0.0.1:49812","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-03-27T01:58:03.832932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.839288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-27T01:58:03.833033Z","caller":"traceutil/trace.go:171","msg":"trace[2104846195] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1302; }","duration":"815.955661ms","start":"2026-03-27T01:58:03.017061Z","end":"2026-03-27T01:58:03.833017Z","steps":["trace[2104846195] 'agreement among raft nodes before linearized reading' (duration: 815.695777ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:03.833092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:58:03.01705Z","time spent":"816.030972ms","remote":"127.0.0.1:49812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-27T01:58:03.832933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.86794ms","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-03-27T01:58:03.833235Z","caller":"traceutil/trace.go:171","msg":"trace[1178990229] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1302; }","duration":"816.178656ms","start":"2026-03-27T01:58:03.017035Z","end":"2026-03-27T01:58:03.833214Z","steps":["trace[1178990229] 'agreement among raft nodes before linearized reading' (duration: 815.760447ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:03.833313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:58:03.017027Z","time spent":"816.271057ms","remote":"127.0.0.1:49812","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-03-27T01:58:03.833455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.672836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-27T01:58:03.834364Z","caller":"traceutil/trace.go:171","msg":"trace[926152597] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1302; }","duration":"817.623361ms","start":"2026-03-27T01:58:03.016721Z","end":"2026-03-27T01:58:03.834344Z","steps":["trace[926152597] 'agreement among raft nodes before linearized reading' (duration: 816.634794ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:03.834397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:58:03.016694Z","time spent":"817.690803ms","remote":"127.0.0.1:49812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-27T01:58:04.082716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.162891ms","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-03-27T01:58:04.082863Z","caller":"traceutil/trace.go:171","msg":"trace[1702028185] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1302; }","duration":"114.335704ms","start":"2026-03-27T01:58:03.968501Z","end":"2026-03-27T01:58:04.082837Z","steps":["trace[1702028185] 'range keys from in-memory index tree' (duration: 114.142191ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:58:04.20377Z","caller":"traceutil/trace.go:171","msg":"trace[984178269] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"365.602222ms","start":"2026-03-27T01:58:03.838128Z","end":"2026-03-27T01:58:04.20373Z","steps":["trace[984178269] 'process raft request' (duration: 365.318527ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:04.203975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:58:03.838104Z","time spent":"365.770695ms","remote":"127.0.0.1:49812","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-03-27T01:58:04.23505Z","caller":"traceutil/trace.go:171","msg":"trace[562243754] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"394.067738ms","start":"2026-03-27T01:58:03.840962Z","end":"2026-03-27T01:58:04.23503Z","steps":["trace[562243754] 'process raft request' (duration: 394.034968ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:58:04.235102Z","caller":"traceutil/trace.go:171","msg":"trace[1866309362] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"396.714367ms","start":"2026-03-27T01:58:03.838345Z","end":"2026-03-27T01:58:04.23506Z","steps":["trace[1866309362] 'process raft request' (duration: 396.522013ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:04.23518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:58:03.840935Z","time spent":"394.175821ms","remote":"127.0.0.1:49812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-27T01:58:04.235372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:58:03.838331Z","time spent":"396.88117ms","remote":"127.0.0.1:49812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-27T01:58:04.235377Z","caller":"traceutil/trace.go:171","msg":"trace[45751533] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"396.738578ms","start":"2026-03-27T01:58:03.838374Z","end":"2026-03-27T01:58:04.235112Z","steps":["trace[45751533] 'process raft request' (duration: 396.598894ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:04.235723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:58:03.838363Z","time spent":"397.168985ms","remote":"127.0.0.1:49812","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-03-27T01:58:06.377701Z","caller":"traceutil/trace.go:171","msg":"trace[371731483] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"538.479839ms","start":"2026-03-27T01:58:05.839195Z","end":"2026-03-27T01:58:06.377675Z","steps":["trace[371731483] 'process raft request' (duration: 538.264305ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:06.377939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:58:05.839168Z","time spent":"538.60678ms","remote":"127.0.0.1:49812","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-03-27T01:58:06.399264Z","caller":"traceutil/trace.go:171","msg":"trace[1373630816] linearizableReadLoop","detail":"{readStateIndex:1367; appliedIndex:1365; }","duration":"483.734635ms","start":"2026-03-27T01:58:05.915506Z","end":"2026-03-27T01:58:06.39924Z","steps":["trace[1373630816] 'read index received' (duration: 461.94058ms)","trace[1373630816] 'applied index is now lower than readState.Index' (duration: 21.793055ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:58:06.399496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.970968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:58:06.399543Z","caller":"traceutil/trace.go:171","msg":"trace[1722710387] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1310; }","duration":"484.081481ms","start":"2026-03-27T01:58:05.915449Z","end":"2026-03-27T01:58:06.399531Z","steps":["trace[1722710387] 'agreement among raft nodes before linearized reading' (duration: 483.891577ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:06.399583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:58:05.915428Z","time spent":"484.145071ms","remote":"127.0.0.1:49696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-03-27T01:58:06.399675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.653349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-27T01:58:06.399759Z","caller":"traceutil/trace.go:171","msg":"trace[916557034] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1310; }","duration":"190.779841ms","start":"2026-03-27T01:58:06.208957Z","end":"2026-03-27T01:58:06.399737Z","steps":["trace[916557034] 'agreement among raft nodes before linearized reading' (duration: 190.540316ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:58:06.399762Z","caller":"traceutil/trace.go:171","msg":"trace[2131067928] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"501.047879ms","start":"2026-03-27T01:58:05.898697Z","end":"2026-03-27T01:58:06.399745Z","steps":["trace[2131067928] 'process raft request' (duration: 500.396607ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:06.399775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.349709ms","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-03-27T01:58:06.399866Z","caller":"traceutil/trace.go:171","msg":"trace[1847651792] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1310; }","duration":"160.498122ms","start":"2026-03-27T01:58:06.239347Z","end":"2026-03-27T01:58:06.399845Z","steps":["trace[1847651792] 'agreement among raft nodes before linearized reading' (duration: 160.218536ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:06.399877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:58:05.898665Z","time spent":"501.148011ms","remote":"127.0.0.1:49812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-27T01:58:06.399673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.845541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-27T01:58:06.400014Z","caller":"traceutil/trace.go:171","msg":"trace[1370652872] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1310; }","duration":"160.159396ms","start":"2026-03-27T01:58:06.239794Z","end":"2026-03-27T01:58:06.399953Z","steps":["trace[1370652872] 'agreement among raft nodes before linearized reading' (duration: 159.752978ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:06.400268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.776966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-27T01:58:06.400315Z","caller":"traceutil/trace.go:171","msg":"trace[953065115] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1310; }","duration":"160.840598ms","start":"2026-03-27T01:58:06.239463Z","end":"2026-03-27T01:58:06.400304Z","steps":["trace[953065115] 'agreement among raft nodes before linearized reading' (duration: 160.043943ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:58:06.833845Z","caller":"traceutil/trace.go:171","msg":"trace[1764350918] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"429.364559ms","start":"2026-03-27T01:58:06.404453Z","end":"2026-03-27T01:58:06.833818Z","steps":["trace[1764350918] 'process raft request' (duration: 367.14919ms)","trace[1764350918] 'compare' (duration: 61.837301ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:58:06.83397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:58:06.404428Z","time spent":"429.472551ms","remote":"127.0.0.1:49812","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-03-27T01:58:06.895758Z","caller":"traceutil/trace.go:171","msg":"trace[79781356] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"490.995227ms","start":"2026-03-27T01:58:06.404734Z","end":"2026-03-27T01:58:06.895729Z","steps":["trace[79781356] 'process raft request' (duration: 490.797044ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:06.895926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:58:06.404712Z","time spent":"491.133879ms","remote":"127.0.0.1:49812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-27T01:58:06.896269Z","caller":"traceutil/trace.go:171","msg":"trace[1201975126] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"491.243411ms","start":"2026-03-27T01:58:06.404985Z","end":"2026-03-27T01:58:06.896229Z","steps":["trace[1201975126] 'process raft request' (duration: 490.684181ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:06.896415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:58:06.404976Z","time spent":"491.349943ms","remote":"127.0.0.1:49812","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-03-27T01:58:06.897316Z","caller":"traceutil/trace.go:171","msg":"trace[1359373638] linearizableReadLoop","detail":"{readStateIndex:1371; appliedIndex:1367; }","duration":"337.917409ms","start":"2026-03-27T01:58:06.559373Z","end":"2026-03-27T01:58:06.89729Z","steps":["trace[1359373638] 'read index received' (duration: 212.329761ms)","trace[1359373638] 'applied index is now lower than readState.Index' (duration: 125.581638ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:58:06.897501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.654579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:58:06.897541Z","caller":"traceutil/trace.go:171","msg":"trace[1610106669] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1314; }","duration":"174.731959ms","start":"2026-03-27T01:58:06.7228Z","end":"2026-03-27T01:58:06.897532Z","steps":["trace[1610106669] 'agreement among raft nodes before linearized reading' (duration: 174.664908ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:06.89758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.191374ms","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-03-27T01:58:06.897622Z","caller":"traceutil/trace.go:171","msg":"trace[519262748] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1314; }","duration":"338.290706ms","start":"2026-03-27T01:58:06.55932Z","end":"2026-03-27T01:58:06.897611Z","steps":["trace[519262748] 'agreement among raft nodes before linearized reading' (duration: 338.119243ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:06.897667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:58:06.5593Z","time spent":"338.358387ms","remote":"127.0.0.1:49812","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-03-27T01:58:06.897712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.61559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-03-27T01:58:06.897778Z","caller":"traceutil/trace.go:171","msg":"trace[409934957] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1314; }","duration":"140.720732ms","start":"2026-03-27T01:58:06.75704Z","end":"2026-03-27T01:58:06.897761Z","steps":["trace[409934957] 'agreement among raft nodes before linearized reading' (duration: 140.520829ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:58:06.897404Z","caller":"traceutil/trace.go:171","msg":"trace[1156706404] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"492.26712ms","start":"2026-03-27T01:58:06.4051Z","end":"2026-03-27T01:58:06.897367Z","steps":["trace[1156706404] 'process raft request' (duration: 490.868284ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:06.898051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:58:06.405087Z","time spent":"492.84313ms","remote":"127.0.0.1:49812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-27T01:58:07.213794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.846096ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-27T01:58:07.214147Z","caller":"traceutil/trace.go:171","msg":"trace[493543984] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"311.731344ms","start":"2026-03-27T01:58:06.902393Z","end":"2026-03-27T01:58:07.214125Z","steps":["trace[493543984] 'process raft request' (duration: 52.464682ms)","trace[493543984] 'compare' (duration: 258.717543ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:58:07.214288Z","caller":"traceutil/trace.go:171","msg":"trace[422662572] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"310.777907ms","start":"2026-03-27T01:58:06.903475Z","end":"2026-03-27T01:58:07.214253Z","steps":["trace[422662572] 'process raft request' (duration: 310.574704ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:07.214449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:58:06.903455Z","time spent":"310.907399ms","remote":"127.0.0.1:49812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-27T01:58:07.214411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:58:06.90237Z","time spent":"311.871416ms","remote":"127.0.0.1:49812","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-03-27T01:58:10.245921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.736788ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-27T01:58:10.246438Z","caller":"traceutil/trace.go:171","msg":"trace[1435207364] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"151.862236ms","start":"2026-03-27T01:58:10.094536Z","end":"2026-03-27T01:58:10.246398Z","steps":["trace[1435207364] 'compare' (duration: 143.565254ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:58:10.369273Z","caller":"traceutil/trace.go:171","msg":"trace[401569210] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"103.531978ms","start":"2026-03-27T01:58:10.265701Z","end":"2026-03-27T01:58:10.369233Z","steps":["trace[401569210] 'process raft request' (duration: 95.285398ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:58:12.419904Z","caller":"traceutil/trace.go:171","msg":"trace[1763132438] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"198.088863ms","start":"2026-03-27T01:58:12.221783Z","end":"2026-03-27T01:58:12.419872Z","steps":["trace[1763132438] 'process raft request' (duration: 197.90242ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:13.227099Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219120650274532990,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-27T01:58:13.727456Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219120650274532990,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-27T01:58:14.044989Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.417213818s","expected-duration":"1s"} {"level":"info","ts":"2026-03-27T01:58:14.04565Z","caller":"traceutil/trace.go:171","msg":"trace[341597014] linearizableReadLoop","detail":"{readStateIndex:1403; appliedIndex:1402; }","duration":"1.319571286s","start":"2026-03-27T01:58:12.726046Z","end":"2026-03-27T01:58:14.045618Z","steps":["trace[341597014] 'read index received' (duration: 1.319275611s)","trace[341597014] 'applied index is now lower than readState.Index' (duration: 294.425µs)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:58:14.045749Z","caller":"traceutil/trace.go:171","msg":"trace[1086304368] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"1.617991389s","start":"2026-03-27T01:58:12.427745Z","end":"2026-03-27T01:58:14.045736Z","steps":["trace[1086304368] 'process raft request' (duration: 1.617698694s)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:14.045926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:58:12.427711Z","time spent":"1.618066992s","remote":"127.0.0.1:49812","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-03-27T01:58:14.046053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.171530721s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-27T01:58:14.046118Z","caller":"traceutil/trace.go:171","msg":"trace[1422497442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1345; }","duration":"1.171644403s","start":"2026-03-27T01:58:12.874454Z","end":"2026-03-27T01:58:14.046098Z","steps":["trace[1422497442] 'agreement among raft nodes before linearized reading' (duration: 1.17146467s)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:14.04617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:58:12.874427Z","time spent":"1.171729775s","remote":"127.0.0.1:49812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-27T01:58:14.046268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.105964052s","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-03-27T01:58:14.046324Z","caller":"traceutil/trace.go:171","msg":"trace[56806500] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1345; }","duration":"1.106071654s","start":"2026-03-27T01:58:12.940237Z","end":"2026-03-27T01:58:14.046309Z","steps":["trace[56806500] 'agreement among raft nodes before linearized reading' (duration: 1.105918241s)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:14.046367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:58:12.940208Z","time spent":"1.106146655s","remote":"127.0.0.1:49812","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-03-27T01:58:14.04635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.103930986s","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-03-27T01:58:14.046422Z","caller":"traceutil/trace.go:171","msg":"trace[1357387192] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1345; }","duration":"1.104019217s","start":"2026-03-27T01:58:12.942391Z","end":"2026-03-27T01:58:14.046411Z","steps":["trace[1357387192] 'agreement among raft nodes before linearized reading' (duration: 1.103875334s)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:14.046468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:58:12.942382Z","time spent":"1.104068159s","remote":"127.0.0.1:49812","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-03-27T01:58:14.046627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.187542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-27T01:58:14.046653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.104697019s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-27T01:58:14.046664Z","caller":"traceutil/trace.go:171","msg":"trace[694418173] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1345; }","duration":"807.273533ms","start":"2026-03-27T01:58:13.239379Z","end":"2026-03-27T01:58:14.046653Z","steps":["trace[694418173] 'agreement among raft nodes before linearized reading' (duration: 807.171931ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:58:14.046705Z","caller":"traceutil/trace.go:171","msg":"trace[239109701] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1345; }","duration":"1.104771751s","start":"2026-03-27T01:58:12.941919Z","end":"2026-03-27T01:58:14.046691Z","steps":["trace[239109701] 'agreement among raft nodes before linearized reading' (duration: 1.104226431s)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:14.046709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:58:13.23935Z","time spent":"807.348605ms","remote":"127.0.0.1:49812","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-03-27T01:58:14.046749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:58:12.941908Z","time spent":"1.104826452s","remote":"127.0.0.1:49812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-27T01:58:14.0472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.321143816s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:58:14.047261Z","caller":"traceutil/trace.go:171","msg":"trace[751575339] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1345; }","duration":"1.321238678s","start":"2026-03-27T01:58:12.726007Z","end":"2026-03-27T01:58:14.047246Z","steps":["trace[751575339] 'agreement among raft nodes before linearized reading' (duration: 1.320098657s)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:14.047298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:58:12.725984Z","time spent":"1.321304849s","remote":"127.0.0.1:49578","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-03-27T01:58:14.047411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.530553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-03-27T01:58:14.04751Z","caller":"traceutil/trace.go:171","msg":"trace[1435809222] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1345; }","duration":"622.719377ms","start":"2026-03-27T01:58:13.424762Z","end":"2026-03-27T01:58:14.047481Z","steps":["trace[1435809222] 'agreement among raft nodes before linearized reading' (duration: 621.24071ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:14.047577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:58:13.424737Z","time spent":"622.817648ms","remote":"127.0.0.1:49812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-27T01:58:14.047912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"956.232376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:58:14.048188Z","caller":"traceutil/trace.go:171","msg":"trace[1831743631] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1345; }","duration":"958.401895ms","start":"2026-03-27T01:58:13.08977Z","end":"2026-03-27T01:58:14.048172Z","steps":["trace[1831743631] 'agreement among raft nodes before linearized reading' (duration: 956.172995ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:14.048241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:58:13.089755Z","time spent":"958.467147ms","remote":"127.0.0.1:49786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-03-27T01:58:58.481575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.028976ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-27T01:58:58.481894Z","caller":"traceutil/trace.go:171","msg":"trace[109054051] linearizableReadLoop","detail":"{readStateIndex:1636; appliedIndex:1634; }","duration":"187.046877ms","start":"2026-03-27T01:58:58.294822Z","end":"2026-03-27T01:58:58.481869Z","steps":["trace[109054051] 'read index received' (duration: 28.912063ms)","trace[109054051] 'applied index is now lower than readState.Index' (duration: 158.132604ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:58:58.481934Z","caller":"traceutil/trace.go:171","msg":"trace[386357001] transaction","detail":"{read_only:false; response_revision:1569; number_of_response:1; }","duration":"187.176408ms","start":"2026-03-27T01:58:58.294717Z","end":"2026-03-27T01:58:58.481893Z","steps":["trace[386357001] 'process raft request' (duration: 76.067682ms)","trace[386357001] 'compare' (duration: 109.849783ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:58:58.482152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.308791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-27T01:58:58.482234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.278856ms","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-03-27T01:58:58.48225Z","caller":"traceutil/trace.go:171","msg":"trace[2064854093] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1569; }","duration":"187.417503ms","start":"2026-03-27T01:58:58.294808Z","end":"2026-03-27T01:58:58.482225Z","steps":["trace[2064854093] 'agreement among raft nodes before linearized reading' (duration: 187.186369ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:58:58.482322Z","caller":"traceutil/trace.go:171","msg":"trace[842571613] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1569; }","duration":"174.338827ms","start":"2026-03-27T01:58:58.307938Z","end":"2026-03-27T01:58:58.482277Z","steps":["trace[842571613] 'agreement among raft nodes before linearized reading' (duration: 174.198394ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:58.482322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.620141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-27T01:58:58.482474Z","caller":"traceutil/trace.go:171","msg":"trace[1324409217] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1569; }","duration":"174.770365ms","start":"2026-03-27T01:58:58.307668Z","end":"2026-03-27T01:58:58.482438Z","steps":["trace[1324409217] 'agreement among raft nodes before linearized reading' (duration: 174.430339ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:59.043959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.761305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:58:59.044054Z","caller":"traceutil/trace.go:171","msg":"trace[574774860] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1573; }","duration":"128.914427ms","start":"2026-03-27T01:58:58.915119Z","end":"2026-03-27T01:58:59.044033Z","steps":["trace[574774860] 'count revisions from in-memory index tree' (duration: 128.669852ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:59:56.863527Z","caller":"traceutil/trace.go:171","msg":"trace[1325263317] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"112.668624ms","start":"2026-03-27T01:59:56.75083Z","end":"2026-03-27T01:59:56.863498Z","steps":["trace[1325263317] 'process raft request' (duration: 112.311127ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:59:56.985708Z","caller":"traceutil/trace.go:171","msg":"trace[1369965418] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"115.454984ms","start":"2026-03-27T01:59:56.870225Z","end":"2026-03-27T01:59:56.98568Z","steps":["trace[1369965418] 'process raft request' (duration: 115.378562ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:59:56.98614Z","caller":"traceutil/trace.go:171","msg":"trace[1185234839] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"117.256625ms","start":"2026-03-27T01:59:56.868869Z","end":"2026-03-27T01:59:56.986126Z","steps":["trace[1185234839] 'process raft request' (duration: 76.399688ms)","trace[1185234839] 'compare' (duration: 40.213556ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T02:00:00.820251Z","caller":"traceutil/trace.go:171","msg":"trace[1836204194] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"102.020007ms","start":"2026-03-27T02:00:00.718201Z","end":"2026-03-27T02:00:00.820221Z","steps":["trace[1836204194] 'process raft request' (duration: 101.657111ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T02:00:00.821226Z","caller":"traceutil/trace.go:171","msg":"trace[1729048661] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"102.933853ms","start":"2026-03-27T02:00:00.718271Z","end":"2026-03-27T02:00:00.821205Z","steps":["trace[1729048661] 'process raft request' (duration: 102.808411ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T02:01:29.009599Z","caller":"traceutil/trace.go:171","msg":"trace[402271135] linearizableReadLoop","detail":"{readStateIndex:2385; appliedIndex:2384; }","duration":"123.153449ms","start":"2026-03-27T02:01:28.886399Z","end":"2026-03-27T02:01:29.009552Z","steps":["trace[402271135] 'read index received' (duration: 75.357274ms)","trace[402271135] 'applied index is now lower than readState.Index' (duration: 47.795135ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T02:01:29.009731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.309502ms","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-03-27T02:01:29.00977Z","caller":"traceutil/trace.go:171","msg":"trace[2104210442] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:2287; }","duration":"123.392503ms","start":"2026-03-27T02:01:28.886366Z","end":"2026-03-27T02:01:29.009759Z","steps":["trace[2104210442] 'agreement among raft nodes before linearized reading' (duration: 123.301551ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T02:01:29.010054Z","caller":"traceutil/trace.go:171","msg":"trace[1056583188] transaction","detail":"{read_only:false; response_revision:2287; number_of_response:1; }","duration":"130.761997ms","start":"2026-03-27T02:01:28.87928Z","end":"2026-03-27T02:01:29.010042Z","steps":["trace[1056583188] 'process raft request' (duration: 82.432762ms)","trace[1056583188] 'compare' (duration: 47.746884ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T02:01:29.45713Z","caller":"traceutil/trace.go:171","msg":"trace[126655084] linearizableReadLoop","detail":"{readStateIndex:2387; appliedIndex:2386; }","duration":"117.335264ms","start":"2026-03-27T02:01:29.339773Z","end":"2026-03-27T02:01:29.457108Z","steps":["trace[126655084] 'read index received' (duration: 117.198042ms)","trace[126655084] 'applied index is now lower than readState.Index' (duration: 136.112µs)"],"step_count":2} {"level":"info","ts":"2026-03-27T02:01:29.457739Z","caller":"traceutil/trace.go:171","msg":"trace[2029550939] transaction","detail":"{read_only:false; response_revision:2289; number_of_response:1; }","duration":"136.017092ms","start":"2026-03-27T02:01:29.321691Z","end":"2026-03-27T02:01:29.457708Z","steps":["trace[2029550939] 'process raft request' (duration: 135.273209ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T02:01:29.457437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.593438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-03-27T02:01:29.457846Z","caller":"traceutil/trace.go:171","msg":"trace[1137896857] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2289; }","duration":"118.101017ms","start":"2026-03-27T02:01:29.339726Z","end":"2026-03-27T02:01:29.457827Z","steps":["trace[1137896857] 'agreement among raft nodes before linearized reading' (duration: 117.470526ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T02:01:29.724563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.757017ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-27T02:01:29.724739Z","caller":"traceutil/trace.go:171","msg":"trace[777962280] linearizableReadLoop","detail":"{readStateIndex:2391; appliedIndex:2389; }","duration":"149.547317ms","start":"2026-03-27T02:01:29.575175Z","end":"2026-03-27T02:01:29.724723Z","steps":["trace[777962280] 'read index received' (duration: 90.569119ms)","trace[777962280] 'applied index is now lower than readState.Index' (duration: 58.977288ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T02:01:29.72476Z","caller":"traceutil/trace.go:171","msg":"trace[272671866] transaction","detail":"{read_only:false; response_revision:2292; number_of_response:1; }","duration":"215.832126ms","start":"2026-03-27T02:01:29.508897Z","end":"2026-03-27T02:01:29.724729Z","steps":["trace[272671866] 'process raft request' (duration: 63.814694ms)","trace[272671866] 'compare' (duration: 151.678016ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T02:01:29.724827Z","caller":"traceutil/trace.go:171","msg":"trace[1843438804] transaction","detail":"{read_only:false; response_revision:2293; number_of_response:1; }","duration":"216.05317ms","start":"2026-03-27T02:01:29.508763Z","end":"2026-03-27T02:01:29.724816Z","steps":["trace[1843438804] 'process raft request' (duration: 215.909577ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T02:01:29.725024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.854702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-03-27T02:01:29.72513Z","caller":"traceutil/trace.go:171","msg":"trace[1139547744] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2293; }","duration":"149.981165ms","start":"2026-03-27T02:01:29.575134Z","end":"2026-03-27T02:01:29.725115Z","steps":["trace[1139547744] 'agreement among raft nodes before linearized reading' (duration: 149.776801ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T02:01:30.330477Z","caller":"traceutil/trace.go:171","msg":"trace[171342195] linearizableReadLoop","detail":"{readStateIndex:2392; appliedIndex:2391; }","duration":"457.963667ms","start":"2026-03-27T02:01:29.872483Z","end":"2026-03-27T02:01:30.330447Z","steps":["trace[171342195] 'read index received' (duration: 441.193004ms)","trace[171342195] 'applied index is now lower than readState.Index' (duration: 16.769023ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T02:01:30.330753Z","caller":"traceutil/trace.go:171","msg":"trace[1715734455] transaction","detail":"{read_only:false; response_revision:2294; number_of_response:1; }","duration":"601.173019ms","start":"2026-03-27T02:01:29.72956Z","end":"2026-03-27T02:01:30.330733Z","steps":["trace[1715734455] 'process raft request' (duration: 583.980908ms)","trace[1715734455] 'compare' (duration: 16.313175ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T02:01:30.330941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T02:01:29.729539Z","time spent":"601.314022ms","remote":"127.0.0.1:49812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-27T02:01:30.330937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.445605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-03-27T02:01:30.331008Z","caller":"traceutil/trace.go:171","msg":"trace[2116592332] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2294; }","duration":"458.581539ms","start":"2026-03-27T02:01:29.872406Z","end":"2026-03-27T02:01:30.330987Z","steps":["trace[2116592332] 'agreement among raft nodes before linearized reading' (duration: 458.384725ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T02:01:30.331057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T02:01:29.872378Z","time spent":"458.668291ms","remote":"127.0.0.1:49812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-27T02:01:30.358535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.60721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.35\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-27T02:01:30.358622Z","caller":"traceutil/trace.go:171","msg":"trace[239008920] range","detail":"{range_begin:/registry/masterleases/199.204.45.35; range_end:; response_count:1; response_revision:2295; }","duration":"342.763983ms","start":"2026-03-27T02:01:30.015841Z","end":"2026-03-27T02:01:30.358605Z","steps":["trace[239008920] 'agreement among raft nodes before linearized reading' (duration: 342.524779ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T02:01:30.35867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T02:01:30.015809Z","time spent":"342.850005ms","remote":"127.0.0.1:49602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.35\" "} {"level":"info","ts":"2026-03-27T02:01:30.358539Z","caller":"traceutil/trace.go:171","msg":"trace[1367592185] transaction","detail":"{read_only:false; response_revision:2295; number_of_response:1; }","duration":"136.931148ms","start":"2026-03-27T02:01:30.221511Z","end":"2026-03-27T02:01:30.358442Z","steps":["trace[1367592185] 'process raft request' (duration: 136.710283ms)"],"step_count":1}