{"level":"warn","ts":"2026-05-14T09:33:56.990451Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-14T09:33:56.99055Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.180:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.180:2380","--initial-cluster=instance=https://199.19.213.180:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.180:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.180: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-05-14T09:33:56.990625Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-14T09:33:56.990641Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.180:2380"]} {"level":"info","ts":"2026-05-14T09:33:56.990679Z","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-05-14T09:33:56.991453Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.180:2379"]} {"level":"info","ts":"2026-05-14T09:33:56.991579Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.180:2380"],"listen-peer-urls":["https://199.19.213.180:2380"],"advertise-client-urls":["https://199.19.213.180:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.180:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.180: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-05-14T09:33:56.998014Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.90449ms"} {"level":"info","ts":"2026-05-14T09:33:57.005263Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5d6864bfaf2cb565","cluster-id":"32ff95319ee9cd99"} {"level":"info","ts":"2026-05-14T09:33:57.005333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 switched to configuration voters=()"} {"level":"info","ts":"2026-05-14T09:33:57.005356Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 became follower at term 0"} {"level":"info","ts":"2026-05-14T09:33:57.00537Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5d6864bfaf2cb565 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-14T09:33:57.005379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 became follower at term 1"} {"level":"info","ts":"2026-05-14T09:33:57.005418Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 switched to configuration voters=(6730740417545680229)"} {"level":"warn","ts":"2026-05-14T09:33:57.015039Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-14T09:33:57.017957Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-14T09:33:57.020799Z","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-05-14T09:33:57.024627Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5d6864bfaf2cb565","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-14T09:33:57.024847Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5d6864bfaf2cb565","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-14T09:33:57.024943Z","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-05-14T09:33:57.025033Z","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-05-14T09:33:57.025045Z","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-05-14T09:33:57.028247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 switched to configuration voters=(6730740417545680229)"} {"level":"info","ts":"2026-05-14T09:33:57.028535Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"32ff95319ee9cd99","local-member-id":"5d6864bfaf2cb565","added-peer-id":"5d6864bfaf2cb565","added-peer-peer-urls":["https://199.19.213.180:2380"]} {"level":"info","ts":"2026-05-14T09:33:57.032362Z","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-05-14T09:33:57.037232Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5d6864bfaf2cb565","initial-advertise-peer-urls":["https://199.19.213.180:2380"],"listen-peer-urls":["https://199.19.213.180:2380"],"advertise-client-urls":["https://199.19.213.180:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.180:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-14T09:33:57.037287Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-14T09:33:57.033041Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.180:2380"} {"level":"info","ts":"2026-05-14T09:33:57.037426Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.180:2380"} {"level":"info","ts":"2026-05-14T09:33:57.905967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 is starting a new election at term 1"} {"level":"info","ts":"2026-05-14T09:33:57.906025Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-14T09:33:57.906049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 received MsgPreVoteResp from 5d6864bfaf2cb565 at term 1"} {"level":"info","ts":"2026-05-14T09:33:57.906089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 became candidate at term 2"} {"level":"info","ts":"2026-05-14T09:33:57.906105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 received MsgVoteResp from 5d6864bfaf2cb565 at term 2"} {"level":"info","ts":"2026-05-14T09:33:57.906125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 became leader at term 2"} {"level":"info","ts":"2026-05-14T09:33:57.906142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5d6864bfaf2cb565 elected leader 5d6864bfaf2cb565 at term 2"} {"level":"info","ts":"2026-05-14T09:33:57.907984Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5d6864bfaf2cb565","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.180:2379]}","request-path":"/0/members/5d6864bfaf2cb565/attributes","cluster-id":"32ff95319ee9cd99","publish-timeout":"7s"} {"level":"info","ts":"2026-05-14T09:33:57.908151Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-14T09:33:57.908216Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-14T09:33:57.908272Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T09:33:57.908403Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-14T09:33:57.908464Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-14T09:33:57.909153Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"32ff95319ee9cd99","local-member-id":"5d6864bfaf2cb565","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T09:33:57.909254Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T09:33:57.909282Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T09:33:57.911286Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-14T09:33:57.920747Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.180:2379"} {"level":"info","ts":"2026-05-14T09:34:03.440929Z","caller":"traceutil/trace.go:171","msg":"trace[1941048539] transaction","detail":"{read_only:false; number_of_response:0; response_revision:250; }","duration":"114.748266ms","start":"2026-05-14T09:34:03.326154Z","end":"2026-05-14T09:34:03.440902Z","steps":["trace[1941048539] 'process raft request' (duration: 114.639003ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:34:03.440987Z","caller":"traceutil/trace.go:171","msg":"trace[802380514] transaction","detail":"{read_only:false; number_of_response:0; response_revision:250; }","duration":"113.9633ms","start":"2026-05-14T09:34:03.326997Z","end":"2026-05-14T09:34:03.440961Z","steps":["trace[802380514] 'process raft request' (duration: 113.867468ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:34:03.441012Z","caller":"traceutil/trace.go:171","msg":"trace[68829204] transaction","detail":"{read_only:false; number_of_response:0; response_revision:250; }","duration":"113.076521ms","start":"2026-05-14T09:34:03.327912Z","end":"2026-05-14T09:34:03.440988Z","steps":["trace[68829204] 'process raft request' (duration: 112.974829ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:34:03.440996Z","caller":"traceutil/trace.go:171","msg":"trace[1669595229] linearizableReadLoop","detail":"{readStateIndex:261; appliedIndex:258; }","duration":"113.905719ms","start":"2026-05-14T09:34:03.327067Z","end":"2026-05-14T09:34:03.440973Z","steps":["trace[1669595229] 'read index received' (duration: 80.318893ms)","trace[1669595229] 'applied index is now lower than readState.Index' (duration: 33.585566ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:34:03.441024Z","caller":"traceutil/trace.go:171","msg":"trace[1709432194] transaction","detail":"{read_only:false; number_of_response:0; response_revision:250; }","duration":"112.876047ms","start":"2026-05-14T09:34:03.328135Z","end":"2026-05-14T09:34:03.441011Z","steps":["trace[1709432194] 'process raft request' (duration: 112.808255ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:34:03.441115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.020461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5897"} {"level":"info","ts":"2026-05-14T09:34:03.441224Z","caller":"traceutil/trace.go:171","msg":"trace[914358187] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:250; }","duration":"114.181474ms","start":"2026-05-14T09:34:03.327025Z","end":"2026-05-14T09:34:03.441206Z","steps":["trace[914358187] 'agreement among raft nodes before linearized reading' (duration: 114.023111ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:34:03.693009Z","caller":"traceutil/trace.go:171","msg":"trace[1675437138] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"104.854453ms","start":"2026-05-14T09:34:03.588117Z","end":"2026-05-14T09:34:03.692972Z","steps":["trace[1675437138] 'process raft request' (duration: 49.818058ms)","trace[1675437138] 'compare' (duration: 54.421712ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:34:03.849686Z","caller":"traceutil/trace.go:171","msg":"trace[956511554] linearizableReadLoop","detail":"{readStateIndex:276; appliedIndex:275; }","duration":"104.73151ms","start":"2026-05-14T09:34:03.744935Z","end":"2026-05-14T09:34:03.849666Z","steps":["trace[956511554] 'read index received' (duration: 62.873125ms)","trace[956511554] 'applied index is now lower than readState.Index' (duration: 41.857845ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T09:34:03.849773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.827032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pv-protection-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T09:34:03.849798Z","caller":"traceutil/trace.go:171","msg":"trace[1515287925] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pv-protection-controller; range_end:; response_count:0; response_revision:263; }","duration":"104.910804ms","start":"2026-05-14T09:34:03.744879Z","end":"2026-05-14T09:34:03.849789Z","steps":["trace[1515287925] 'agreement among raft nodes before linearized reading' (duration: 104.856913ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:34:03.849879Z","caller":"traceutil/trace.go:171","msg":"trace[680384191] transaction","detail":"{read_only:false; response_revision:263; number_of_response:1; }","duration":"149.214489ms","start":"2026-05-14T09:34:03.700644Z","end":"2026-05-14T09:34:03.849858Z","steps":["trace[680384191] 'process raft request' (duration: 107.204611ms)","trace[680384191] 'compare' (duration: 41.727642ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:34:06.39715Z","caller":"traceutil/trace.go:171","msg":"trace[1983331668] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"107.148524ms","start":"2026-05-14T09:34:06.289974Z","end":"2026-05-14T09:34:06.397123Z","steps":["trace[1983331668] 'process raft request' (duration: 106.990351ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:34:12.754735Z","caller":"traceutil/trace.go:171","msg":"trace[310373865] linearizableReadLoop","detail":"{readStateIndex:381; appliedIndex:380; }","duration":"290.172203ms","start":"2026-05-14T09:34:12.464535Z","end":"2026-05-14T09:34:12.754707Z","steps":["trace[310373865] 'read index received' (duration: 289.898498ms)","trace[310373865] 'applied index is now lower than readState.Index' (duration: 272.575µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:34:12.754776Z","caller":"traceutil/trace.go:171","msg":"trace[176597746] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"375.741875ms","start":"2026-05-14T09:34:12.379001Z","end":"2026-05-14T09:34:12.754743Z","steps":["trace[176597746] 'process raft request' (duration: 375.51291ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:34:12.754985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.400507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T09:34:12.755068Z","caller":"traceutil/trace.go:171","msg":"trace[703787759] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:366; }","duration":"290.579691ms","start":"2026-05-14T09:34:12.464468Z","end":"2026-05-14T09:34:12.755048Z","steps":["trace[703787759] 'agreement among raft nodes before linearized reading' (duration: 290.376407ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:34:12.755454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:34:12.378981Z","time spent":"375.916199ms","remote":"127.0.0.1:44022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T09:34:13.791011Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13071027378852696155,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T09:34:13.812584Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.044725495s","expected-duration":"1s"} {"level":"info","ts":"2026-05-14T09:34:13.812848Z","caller":"traceutil/trace.go:171","msg":"trace[1888941856] linearizableReadLoop","detail":"{readStateIndex:382; appliedIndex:381; }","duration":"522.229705ms","start":"2026-05-14T09:34:13.290604Z","end":"2026-05-14T09:34:13.812834Z","steps":["trace[1888941856] 'read index received' (duration: 522.142553ms)","trace[1888941856] 'applied index is now lower than readState.Index' (duration: 86.602µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:34:13.812922Z","caller":"traceutil/trace.go:171","msg":"trace[654335777] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"1.045151015s","start":"2026-05-14T09:34:12.767766Z","end":"2026-05-14T09:34:13.812917Z","steps":["trace[654335777] 'process raft request' (duration: 1.044979671s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:34:13.812992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:34:12.767745Z","time spent":"1.045194576s","remote":"127.0.0.1:43922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4361,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T09:34:13.813119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.920707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-14T09:34:13.813202Z","caller":"traceutil/trace.go:171","msg":"trace[95667407] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:367; }","duration":"516.039489ms","start":"2026-05-14T09:34:13.297141Z","end":"2026-05-14T09:34:13.813181Z","steps":["trace[95667407] 'agreement among raft nodes before linearized reading' (duration: 515.847645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:34:13.81323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.775498ms","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-05-14T09:34:13.813262Z","caller":"traceutil/trace.go:171","msg":"trace[1374004242] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:367; }","duration":"254.847719ms","start":"2026-05-14T09:34:13.558406Z","end":"2026-05-14T09:34:13.813254Z","steps":["trace[1374004242] 'agreement among raft nodes before linearized reading' (duration: 254.799048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:34:13.813228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.599313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-14T09:34:13.813304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:34:13.297132Z","time spent":"516.157471ms","remote":"127.0.0.1:44022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-14T09:34:13.813309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.302722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T09:34:13.813427Z","caller":"traceutil/trace.go:171","msg":"trace[1058814126] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:367; }","duration":"346.453776ms","start":"2026-05-14T09:34:13.466968Z","end":"2026-05-14T09:34:13.813422Z","steps":["trace[1058814126] 'agreement among raft nodes before linearized reading' (duration: 346.331013ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:34:13.813442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:34:13.466948Z","time spent":"346.490656ms","remote":"127.0.0.1:43758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-14T09:34:13.813422Z","caller":"traceutil/trace.go:171","msg":"trace[755464599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:367; }","duration":"522.783266ms","start":"2026-05-14T09:34:13.290556Z","end":"2026-05-14T09:34:13.813339Z","steps":["trace[755464599] 'agreement among raft nodes before linearized reading' (duration: 522.555231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:34:13.813782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:34:13.290533Z","time spent":"523.225085ms","remote":"127.0.0.1:44022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-14T09:34:13.994317Z","caller":"traceutil/trace.go:171","msg":"trace[1297220089] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"177.536475ms","start":"2026-05-14T09:34:13.816747Z","end":"2026-05-14T09:34:13.994283Z","steps":["trace[1297220089] 'process raft request' (duration: 90.3515ms)","trace[1297220089] 'compare' (duration: 86.984651ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:34:14.072593Z","caller":"traceutil/trace.go:171","msg":"trace[987725709] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"253.141943ms","start":"2026-05-14T09:34:13.819431Z","end":"2026-05-14T09:34:14.072572Z","steps":["trace[987725709] 'process raft request' (duration: 253.090362ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:34:14.072615Z","caller":"traceutil/trace.go:171","msg":"trace[815227960] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"253.149433ms","start":"2026-05-14T09:34:13.81943Z","end":"2026-05-14T09:34:14.072579Z","steps":["trace[815227960] 'process raft request' (duration: 252.98242ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:34:14.072761Z","caller":"traceutil/trace.go:171","msg":"trace[1331098073] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"253.293107ms","start":"2026-05-14T09:34:13.819461Z","end":"2026-05-14T09:34:14.072755Z","steps":["trace[1331098073] 'process raft request' (duration: 253.085443ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:34:29.304609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.127413ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-14T09:34:29.304808Z","caller":"traceutil/trace.go:171","msg":"trace[614705738] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"717.815501ms","start":"2026-05-14T09:34:28.586977Z","end":"2026-05-14T09:34:29.304792Z","steps":["trace[614705738] 'process raft request' (duration: 717.74393ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:34:29.304876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:34:28.586971Z","time spent":"717.876182ms","remote":"127.0.0.1:43806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T09:34:29.305334Z","caller":"traceutil/trace.go:171","msg":"trace[1710384565] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"719.442484ms","start":"2026-05-14T09:34:28.585866Z","end":"2026-05-14T09:34:29.305308Z","steps":["trace[1710384565] 'process raft request' (duration: 447.376552ms)","trace[1710384565] 'compare' (duration: 270.951418ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T09:34:29.305465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:34:28.585854Z","time spent":"719.531025ms","remote":"127.0.0.1:43806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T09:34:29.648419Z","caller":"traceutil/trace.go:171","msg":"trace[1910205878] transaction","detail":"{read_only:false; number_of_response:1; response_revision:475; }","duration":"1.056425245s","start":"2026-05-14T09:34:28.591953Z","end":"2026-05-14T09:34:29.648379Z","steps":["trace[1910205878] 'process raft request' (duration: 1.056216461s)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:34:29.648507Z","caller":"traceutil/trace.go:171","msg":"trace[666757626] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"1.056327623s","start":"2026-05-14T09:34:28.592111Z","end":"2026-05-14T09:34:29.648439Z","steps":["trace[666757626] 'process raft request' (duration: 1.056115609s)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:34:29.648559Z","caller":"traceutil/trace.go:171","msg":"trace[872590364] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"1.056374404s","start":"2026-05-14T09:34:28.592143Z","end":"2026-05-14T09:34:29.648518Z","steps":["trace[872590364] 'process raft request' (duration: 1.056141059s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:34:29.648666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:34:28.592106Z","time spent":"1.056485746s","remote":"127.0.0.1:44280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T09:34:29.648708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:34:28.59214Z","time spent":"1.056490256s","remote":"127.0.0.1:44280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T09:34:29.64871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:34:28.59195Z","time spent":"1.056638169s","remote":"127.0.0.1:43806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7365,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:34:29.648692Z","caller":"traceutil/trace.go:171","msg":"trace[2025101913] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"1.046744085s","start":"2026-05-14T09:34:28.601911Z","end":"2026-05-14T09:34:29.648655Z","steps":["trace[2025101913] 'process raft request' (duration: 1.046643743s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:34:29.649724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:34:28.601908Z","time spent":"1.047698936s","remote":"127.0.0.1:43806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T09:34:29.650004Z","caller":"traceutil/trace.go:171","msg":"trace[1380522382] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"1.058069599s","start":"2026-05-14T09:34:28.59191Z","end":"2026-05-14T09:34:29.649979Z","steps":["trace[1380522382] 'process raft request' (duration: 1.056044387s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:34:29.650115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:34:28.591902Z","time spent":"1.058166041s","remote":"127.0.0.1:43806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7317,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:34:29.650262Z","caller":"traceutil/trace.go:171","msg":"trace[323688549] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"1.055171559s","start":"2026-05-14T09:34:28.595068Z","end":"2026-05-14T09:34:29.65024Z","steps":["trace[323688549] 'process raft request' (duration: 1.05325179s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:34:29.650363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:34:28.595052Z","time spent":"1.055246032s","remote":"127.0.0.1:44280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T09:34:29.650744Z","caller":"traceutil/trace.go:171","msg":"trace[806987839] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"1.048825349s","start":"2026-05-14T09:34:28.601872Z","end":"2026-05-14T09:34:29.650698Z","steps":["trace[806987839] 'process raft request' (duration: 1.046491761s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:34:29.650872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:34:28.601866Z","time spent":"1.048937851s","remote":"127.0.0.1:43806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:34:29.651243Z","caller":"traceutil/trace.go:171","msg":"trace[1102568232] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"1.042797026s","start":"2026-05-14T09:34:28.608413Z","end":"2026-05-14T09:34:29.65121Z","steps":["trace[1102568232] 'process raft request' (duration: 1.040191042s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:34:29.651404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:34:28.608406Z","time spent":"1.042926058s","remote":"127.0.0.1:43806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T09:34:29.652159Z","caller":"traceutil/trace.go:171","msg":"trace[575220857] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"1.030790179s","start":"2026-05-14T09:34:28.621339Z","end":"2026-05-14T09:34:29.652129Z","steps":["trace[575220857] 'process raft request' (duration: 1.029693206s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:34:29.652256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:34:28.621245Z","time spent":"1.030952843s","remote":"127.0.0.1:43806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T09:34:29.653105Z","caller":"traceutil/trace.go:171","msg":"trace[615025315] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"1.031496914s","start":"2026-05-14T09:34:28.621398Z","end":"2026-05-14T09:34:29.652895Z","steps":["trace[615025315] 'process raft request' (duration: 1.030872991s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:34:29.65326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:34:28.621393Z","time spent":"1.0317972s","remote":"127.0.0.1:43806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T09:34:29.656152Z","caller":"traceutil/trace.go:171","msg":"trace[236258375] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"1.024054942s","start":"2026-05-14T09:34:28.632059Z","end":"2026-05-14T09:34:29.656114Z","steps":["trace[236258375] 'process raft request' (duration: 1.0220106s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:34:29.656245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:34:28.632049Z","time spent":"1.024157004s","remote":"127.0.0.1:43806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T09:34:29.661954Z","caller":"traceutil/trace.go:171","msg":"trace[1318943260] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"1.006297508s","start":"2026-05-14T09:34:28.655646Z","end":"2026-05-14T09:34:29.661944Z","steps":["trace[1318943260] 'process raft request' (duration: 1.002282696s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:34:29.66423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:34:28.655638Z","time spent":"1.006351489s","remote":"127.0.0.1:43806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T09:34:29.664269Z","caller":"traceutil/trace.go:171","msg":"trace[1543153466] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"352.729194ms","start":"2026-05-14T09:34:29.311525Z","end":"2026-05-14T09:34:29.664255Z","steps":["trace[1543153466] 'process raft request' (duration: 352.194973ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:34:29.664311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:34:29.311506Z","time spent":"352.784435ms","remote":"127.0.0.1:44280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T09:34:29.664401Z","caller":"traceutil/trace.go:171","msg":"trace[1884050373] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"903.116995ms","start":"2026-05-14T09:34:28.761267Z","end":"2026-05-14T09:34:29.664384Z","steps":["trace[1884050373] 'process raft request' (duration: 900.588363ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:34:29.664476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:34:28.761237Z","time spent":"903.195016ms","remote":"127.0.0.1:43806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T09:34:29.664507Z","caller":"traceutil/trace.go:171","msg":"trace[1723199352] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"347.389575ms","start":"2026-05-14T09:34:29.31711Z","end":"2026-05-14T09:34:29.664499Z","steps":["trace[1723199352] 'process raft request' (duration: 346.68676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:34:29.664572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:34:29.317097Z","time spent":"347.435445ms","remote":"127.0.0.1:43806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:34:29.664652Z","caller":"traceutil/trace.go:171","msg":"trace[178205346] linearizableReadLoop","detail":"{readStateIndex:508; appliedIndex:494; }","duration":"347.032737ms","start":"2026-05-14T09:34:29.316828Z","end":"2026-05-14T09:34:29.66386Z","steps":["trace[178205346] 'read index received' (duration: 331.12066ms)","trace[178205346] 'applied index is now lower than readState.Index' (duration: 15.911197ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T09:34:29.664778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.942675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T09:34:29.66481Z","caller":"traceutil/trace.go:171","msg":"trace[775052175] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:488; }","duration":"348.038828ms","start":"2026-05-14T09:34:29.31676Z","end":"2026-05-14T09:34:29.664799Z","steps":["trace[775052175] 'agreement among raft nodes before linearized reading' (duration: 347.957596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:34:29.664835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:34:29.316744Z","time spent":"348.086528ms","remote":"127.0.0.1:43758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-14T09:34:29.677951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.697777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T09:34:29.678015Z","caller":"traceutil/trace.go:171","msg":"trace[1760637072] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:490; }","duration":"213.796208ms","start":"2026-05-14T09:34:29.464204Z","end":"2026-05-14T09:34:29.678001Z","steps":["trace[1760637072] 'agreement among raft nodes before linearized reading' (duration: 213.694996ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:34:34.947755Z","caller":"traceutil/trace.go:171","msg":"trace[2131714821] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"292.130932ms","start":"2026-05-14T09:34:34.655596Z","end":"2026-05-14T09:34:34.947727Z","steps":["trace[2131714821] 'process raft request' (duration: 291.271125ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:34:36.091994Z","caller":"traceutil/trace.go:171","msg":"trace[788996614] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"119.591348ms","start":"2026-05-14T09:34:35.972371Z","end":"2026-05-14T09:34:36.091962Z","steps":["trace[788996614] 'process raft request' (duration: 118.517737ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:34:36.593082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.977963ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-14T09:34:36.593313Z","caller":"traceutil/trace.go:171","msg":"trace[2145277120] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"448.476424ms","start":"2026-05-14T09:34:36.144817Z","end":"2026-05-14T09:34:36.593294Z","steps":["trace[2145277120] 'process raft request' (duration: 448.375082ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:34:36.593418Z","caller":"traceutil/trace.go:171","msg":"trace[553608787] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"452.039107ms","start":"2026-05-14T09:34:36.141314Z","end":"2026-05-14T09:34:36.593353Z","steps":["trace[553608787] 'read index received' (duration: 276.784198ms)","trace[553608787] 'applied index is now lower than readState.Index' (duration: 175.248328ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T09:34:36.593441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:34:36.144804Z","time spent":"448.579686ms","remote":"127.0.0.1:44268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T09:34:36.594821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.646715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-05-14T09:34:36.594891Z","caller":"traceutil/trace.go:171","msg":"trace[34750008] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:578; }","duration":"337.772957ms","start":"2026-05-14T09:34:36.2571Z","end":"2026-05-14T09:34:36.594873Z","steps":["trace[34750008] 'agreement among raft nodes before linearized reading' (duration: 337.595264ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:34:36.594924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.171885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T09:34:36.595007Z","caller":"traceutil/trace.go:171","msg":"trace[543017829] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:578; }","duration":"129.301797ms","start":"2026-05-14T09:34:36.465684Z","end":"2026-05-14T09:34:36.594986Z","steps":["trace[543017829] 'agreement among raft nodes before linearized reading' (duration: 129.197875ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:34:36.593858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.617197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-14T09:34:36.595648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:34:36.25708Z","time spent":"338.550503ms","remote":"127.0.0.1:44022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-14T09:34:36.593874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.714616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-14T09:34:36.595954Z","caller":"traceutil/trace.go:171","msg":"trace[1957031819] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:578; }","duration":"338.797268ms","start":"2026-05-14T09:34:36.257114Z","end":"2026-05-14T09:34:36.595912Z","steps":["trace[1957031819] 'agreement among raft nodes before linearized reading' (duration: 336.708526ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:34:36.596002Z","caller":"traceutil/trace.go:171","msg":"trace[701033493] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:578; }","duration":"344.816202ms","start":"2026-05-14T09:34:36.251139Z","end":"2026-05-14T09:34:36.595955Z","steps":["trace[701033493] 'agreement among raft nodes before linearized reading' (duration: 342.481865ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:34:36.596085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:34:36.257098Z","time spent":"338.940572ms","remote":"127.0.0.1:44022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-14T09:34:36.596207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:34:36.251126Z","time spent":"345.032786ms","remote":"127.0.0.1:44022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-14T09:34:36.593781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.121541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xzmql\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-14T09:34:36.596288Z","caller":"traceutil/trace.go:171","msg":"trace[909352184] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xzmql; range_end:; response_count:1; response_revision:578; }","duration":"186.643693ms","start":"2026-05-14T09:34:36.409612Z","end":"2026-05-14T09:34:36.596255Z","steps":["trace[909352184] 'agreement among raft nodes before linearized reading' (duration: 184.037659ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:34:36.593719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.404146ms","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-05-14T09:34:36.596347Z","caller":"traceutil/trace.go:171","msg":"trace[162753187] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:578; }","duration":"455.091841ms","start":"2026-05-14T09:34:36.141244Z","end":"2026-05-14T09:34:36.596336Z","steps":["trace[162753187] 'agreement among raft nodes before linearized reading' (duration: 452.331384ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:34:36.596389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:34:36.141184Z","time spent":"455.198183ms","remote":"127.0.0.1:44022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-14T09:34:36.732899Z","caller":"traceutil/trace.go:171","msg":"trace[402155260] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"131.017952ms","start":"2026-05-14T09:34:36.601856Z","end":"2026-05-14T09:34:36.732874Z","steps":["trace[402155260] 'process raft request' (duration: 130.838848ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:34:36.733134Z","caller":"traceutil/trace.go:171","msg":"trace[1029289052] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"131.292299ms","start":"2026-05-14T09:34:36.601832Z","end":"2026-05-14T09:34:36.733124Z","steps":["trace[1029289052] 'process raft request' (duration: 131.001563ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:34:36.733469Z","caller":"traceutil/trace.go:171","msg":"trace[1661368679] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"131.39843ms","start":"2026-05-14T09:34:36.602059Z","end":"2026-05-14T09:34:36.733458Z","steps":["trace[1661368679] 'process raft request' (duration: 131.018312ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:34:36.733648Z","caller":"traceutil/trace.go:171","msg":"trace[1684224802] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:604; }","duration":"125.721114ms","start":"2026-05-14T09:34:36.607904Z","end":"2026-05-14T09:34:36.733625Z","steps":["trace[1684224802] 'read index received' (duration: 49.746657ms)","trace[1684224802] 'applied index is now lower than readState.Index' (duration: 75.973017ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:34:36.733714Z","caller":"traceutil/trace.go:171","msg":"trace[289836446] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"122.793044ms","start":"2026-05-14T09:34:36.610893Z","end":"2026-05-14T09:34:36.733686Z","steps":["trace[289836446] 'process raft request' (duration: 122.454636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:34:36.733754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.829946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T09:34:36.733804Z","caller":"traceutil/trace.go:171","msg":"trace[13925078] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:583; }","duration":"125.913838ms","start":"2026-05-14T09:34:36.607863Z","end":"2026-05-14T09:34:36.733777Z","steps":["trace[13925078] 'agreement among raft nodes before linearized reading' (duration: 125.840467ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:34:37.915838Z","caller":"traceutil/trace.go:171","msg":"trace[1282172679] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"759.870462ms","start":"2026-05-14T09:34:37.155946Z","end":"2026-05-14T09:34:37.915816Z","steps":["trace[1282172679] 'process raft request' (duration: 759.443522ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:34:37.915983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:34:37.155909Z","time spent":"760.011935ms","remote":"127.0.0.1:43864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:34:39.774281Z","caller":"traceutil/trace.go:171","msg":"trace[1390789592] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"211.462552ms","start":"2026-05-14T09:34:39.562789Z","end":"2026-05-14T09:34:39.774251Z","steps":["trace[1390789592] 'process raft request' (duration: 211.284448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:34:53.287337Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13071027378852696921,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-14T09:34:53.425293Z","caller":"traceutil/trace.go:171","msg":"trace[1952993696] linearizableReadLoop","detail":"{readStateIndex:773; appliedIndex:772; }","duration":"638.094318ms","start":"2026-05-14T09:34:52.787177Z","end":"2026-05-14T09:34:53.425271Z","steps":["trace[1952993696] 'read index received' (duration: 637.828273ms)","trace[1952993696] 'applied index is now lower than readState.Index' (duration: 265.095µs)"],"step_count":2} {"level":"warn","ts":"2026-05-14T09:34:53.425609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.277517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-14T09:34:53.425609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.075677ms","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-05-14T09:34:53.425644Z","caller":"traceutil/trace.go:171","msg":"trace[1911715376] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:743; }","duration":"607.385269ms","start":"2026-05-14T09:34:52.818249Z","end":"2026-05-14T09:34:53.425634Z","steps":["trace[1911715376] 'agreement among raft nodes before linearized reading' (duration: 607.227906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:34:53.425588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.390154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-14T09:34:53.425699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:34:52.818221Z","time spent":"607.46981ms","remote":"127.0.0.1:44022","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-05-14T09:34:53.425662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.058761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-14T09:34:53.425724Z","caller":"traceutil/trace.go:171","msg":"trace[833973001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:743; }","duration":"638.588218ms","start":"2026-05-14T09:34:52.787117Z","end":"2026-05-14T09:34:53.425705Z","steps":["trace[833973001] 'agreement among raft nodes before linearized reading' (duration: 638.243611ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:34:53.425792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:34:52.787089Z","time spent":"638.69296ms","remote":"127.0.0.1:44022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-14T09:34:53.425806Z","caller":"traceutil/trace.go:171","msg":"trace[515063683] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:743; }","duration":"606.205134ms","start":"2026-05-14T09:34:52.819534Z","end":"2026-05-14T09:34:53.42574Z","steps":["trace[515063683] 'agreement among raft nodes before linearized reading' (duration: 605.924929ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:34:53.425663Z","caller":"traceutil/trace.go:171","msg":"trace[1127046231] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:743; }","duration":"616.159628ms","start":"2026-05-14T09:34:52.809487Z","end":"2026-05-14T09:34:53.425647Z","steps":["trace[1127046231] 'agreement among raft nodes before linearized reading' (duration: 615.941524ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:34:53.425862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:34:52.81951Z","time spent":"606.343918ms","remote":"127.0.0.1:44022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-14T09:34:53.425921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:34:52.809474Z","time spent":"616.409234ms","remote":"127.0.0.1:44022","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":"info","ts":"2026-05-14T09:34:53.425928Z","caller":"traceutil/trace.go:171","msg":"trace[1257800503] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"910.65724ms","start":"2026-05-14T09:34:52.515251Z","end":"2026-05-14T09:34:53.425908Z","steps":["trace[1257800503] 'process raft request' (duration: 909.870474ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:34:53.426089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:34:52.515231Z","time spent":"910.780322ms","remote":"127.0.0.1:43926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:34:59.881863Z","caller":"traceutil/trace.go:171","msg":"trace[145621937] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"132.418133ms","start":"2026-05-14T09:34:59.749424Z","end":"2026-05-14T09:34:59.881842Z","steps":["trace[145621937] 'process raft request' (duration: 132.29782ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:35:00.267302Z","caller":"traceutil/trace.go:171","msg":"trace[1653096422] linearizableReadLoop","detail":"{readStateIndex:846; appliedIndex:844; }","duration":"300.240519ms","start":"2026-05-14T09:34:59.967044Z","end":"2026-05-14T09:35:00.267285Z","steps":["trace[1653096422] 'read index received' (duration: 293.95944ms)","trace[1653096422] 'applied index is now lower than readState.Index' (duration: 6.280299ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:35:00.267544Z","caller":"traceutil/trace.go:171","msg":"trace[104126982] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"380.103294ms","start":"2026-05-14T09:34:59.887433Z","end":"2026-05-14T09:35:00.267536Z","steps":["trace[104126982] 'process raft request' (duration: 373.55431ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:35:00.267636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:34:59.887412Z","time spent":"380.159775ms","remote":"127.0.0.1:43926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3856,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:35:00.267833Z","caller":"traceutil/trace.go:171","msg":"trace[1886767313] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"376.695246ms","start":"2026-05-14T09:34:59.891129Z","end":"2026-05-14T09:35:00.267824Z","steps":["trace[1886767313] 'process raft request' (duration: 376.090982ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:35:00.267882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:34:59.891117Z","time spent":"376.739216ms","remote":"127.0.0.1:40048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2318,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:35:00.267997Z","caller":"traceutil/trace.go:171","msg":"trace[710569062] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"300.430064ms","start":"2026-05-14T09:34:59.96756Z","end":"2026-05-14T09:35:00.26799Z","steps":["trace[710569062] 'process raft request' (duration: 299.698528ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:35:00.268053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:34:59.967543Z","time spent":"300.474444ms","remote":"127.0.0.1:43840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":719,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T09:35:00.268223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.177819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-54hdn\" ","response":"range_response_count:1 size:3871"} {"level":"info","ts":"2026-05-14T09:35:00.268251Z","caller":"traceutil/trace.go:171","msg":"trace[345818058] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-54hdn; range_end:; response_count:1; response_revision:816; }","duration":"301.24693ms","start":"2026-05-14T09:34:59.966997Z","end":"2026-05-14T09:35:00.268244Z","steps":["trace[345818058] 'agreement among raft nodes before linearized reading' (duration: 301.157768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:35:00.268269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:34:59.966972Z","time spent":"301.29253ms","remote":"127.0.0.1:43926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":3893,"request content":"key:\"/registry/pods/kube-system/coredns-67659f764b-54hdn\" "} {"level":"warn","ts":"2026-05-14T09:35:00.268762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.977579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T09:35:00.268846Z","caller":"traceutil/trace.go:171","msg":"trace[58268971] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:816; }","duration":"226.104241ms","start":"2026-05-14T09:35:00.042723Z","end":"2026-05-14T09:35:00.268828Z","steps":["trace[58268971] 'agreement among raft nodes before linearized reading' (duration: 225.952438ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:35:14.394903Z","caller":"traceutil/trace.go:171","msg":"trace[749895154] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"149.662905ms","start":"2026-05-14T09:35:14.245219Z","end":"2026-05-14T09:35:14.394882Z","steps":["trace[749895154] 'process raft request' (duration: 149.500131ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:35:18.134745Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13071027378852697269,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-14T09:35:18.464237Z","caller":"traceutil/trace.go:171","msg":"trace[1113725182] linearizableReadLoop","detail":"{readStateIndex:957; appliedIndex:955; }","duration":"830.537909ms","start":"2026-05-14T09:35:17.633676Z","end":"2026-05-14T09:35:18.464214Z","steps":["trace[1113725182] 'read index received' (duration: 829.545899ms)","trace[1113725182] 'applied index is now lower than readState.Index' (duration: 991.13µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:35:18.464349Z","caller":"traceutil/trace.go:171","msg":"trace[1679083517] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"830.818974ms","start":"2026-05-14T09:35:17.633518Z","end":"2026-05-14T09:35:18.464337Z","steps":["trace[1679083517] 'process raft request' (duration: 830.660091ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:35:18.464337Z","caller":"traceutil/trace.go:171","msg":"trace[1341082139] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"830.920467ms","start":"2026-05-14T09:35:17.633341Z","end":"2026-05-14T09:35:18.464262Z","steps":["trace[1341082139] 'process raft request' (duration: 830.117621ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:35:18.464438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:35:17.63349Z","time spent":"830.878865ms","remote":"127.0.0.1:44022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T09:35:18.464678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.002418ms","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-05-14T09:35:18.464703Z","caller":"traceutil/trace.go:171","msg":"trace[798488914] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:920; }","duration":"831.049318ms","start":"2026-05-14T09:35:17.633645Z","end":"2026-05-14T09:35:18.464695Z","steps":["trace[798488914] 'agreement among raft nodes before linearized reading' (duration: 830.934266ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:35:18.464723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:35:17.633632Z","time spent":"831.08712ms","remote":"127.0.0.1:44022","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-05-14T09:35:18.464755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:35:17.633331Z","time spent":"831.170763ms","remote":"127.0.0.1:44022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T09:35:18.464889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.277959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T09:35:18.46491Z","caller":"traceutil/trace.go:171","msg":"trace[1550546559] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:920; }","duration":"257.358811ms","start":"2026-05-14T09:35:18.207546Z","end":"2026-05-14T09:35:18.464904Z","steps":["trace[1550546559] 'agreement among raft nodes before linearized reading' (duration: 257.273659ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:35:31.841954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.696863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T09:35:31.842089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.078138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-14T09:35:31.842143Z","caller":"traceutil/trace.go:171","msg":"trace[1960334368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:967; }","duration":"116.267622ms","start":"2026-05-14T09:35:31.725851Z","end":"2026-05-14T09:35:31.842119Z","steps":["trace[1960334368] 'range keys from in-memory index tree' (duration: 115.920374ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:35:31.842159Z","caller":"traceutil/trace.go:171","msg":"trace[1352303228] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:967; }","duration":"374.848867ms","start":"2026-05-14T09:35:31.467214Z","end":"2026-05-14T09:35:31.842063Z","steps":["trace[1352303228] 'range keys from in-memory index tree' (duration: 374.634611ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:35:31.842219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:35:31.467171Z","time spent":"375.032481ms","remote":"127.0.0.1:43758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-14T09:35:31.842005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.091627ms","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-05-14T09:35:31.842668Z","caller":"traceutil/trace.go:171","msg":"trace[1766512572] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:967; }","duration":"116.795433ms","start":"2026-05-14T09:35:31.725856Z","end":"2026-05-14T09:35:31.842651Z","steps":["trace[1766512572] 'range keys from in-memory index tree' (duration: 115.859082ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:35:32.328518Z","caller":"traceutil/trace.go:171","msg":"trace[1746900433] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"480.266665ms","start":"2026-05-14T09:35:31.848218Z","end":"2026-05-14T09:35:32.328485Z","steps":["trace[1746900433] 'process raft request' (duration: 480.095281ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:35:32.328724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:35:31.848197Z","time spent":"480.410468ms","remote":"127.0.0.1:44022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T09:35:33.032285Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13071027378852697408,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T09:35:33.409435Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.032884763s","expected-duration":"1s"} {"level":"info","ts":"2026-05-14T09:35:33.4677Z","caller":"traceutil/trace.go:171","msg":"trace[1380109486] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"1.617297282s","start":"2026-05-14T09:35:31.85034Z","end":"2026-05-14T09:35:33.467638Z","steps":["trace[1380109486] 'process raft request' (duration: 1.559470148s)","trace[1380109486] 'compare' (duration: 57.419526ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T09:35:33.467872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:35:31.85031Z","time spent":"1.617470375s","remote":"127.0.0.1:44022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:35:33.484849Z","caller":"traceutil/trace.go:171","msg":"trace[1903077721] linearizableReadLoop","detail":"{readStateIndex:1010; appliedIndex:1008; }","duration":"953.15642ms","start":"2026-05-14T09:35:32.53164Z","end":"2026-05-14T09:35:33.484796Z","steps":["trace[1903077721] 'read index received' (duration: 878.189994ms)","trace[1903077721] 'applied index is now lower than readState.Index' (duration: 74.965066ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:35:33.484946Z","caller":"traceutil/trace.go:171","msg":"trace[1657931865] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"962.116383ms","start":"2026-05-14T09:35:32.522785Z","end":"2026-05-14T09:35:33.484902Z","steps":["trace[1657931865] 'process raft request' (duration: 960.937899ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:35:33.485176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:35:32.522751Z","time spent":"962.292367ms","remote":"127.0.0.1:44022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T09:35:33.485255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.598298ms","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-05-14T09:35:33.485363Z","caller":"traceutil/trace.go:171","msg":"trace[590845357] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:970; }","duration":"953.778752ms","start":"2026-05-14T09:35:32.531574Z","end":"2026-05-14T09:35:33.485352Z","steps":["trace[590845357] 'agreement among raft nodes before linearized reading' (duration: 953.441925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:35:33.485395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:35:32.531548Z","time spent":"953.838514ms","remote":"127.0.0.1:44022","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-05-14T09:35:33.485614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.856776ms","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-05-14T09:35:33.485695Z","caller":"traceutil/trace.go:171","msg":"trace[938466408] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:970; }","duration":"415.991069ms","start":"2026-05-14T09:35:33.069692Z","end":"2026-05-14T09:35:33.485683Z","steps":["trace[938466408] 'agreement among raft nodes before linearized reading' (duration: 415.821085ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:35:33.485716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"936.07012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-14T09:35:33.485773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:35:33.069664Z","time spent":"416.081711ms","remote":"127.0.0.1:44066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-05-14T09:35:33.485779Z","caller":"traceutil/trace.go:171","msg":"trace[194732958] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:970; }","duration":"936.152181ms","start":"2026-05-14T09:35:32.549606Z","end":"2026-05-14T09:35:33.485758Z","steps":["trace[194732958] 'agreement among raft nodes before linearized reading' (duration: 936.063019ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:35:33.485823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:35:32.549591Z","time spent":"936.224003ms","remote":"127.0.0.1:44022","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-05-14T09:35:33.485603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.203323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-14T09:35:33.485992Z","caller":"traceutil/trace.go:171","msg":"trace[1718281966] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:970; }","duration":"937.639521ms","start":"2026-05-14T09:35:32.548322Z","end":"2026-05-14T09:35:33.485961Z","steps":["trace[1718281966] 'agreement among raft nodes before linearized reading' (duration: 937.041019ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:35:33.486102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:35:32.5483Z","time spent":"937.760324ms","remote":"127.0.0.1:44022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-14T09:35:58.173592Z","caller":"traceutil/trace.go:171","msg":"trace[2019709576] linearizableReadLoop","detail":"{readStateIndex:1097; appliedIndex:1096; }","duration":"435.078619ms","start":"2026-05-14T09:35:57.738438Z","end":"2026-05-14T09:35:58.173517Z","steps":["trace[2019709576] 'read index received' (duration: 339.799018ms)","trace[2019709576] 'applied index is now lower than readState.Index' (duration: 95.277881ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:35:58.17376Z","caller":"traceutil/trace.go:171","msg":"trace[948433654] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"512.753981ms","start":"2026-05-14T09:35:57.660993Z","end":"2026-05-14T09:35:58.173747Z","steps":["trace[948433654] 'process raft request' (duration: 417.239625ms)","trace[948433654] 'compare' (duration: 95.034626ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T09:35:58.173881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:35:57.660976Z","time spent":"512.814552ms","remote":"127.0.0.1:44022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T09:35:58.1739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.404231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T09:35:58.173978Z","caller":"traceutil/trace.go:171","msg":"trace[2118797704] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1052; }","duration":"362.528434ms","start":"2026-05-14T09:35:57.81143Z","end":"2026-05-14T09:35:58.173958Z","steps":["trace[2118797704] 'agreement among raft nodes before linearized reading' (duration: 362.365691ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:35:58.174023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:35:57.811418Z","time spent":"362.595005ms","remote":"127.0.0.1:44114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":53,"response size":29,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-05-14T09:35:58.174077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.645081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T09:35:58.174132Z","caller":"traceutil/trace.go:171","msg":"trace[589835329] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1052; }","duration":"435.737393ms","start":"2026-05-14T09:35:57.738382Z","end":"2026-05-14T09:35:58.174119Z","steps":["trace[589835329] 'agreement among raft nodes before linearized reading' (duration: 435.649902ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:35:58.174166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:35:57.738364Z","time spent":"435.793534ms","remote":"127.0.0.1:40568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-14T09:35:58.1743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.46279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T09:35:58.174392Z","caller":"traceutil/trace.go:171","msg":"trace[1021451459] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1052; }","duration":"253.624583ms","start":"2026-05-14T09:35:57.920743Z","end":"2026-05-14T09:35:58.174368Z","steps":["trace[1021451459] 'agreement among raft nodes before linearized reading' (duration: 253.46397ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:35:58.732384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.008413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T09:35:58.732458Z","caller":"traceutil/trace.go:171","msg":"trace[612351213] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1052; }","duration":"277.095165ms","start":"2026-05-14T09:35:58.455344Z","end":"2026-05-14T09:35:58.732439Z","steps":["trace[612351213] 'count revisions from in-memory index tree' (duration: 276.889789ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:35:58.732613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.282254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T09:35:58.732762Z","caller":"traceutil/trace.go:171","msg":"trace[1681539333] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1052; }","duration":"267.483838ms","start":"2026-05-14T09:35:58.465216Z","end":"2026-05-14T09:35:58.7327Z","steps":["trace[1681539333] 'range keys from in-memory index tree' (duration: 267.162522ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:35:58.732791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.319117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-14T09:35:58.732818Z","caller":"traceutil/trace.go:171","msg":"trace[599292188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1052; }","duration":"251.366068ms","start":"2026-05-14T09:35:58.481445Z","end":"2026-05-14T09:35:58.732811Z","steps":["trace[599292188] 'range keys from in-memory index tree' (duration: 251.197864ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:35:58.733165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.723631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T09:35:58.733223Z","caller":"traceutil/trace.go:171","msg":"trace[384675315] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1052; }","duration":"265.66625ms","start":"2026-05-14T09:35:58.467543Z","end":"2026-05-14T09:35:58.73321Z","steps":["trace[384675315] 'count revisions from in-memory index tree' (duration: 264.66034ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:36:00.0894Z","caller":"traceutil/trace.go:171","msg":"trace[743745602] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"474.280773ms","start":"2026-05-14T09:35:59.615081Z","end":"2026-05-14T09:36:00.089362Z","steps":["trace[743745602] 'process raft request' (duration: 474.111989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:36:00.089807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:35:59.615046Z","time spent":"474.454036ms","remote":"127.0.0.1:44022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:36:00.103822Z","caller":"traceutil/trace.go:171","msg":"trace[1260904516] linearizableReadLoop","detail":"{readStateIndex:1100; appliedIndex:1098; }","duration":"441.474432ms","start":"2026-05-14T09:35:59.662318Z","end":"2026-05-14T09:36:00.103792Z","steps":["trace[1260904516] 'read index received' (duration: 426.869953ms)","trace[1260904516] 'applied index is now lower than readState.Index' (duration: 14.603039ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:36:00.104034Z","caller":"traceutil/trace.go:171","msg":"trace[41621587] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"484.205787ms","start":"2026-05-14T09:35:59.619814Z","end":"2026-05-14T09:36:00.10402Z","steps":["trace[41621587] 'process raft request' (duration: 483.779148ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:36:00.104181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:35:59.619793Z","time spent":"484.280698ms","remote":"127.0.0.1:44022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T09:36:00.104372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.739967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T09:36:00.104453Z","caller":"traceutil/trace.go:171","msg":"trace[1895062475] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1055; }","duration":"358.851159ms","start":"2026-05-14T09:35:59.745585Z","end":"2026-05-14T09:36:00.104437Z","steps":["trace[1895062475] 'agreement among raft nodes before linearized reading' (duration: 358.636025ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:36:00.104486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.140485ms","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-05-14T09:36:00.10456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:35:59.745494Z","time spent":"359.054433ms","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":29,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"info","ts":"2026-05-14T09:36:00.104611Z","caller":"traceutil/trace.go:171","msg":"trace[1402543547] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1055; }","duration":"442.319559ms","start":"2026-05-14T09:35:59.662267Z","end":"2026-05-14T09:36:00.104587Z","steps":["trace[1402543547] 'agreement among raft nodes before linearized reading' (duration: 441.833429ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:36:00.104671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:35:59.662249Z","time spent":"442.412041ms","remote":"127.0.0.1:44022","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-05-14T09:36:00.104725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.74286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-14T09:36:00.104907Z","caller":"traceutil/trace.go:171","msg":"trace[176307932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1055; }","duration":"385.046796ms","start":"2026-05-14T09:35:59.719813Z","end":"2026-05-14T09:36:00.104859Z","steps":["trace[176307932] 'agreement among raft nodes before linearized reading' (duration: 384.388932ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:36:00.105135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:35:59.719757Z","time spent":"385.254061ms","remote":"127.0.0.1:44022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-14T09:36:00.370418Z","caller":"traceutil/trace.go:171","msg":"trace[1666942797] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"261.938814ms","start":"2026-05-14T09:36:00.108447Z","end":"2026-05-14T09:36:00.370385Z","steps":["trace[1666942797] 'process raft request' (duration: 261.76185ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:36:00.425389Z","caller":"traceutil/trace.go:171","msg":"trace[1238470371] linearizableReadLoop","detail":"{readStateIndex:1102; appliedIndex:1100; }","duration":"279.79369ms","start":"2026-05-14T09:36:00.145583Z","end":"2026-05-14T09:36:00.425376Z","steps":["trace[1238470371] 'read index received' (duration: 224.685291ms)","trace[1238470371] 'applied index is now lower than readState.Index' (duration: 55.107239ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:36:00.425497Z","caller":"traceutil/trace.go:171","msg":"trace[383148606] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"315.215256ms","start":"2026-05-14T09:36:00.110272Z","end":"2026-05-14T09:36:00.425488Z","steps":["trace[383148606] 'process raft request' (duration: 295.533963ms)","trace[383148606] 'compare' (duration: 19.444549ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T09:36:00.425848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.204379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T09:36:00.425966Z","caller":"traceutil/trace.go:171","msg":"trace[1583932903] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1057; }","duration":"280.453314ms","start":"2026-05-14T09:36:00.145491Z","end":"2026-05-14T09:36:00.425944Z","steps":["trace[1583932903] 'agreement among raft nodes before linearized reading' (duration: 280.194618ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:36:00.426031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:36:00.110244Z","time spent":"315.668565ms","remote":"127.0.0.1:44022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T09:36:00.425827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.566909ms","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-05-14T09:36:00.426308Z","caller":"traceutil/trace.go:171","msg":"trace[1433769624] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1057; }","duration":"247.10261ms","start":"2026-05-14T09:36:00.179177Z","end":"2026-05-14T09:36:00.42628Z","steps":["trace[1433769624] 'agreement among raft nodes before linearized reading' (duration: 246.423457ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:36:02.325806Z","caller":"traceutil/trace.go:171","msg":"trace[1755542422] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"215.449993ms","start":"2026-05-14T09:36:02.109889Z","end":"2026-05-14T09:36:02.325339Z","steps":["trace[1755542422] 'process raft request' (duration: 215.247189ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:36:02.361747Z","caller":"traceutil/trace.go:171","msg":"trace[943397856] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"251.607473ms","start":"2026-05-14T09:36:02.110102Z","end":"2026-05-14T09:36:02.36171Z","steps":["trace[943397856] 'process raft request' (duration: 251.341647ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:36:17.042299Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13071027378852697866,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T09:36:17.542435Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13071027378852697866,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T09:36:17.981884Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.520746237s","expected-duration":"1s"} {"level":"info","ts":"2026-05-14T09:36:17.982263Z","caller":"traceutil/trace.go:171","msg":"trace[633682914] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"1.521229078s","start":"2026-05-14T09:36:16.461007Z","end":"2026-05-14T09:36:17.982236Z","steps":["trace[633682914] 'process raft request' (duration: 1.521040874s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:36:17.982398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:36:16.460986Z","time spent":"1.52132725s","remote":"127.0.0.1:44022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:36:17.983118Z","caller":"traceutil/trace.go:171","msg":"trace[835593889] linearizableReadLoop","detail":"{readStateIndex:1211; appliedIndex:1209; }","duration":"1.441178945s","start":"2026-05-14T09:36:16.541918Z","end":"2026-05-14T09:36:17.983097Z","steps":["trace[835593889] 'read index received' (duration: 1.440246874s)","trace[835593889] 'applied index is now lower than readState.Index' (duration: 930.861µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:36:17.983239Z","caller":"traceutil/trace.go:171","msg":"trace[2059510142] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"1.518256761s","start":"2026-05-14T09:36:16.464967Z","end":"2026-05-14T09:36:17.983223Z","steps":["trace[2059510142] 'process raft request' (duration: 1.518017826s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:36:17.98331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:36:16.464939Z","time spent":"1.518327083s","remote":"127.0.0.1:44022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T09:36:17.983447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.440486371s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-14T09:36:17.98352Z","caller":"traceutil/trace.go:171","msg":"trace[1877276039] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1160; }","duration":"1.440582493s","start":"2026-05-14T09:36:16.542917Z","end":"2026-05-14T09:36:17.9835Z","steps":["trace[1877276039] 'agreement among raft nodes before linearized reading' (duration: 1.440389827s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:36:17.983561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:36:16.54291Z","time spent":"1.440642525s","remote":"127.0.0.1:44022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-14T09:36:17.983735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.441812389s","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-05-14T09:36:17.983762Z","caller":"traceutil/trace.go:171","msg":"trace[1678954856] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1160; }","duration":"1.441884471s","start":"2026-05-14T09:36:16.54187Z","end":"2026-05-14T09:36:17.983754Z","steps":["trace[1678954856] 'agreement among raft nodes before linearized reading' (duration: 1.441791979s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:36:17.983762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"884.898647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-14T09:36:17.983768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.057151264s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-14T09:36:17.98378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.43737857s","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-05-14T09:36:17.983808Z","caller":"traceutil/trace.go:171","msg":"trace[942085895] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1160; }","duration":"884.981209ms","start":"2026-05-14T09:36:17.098814Z","end":"2026-05-14T09:36:17.983795Z","steps":["trace[942085895] 'agreement among raft nodes before linearized reading' (duration: 884.826516ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:36:17.983822Z","caller":"traceutil/trace.go:171","msg":"trace[1270186751] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1160; }","duration":"1.437458382s","start":"2026-05-14T09:36:16.546354Z","end":"2026-05-14T09:36:17.983813Z","steps":["trace[1270186751] 'agreement among raft nodes before linearized reading' (duration: 1.43736213s)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:36:17.983823Z","caller":"traceutil/trace.go:171","msg":"trace[277301576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1160; }","duration":"1.057236755s","start":"2026-05-14T09:36:16.926569Z","end":"2026-05-14T09:36:17.983806Z","steps":["trace[277301576] 'agreement among raft nodes before linearized reading' (duration: 1.057085112s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:36:17.98383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.709612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T09:36:17.983844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:36:17.098796Z","time spent":"885.0395ms","remote":"127.0.0.1:43840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":100,"response size":29,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-05-14T09:36:17.983858Z","caller":"traceutil/trace.go:171","msg":"trace[32238512] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1160; }","duration":"518.777503ms","start":"2026-05-14T09:36:17.465073Z","end":"2026-05-14T09:36:17.98385Z","steps":["trace[32238512] 'agreement among raft nodes before linearized reading' (duration: 518.733912ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:36:17.983857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:36:16.926544Z","time spent":"1.057305377s","remote":"127.0.0.1:44022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-14T09:36:17.983849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:36:16.546327Z","time spent":"1.437516194s","remote":"127.0.0.1:44022","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-05-14T09:36:17.983879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:36:17.465047Z","time spent":"518.827935ms","remote":"127.0.0.1:43758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-14T09:36:17.983786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:36:16.541848Z","time spent":"1.441928723s","remote":"127.0.0.1:44022","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":"info","ts":"2026-05-14T09:36:32.384037Z","caller":"traceutil/trace.go:171","msg":"trace[1573876393] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"327.155437ms","start":"2026-05-14T09:36:32.056854Z","end":"2026-05-14T09:36:32.38401Z","steps":["trace[1573876393] 'process raft request' (duration: 327.009883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:36:32.384195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:36:32.056831Z","time spent":"327.274039ms","remote":"127.0.0.1:44022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:36:32.944627Z","caller":"traceutil/trace.go:171","msg":"trace[1628796630] linearizableReadLoop","detail":"{readStateIndex:1268; appliedIndex:1266; }","duration":"872.203002ms","start":"2026-05-14T09:36:32.072387Z","end":"2026-05-14T09:36:32.94459Z","steps":["trace[1628796630] 'read index received' (duration: 311.506927ms)","trace[1628796630] 'applied index is now lower than readState.Index' (duration: 560.694815ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:36:32.945203Z","caller":"traceutil/trace.go:171","msg":"trace[451815268] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"879.337102ms","start":"2026-05-14T09:36:32.065652Z","end":"2026-05-14T09:36:32.94499Z","steps":["trace[451815268] 'process raft request' (duration: 878.748919ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:36:32.945385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.981399ms","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-05-14T09:36:32.945404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:36:32.065633Z","time spent":"879.632568ms","remote":"127.0.0.1:44022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:36:32.945472Z","caller":"traceutil/trace.go:171","msg":"trace[1507473830] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1214; }","duration":"873.105933ms","start":"2026-05-14T09:36:32.072346Z","end":"2026-05-14T09:36:32.945452Z","steps":["trace[1507473830] 'agreement among raft nodes before linearized reading' (duration: 872.651132ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:36:32.945576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:36:32.072332Z","time spent":"873.232836ms","remote":"127.0.0.1:44022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-14T09:36:32.963758Z","caller":"traceutil/trace.go:171","msg":"trace[749512059] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"465.336182ms","start":"2026-05-14T09:36:32.49841Z","end":"2026-05-14T09:36:32.963746Z","steps":["trace[749512059] 'process raft request' (duration: 465.23776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:36:32.963848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:36:32.498378Z","time spent":"465.418703ms","remote":"127.0.0.1:44022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T09:36:32.964174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"876.917858ms","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-05-14T09:36:32.964209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"869.84871ms","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-05-14T09:36:32.96425Z","caller":"traceutil/trace.go:171","msg":"trace[1127087148] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1215; }","duration":"877.047721ms","start":"2026-05-14T09:36:32.087185Z","end":"2026-05-14T09:36:32.964233Z","steps":["trace[1127087148] 'agreement among raft nodes before linearized reading' (duration: 876.764855ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:36:32.964305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:36:32.087171Z","time spent":"877.124862ms","remote":"127.0.0.1:44022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-14T09:36:32.964435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"876.994869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-05-14T09:36:32.964471Z","caller":"traceutil/trace.go:171","msg":"trace[2044937606] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1215; }","duration":"877.03022ms","start":"2026-05-14T09:36:32.087432Z","end":"2026-05-14T09:36:32.964462Z","steps":["trace[2044937606] 'agreement among raft nodes before linearized reading' (duration: 876.904937ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:36:32.964505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:36:32.087398Z","time spent":"877.098781ms","remote":"127.0.0.1:44022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-14T09:36:32.964565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.343967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-14T09:36:32.964591Z","caller":"traceutil/trace.go:171","msg":"trace[1668840690] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1215; }","duration":"877.368507ms","start":"2026-05-14T09:36:32.087216Z","end":"2026-05-14T09:36:32.964584Z","steps":["trace[1668840690] 'agreement among raft nodes before linearized reading' (duration: 877.301556ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:36:32.964609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:36:32.087163Z","time spent":"877.441469ms","remote":"127.0.0.1:44022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-14T09:36:32.964695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.234584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T09:36:32.964715Z","caller":"traceutil/trace.go:171","msg":"trace[1927169815] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1215; }","duration":"220.256274ms","start":"2026-05-14T09:36:32.744451Z","end":"2026-05-14T09:36:32.964707Z","steps":["trace[1927169815] 'agreement among raft nodes before linearized reading' (duration: 220.218393ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:36:32.964916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.138148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T09:36:32.964947Z","caller":"traceutil/trace.go:171","msg":"trace[467747640] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1215; }","duration":"501.205269ms","start":"2026-05-14T09:36:32.463734Z","end":"2026-05-14T09:36:32.964939Z","steps":["trace[467747640] 'agreement among raft nodes before linearized reading' (duration: 501.156878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:36:32.964967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:36:32.463708Z","time spent":"501.255021ms","remote":"127.0.0.1:43758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-14T09:36:32.964246Z","caller":"traceutil/trace.go:171","msg":"trace[512471687] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1215; }","duration":"869.919041ms","start":"2026-05-14T09:36:32.094316Z","end":"2026-05-14T09:36:32.964236Z","steps":["trace[512471687] 'agreement among raft nodes before linearized reading' (duration: 869.855499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:36:32.965208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:36:32.094298Z","time spent":"870.901604ms","remote":"127.0.0.1:40682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"info","ts":"2026-05-14T09:36:33.310928Z","caller":"traceutil/trace.go:171","msg":"trace[669080149] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"120.593135ms","start":"2026-05-14T09:36:33.19031Z","end":"2026-05-14T09:36:33.310904Z","steps":["trace[669080149] 'process raft request' (duration: 120.459532ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:36:34.517196Z","caller":"traceutil/trace.go:171","msg":"trace[439669778] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"123.92104ms","start":"2026-05-14T09:36:34.39325Z","end":"2026-05-14T09:36:34.517171Z","steps":["trace[439669778] 'process raft request' (duration: 123.740015ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:36:35.136482Z","caller":"traceutil/trace.go:171","msg":"trace[1831461198] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"132.399129ms","start":"2026-05-14T09:36:35.004051Z","end":"2026-05-14T09:36:35.13645Z","steps":["trace[1831461198] 'process raft request' (duration: 122.968589ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:36:35.136483Z","caller":"traceutil/trace.go:171","msg":"trace[673003468] linearizableReadLoop","detail":"{readStateIndex:1277; appliedIndex:1276; }","duration":"118.733615ms","start":"2026-05-14T09:36:35.017661Z","end":"2026-05-14T09:36:35.136394Z","steps":["trace[673003468] 'read index received' (duration: 109.373806ms)","trace[673003468] 'applied index is now lower than readState.Index' (duration: 9.357509ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T09:36:35.136599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.912068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T09:36:35.136724Z","caller":"traceutil/trace.go:171","msg":"trace[1381996498] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1223; }","duration":"119.00377ms","start":"2026-05-14T09:36:35.017646Z","end":"2026-05-14T09:36:35.13665Z","steps":["trace[1381996498] 'agreement among raft nodes before linearized reading' (duration: 118.876958ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:36:35.388838Z","caller":"traceutil/trace.go:171","msg":"trace[2063841519] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"246.258394ms","start":"2026-05-14T09:36:35.142548Z","end":"2026-05-14T09:36:35.388806Z","steps":["trace[2063841519] 'process raft request' (duration: 225.858739ms)","trace[2063841519] 'compare' (duration: 19.944435ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:36:35.388925Z","caller":"traceutil/trace.go:171","msg":"trace[696665911] linearizableReadLoop","detail":"{readStateIndex:1280; appliedIndex:1278; }","duration":"128.065342ms","start":"2026-05-14T09:36:35.260835Z","end":"2026-05-14T09:36:35.388901Z","steps":["trace[696665911] 'read index received' (duration: 107.497573ms)","trace[696665911] 'applied index is now lower than readState.Index' (duration: 20.564639ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T09:36:35.389072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.223566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T09:36:35.389157Z","caller":"traceutil/trace.go:171","msg":"trace[2130590964] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1226; }","duration":"128.352038ms","start":"2026-05-14T09:36:35.260789Z","end":"2026-05-14T09:36:35.389141Z","steps":["trace[2130590964] 'agreement among raft nodes before linearized reading' (duration: 128.224786ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:36:35.389094Z","caller":"traceutil/trace.go:171","msg":"trace[729862489] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"246.349116ms","start":"2026-05-14T09:36:35.142686Z","end":"2026-05-14T09:36:35.389035Z","steps":["trace[729862489] 'process raft request' (duration: 246.030779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:36:36.092339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.366909ms","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-05-14T09:36:36.092517Z","caller":"traceutil/trace.go:171","msg":"trace[1848882300] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1226; }","duration":"172.557883ms","start":"2026-05-14T09:36:35.919939Z","end":"2026-05-14T09:36:36.092497Z","steps":["trace[1848882300] 'range keys from in-memory index tree' (duration: 172.349099ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:36:36.092883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.725262ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T09:36:36.093352Z","caller":"traceutil/trace.go:171","msg":"trace[1056332458] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"710.600375ms","start":"2026-05-14T09:36:35.382733Z","end":"2026-05-14T09:36:36.093333Z","steps":["trace[1056332458] 'process raft request' (duration: 151.31348ms)","trace[1056332458] 'compare' (duration: 558.097799ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T09:36:36.093577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:36:35.382705Z","time spent":"710.760708ms","remote":"127.0.0.1:43922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5500,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T09:36:36.093684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.841946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T09:36:36.093756Z","caller":"traceutil/trace.go:171","msg":"trace[2105369899] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1227; }","duration":"787.955298ms","start":"2026-05-14T09:36:35.305785Z","end":"2026-05-14T09:36:36.09374Z","steps":["trace[2105369899] 'agreement among raft nodes before linearized reading' (duration: 787.761773ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:36:36.092994Z","caller":"traceutil/trace.go:171","msg":"trace[1354053634] linearizableReadLoop","detail":"{readStateIndex:1281; appliedIndex:1280; }","duration":"703.987068ms","start":"2026-05-14T09:36:35.388994Z","end":"2026-05-14T09:36:36.092981Z","steps":["trace[1354053634] 'read index received' (duration: 145.049871ms)","trace[1354053634] 'applied index is now lower than readState.Index' (duration: 558.935517ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T09:36:36.093793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:36:35.305774Z","time spent":"788.011259ms","remote":"127.0.0.1:44078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":17,"response size":29,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"info","ts":"2026-05-14T09:36:36.096029Z","caller":"traceutil/trace.go:171","msg":"trace[347629100] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"304.659985ms","start":"2026-05-14T09:36:35.79135Z","end":"2026-05-14T09:36:36.09601Z","steps":["trace[347629100] 'process raft request' (duration: 303.714484ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:36:36.096113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:36:35.791318Z","time spent":"304.756717ms","remote":"127.0.0.1:43840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":757,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T09:36:36.670534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.545586ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-14T09:36:36.670864Z","caller":"traceutil/trace.go:171","msg":"trace[433905974] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"168.032642ms","start":"2026-05-14T09:36:36.502812Z","end":"2026-05-14T09:36:36.670844Z","steps":["trace[433905974] 'process raft request' (duration: 167.94809ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:36:36.671246Z","caller":"traceutil/trace.go:171","msg":"trace[2146922537] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"776.939291ms","start":"2026-05-14T09:36:35.894289Z","end":"2026-05-14T09:36:36.671228Z","steps":["trace[2146922537] 'process raft request' (duration: 776.340048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:36:36.671398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:36:35.894256Z","time spent":"777.042065ms","remote":"127.0.0.1:44022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:36:36.67158Z","caller":"traceutil/trace.go:171","msg":"trace[1238498167] linearizableReadLoop","detail":"{readStateIndex:1284; appliedIndex:1281; }","duration":"577.723685ms","start":"2026-05-14T09:36:36.093844Z","end":"2026-05-14T09:36:36.671568Z","steps":["trace[1238498167] 'read index received' (duration: 1.176327ms)","trace[1238498167] 'applied index is now lower than readState.Index' (duration: 576.545608ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T09:36:36.671749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.129971064s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T09:36:36.671787Z","caller":"traceutil/trace.go:171","msg":"trace[627647446] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1230; }","duration":"1.130040576s","start":"2026-05-14T09:36:35.541736Z","end":"2026-05-14T09:36:36.671776Z","steps":["trace[627647446] 'agreement among raft nodes before linearized reading' (duration: 1.129978065s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:36:36.671824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:36:35.541717Z","time spent":"1.130099937s","remote":"127.0.0.1:44038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":27,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-05-14T09:36:36.672042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.207301096s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T09:36:36.672127Z","caller":"traceutil/trace.go:171","msg":"trace[864517457] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1230; }","duration":"1.207424528s","start":"2026-05-14T09:36:35.464684Z","end":"2026-05-14T09:36:36.672108Z","steps":["trace[864517457] 'agreement among raft nodes before linearized reading' (duration: 1.207279495s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:36:36.672177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:36:35.464665Z","time spent":"1.207503031s","remote":"127.0.0.1:43758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-14T09:36:36.672293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.857132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5514"} {"level":"info","ts":"2026-05-14T09:36:36.672337Z","caller":"traceutil/trace.go:171","msg":"trace[2047223481] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1230; }","duration":"561.944424ms","start":"2026-05-14T09:36:36.110382Z","end":"2026-05-14T09:36:36.672327Z","steps":["trace[2047223481] 'agreement among raft nodes before linearized reading' (duration: 561.813691ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:36:36.67237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:36:36.110359Z","time spent":"562.004265ms","remote":"127.0.0.1:43922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5536,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-14T09:36:36.672644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.329988ms","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-05-14T09:36:36.67269Z","caller":"traceutil/trace.go:171","msg":"trace[753093330] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1230; }","duration":"150.41741ms","start":"2026-05-14T09:36:36.522262Z","end":"2026-05-14T09:36:36.672679Z","steps":["trace[753093330] 'agreement among raft nodes before linearized reading' (duration: 150.275807ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:36:36.81921Z","caller":"traceutil/trace.go:171","msg":"trace[2107026606] linearizableReadLoop","detail":"{readStateIndex:1287; appliedIndex:1285; }","duration":"138.202057ms","start":"2026-05-14T09:36:36.680984Z","end":"2026-05-14T09:36:36.819186Z","steps":["trace[2107026606] 'read index received' (duration: 29.471346ms)","trace[2107026606] 'applied index is now lower than readState.Index' (duration: 108.729151ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:36:36.819498Z","caller":"traceutil/trace.go:171","msg":"trace[1594775498] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"142.103414ms","start":"2026-05-14T09:36:36.67738Z","end":"2026-05-14T09:36:36.819483Z","steps":["trace[1594775498] 'process raft request' (duration: 93.715747ms)","trace[1594775498] 'compare' (duration: 47.915727ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T09:36:36.819768Z","caller":"traceutil/trace.go:171","msg":"trace[657602336] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"124.571385ms","start":"2026-05-14T09:36:36.695186Z","end":"2026-05-14T09:36:36.819758Z","steps":["trace[657602336] 'process raft request' (duration: 123.95034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:36:36.819961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.959805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T09:36:36.819994Z","caller":"traceutil/trace.go:171","msg":"trace[1701527981] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1233; }","duration":"139.007806ms","start":"2026-05-14T09:36:36.680977Z","end":"2026-05-14T09:36:36.819985Z","steps":["trace[1701527981] 'agreement among raft nodes before linearized reading' (duration: 138.910424ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:36:36.820171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.747657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T09:36:36.820201Z","caller":"traceutil/trace.go:171","msg":"trace[1105049459] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1233; }","duration":"123.788298ms","start":"2026-05-14T09:36:36.696404Z","end":"2026-05-14T09:36:36.820192Z","steps":["trace[1105049459] 'agreement among raft nodes before linearized reading' (duration: 123.743597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:36:37.181561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.536786ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T09:36:37.181674Z","caller":"traceutil/trace.go:171","msg":"trace[141227702] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"190.045173ms","start":"2026-05-14T09:36:36.991596Z","end":"2026-05-14T09:36:37.181641Z","steps":["trace[141227702] 'process raft request' (duration: 42.347893ms)","trace[141227702] 'compare' (duration: 147.457235ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T09:36:45.626844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.505788ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T09:36:45.626954Z","caller":"traceutil/trace.go:171","msg":"trace[1637951189] linearizableReadLoop","detail":"{readStateIndex:1368; appliedIndex:1367; }","duration":"184.591241ms","start":"2026-05-14T09:36:45.442344Z","end":"2026-05-14T09:36:45.626935Z","steps":["trace[1637951189] 'read index received' (duration: 40.562µs)","trace[1637951189] 'applied index is now lower than readState.Index' (duration: 184.548319ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T09:36:45.627141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.800925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-14T09:36:45.627177Z","caller":"traceutil/trace.go:171","msg":"trace[1097338802] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1312; }","duration":"184.869197ms","start":"2026-05-14T09:36:45.442298Z","end":"2026-05-14T09:36:45.627167Z","steps":["trace[1097338802] 'agreement among raft nodes before linearized reading' (duration: 184.735764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:36:45.627425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.444185ms","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-05-14T09:36:45.627461Z","caller":"traceutil/trace.go:171","msg":"trace[799111539] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1312; }","duration":"183.491527ms","start":"2026-05-14T09:36:45.443961Z","end":"2026-05-14T09:36:45.627453Z","steps":["trace[799111539] 'agreement among raft nodes before linearized reading' (duration: 183.402375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:36:45.62764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.233773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T09:36:45.627678Z","caller":"traceutil/trace.go:171","msg":"trace[579259341] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1312; }","duration":"162.298185ms","start":"2026-05-14T09:36:45.465371Z","end":"2026-05-14T09:36:45.627669Z","steps":["trace[579259341] 'agreement among raft nodes before linearized reading' (duration: 162.246614ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:36:45.627696Z","caller":"traceutil/trace.go:171","msg":"trace[871381904] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"397.788148ms","start":"2026-05-14T09:36:45.229877Z","end":"2026-05-14T09:36:45.627665Z","steps":["trace[871381904] 'process raft request' (duration: 58.338748ms)","trace[871381904] 'compare' (duration: 338.301854ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T09:36:45.62786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:36:45.229831Z","time spent":"397.946211ms","remote":"127.0.0.1:44022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T09:36:46.362263Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13071027378852698185,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T09:36:46.437173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.016254ms","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-05-14T09:36:46.437279Z","caller":"traceutil/trace.go:171","msg":"trace[1999122590] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1312; }","duration":"517.134767ms","start":"2026-05-14T09:36:45.92013Z","end":"2026-05-14T09:36:46.437265Z","steps":["trace[1999122590] 'range keys from in-memory index tree' (duration: 517.002624ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:36:46.438306Z","caller":"traceutil/trace.go:171","msg":"trace[1119404230] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"803.539765ms","start":"2026-05-14T09:36:45.634755Z","end":"2026-05-14T09:36:46.438294Z","steps":["trace[1119404230] 'process raft request' (duration: 797.127332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:36:46.4384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:36:45.634739Z","time spent":"803.611916ms","remote":"127.0.0.1:44022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:36:46.438398Z","caller":"traceutil/trace.go:171","msg":"trace[1431542737] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"803.512344ms","start":"2026-05-14T09:36:45.63482Z","end":"2026-05-14T09:36:46.438333Z","steps":["trace[1431542737] 'process raft request' (duration: 803.233448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:36:46.438566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:36:45.634792Z","time spent":"803.695158ms","remote":"127.0.0.1:44022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T09:36:46.511764Z","caller":"traceutil/trace.go:171","msg":"trace[546745699] linearizableReadLoop","detail":"{readStateIndex:1371; appliedIndex:1368; }","duration":"649.602326ms","start":"2026-05-14T09:36:45.862137Z","end":"2026-05-14T09:36:46.511739Z","steps":["trace[546745699] 'read index received' (duration: 569.705868ms)","trace[546745699] 'applied index is now lower than readState.Index' (duration: 79.894678ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T09:36:46.512113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.955654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"warn","ts":"2026-05-14T09:36:46.512145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.639573ms","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:531"} {"level":"info","ts":"2026-05-14T09:36:46.512161Z","caller":"traceutil/trace.go:171","msg":"trace[1336231875] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1314; }","duration":"650.044976ms","start":"2026-05-14T09:36:45.862103Z","end":"2026-05-14T09:36:46.512148Z","steps":["trace[1336231875] 'agreement among raft nodes before linearized reading' (duration: 649.884413ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T09:36:46.51219Z","caller":"traceutil/trace.go:171","msg":"trace[463669325] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1314; }","duration":"224.734555ms","start":"2026-05-14T09:36:46.287444Z","end":"2026-05-14T09:36:46.512178Z","steps":["trace[463669325] 'agreement among raft nodes before linearized reading' (duration: 224.614532ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:36:46.512202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T09:36:45.862081Z","time spent":"650.113007ms","remote":"127.0.0.1:44022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-14T09:39:51.939091Z","caller":"traceutil/trace.go:171","msg":"trace[2011893841] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"108.554857ms","start":"2026-05-14T09:39:51.830512Z","end":"2026-05-14T09:39:51.939067Z","steps":["trace[2011893841] 'process raft request' (duration: 107.995405ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:39:59.316655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.062784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:100277"} {"level":"info","ts":"2026-05-14T09:39:59.316735Z","caller":"traceutil/trace.go:171","msg":"trace[847962075] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2228; }","duration":"188.189917ms","start":"2026-05-14T09:39:59.128528Z","end":"2026-05-14T09:39:59.316717Z","steps":["trace[847962075] 'range keys from in-memory index tree' (duration: 187.900271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T09:39:59.316806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.296631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-05-14T09:39:59.316929Z","caller":"traceutil/trace.go:171","msg":"trace[1412577085] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2228; }","duration":"183.465105ms","start":"2026-05-14T09:39:59.133436Z","end":"2026-05-14T09:39:59.316901Z","steps":["trace[1412577085] 'range keys from in-memory index tree' (duration: 183.136587ms)"],"step_count":1}