{"level":"warn","ts":"2026-04-21T17:39:37.481647Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-21T17:39:37.481728Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.109:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.109:2380","--initial-cluster=instance=https://199.204.45.109:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.109:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.109:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-21T17:39:37.481859Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-21T17:39:37.481873Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-04-21T17:39:37.481924Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-21T17:39:37.482628Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"]} {"level":"info","ts":"2026-04-21T17:39:37.482746Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.109:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-21T17:39:37.488322Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.393949ms"} {"level":"info","ts":"2026-04-21T17:39:37.498197Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d71a1778b31d4ce","cluster-id":"aaca35652fabf9b2"} {"level":"info","ts":"2026-04-21T17:39:37.498267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=()"} {"level":"info","ts":"2026-04-21T17:39:37.498294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 0"} {"level":"info","ts":"2026-04-21T17:39:37.498304Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d71a1778b31d4ce [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-21T17:39:37.498315Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 1"} {"level":"info","ts":"2026-04-21T17:39:37.498352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"warn","ts":"2026-04-21T17:39:37.503556Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-21T17:39:37.505078Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-21T17:39:37.506289Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-21T17:39:37.508581Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d71a1778b31d4ce","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-21T17:39:37.512018Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-21T17:39:37.512116Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-21T17:39:37.512107Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d71a1778b31d4ce","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-21T17:39:37.512181Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-21T17:39:37.514125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"info","ts":"2026-04-21T17:39:37.514253Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","added-peer-id":"d71a1778b31d4ce","added-peer-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-04-21T17:39:37.514327Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-21T17:39:37.514554Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d71a1778b31d4ce","initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-21T17:39:37.514582Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-21T17:39:37.515115Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-04-21T17:39:37.515139Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-04-21T17:39:37.998963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce is starting a new election at term 1"} {"level":"info","ts":"2026-04-21T17:39:37.999039Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became pre-candidate at term 1"} {"level":"info","ts":"2026-04-21T17:39:37.999082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgPreVoteResp from d71a1778b31d4ce at term 1"} {"level":"info","ts":"2026-04-21T17:39:37.999106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became candidate at term 2"} {"level":"info","ts":"2026-04-21T17:39:37.999118Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgVoteResp from d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-04-21T17:39:37.999134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became leader at term 2"} {"level":"info","ts":"2026-04-21T17:39:37.999154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d71a1778b31d4ce elected leader d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-04-21T17:39:38.022863Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T17:39:38.024416Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d71a1778b31d4ce","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.109:2379]}","request-path":"/0/members/d71a1778b31d4ce/attributes","cluster-id":"aaca35652fabf9b2","publish-timeout":"7s"} {"level":"info","ts":"2026-04-21T17:39:38.024571Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-21T17:39:38.024651Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-21T17:39:38.024926Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-21T17:39:38.025115Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-21T17:39:38.028839Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.109:2379"} {"level":"info","ts":"2026-04-21T17:39:38.030793Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T17:39:38.030925Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T17:39:38.031002Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T17:39:38.031985Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-21T17:39:42.973955Z","caller":"traceutil/trace.go:171","msg":"trace[498072354] transaction","detail":"{read_only:false; response_revision:239; number_of_response:1; }","duration":"119.460714ms","start":"2026-04-21T17:39:42.854466Z","end":"2026-04-21T17:39:42.973926Z","steps":["trace[498072354] 'process raft request' (duration: 115.288661ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:39:48.212411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.426132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-21T17:39:48.215269Z","caller":"traceutil/trace.go:171","msg":"trace[918917369] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:302; }","duration":"209.339646ms","start":"2026-04-21T17:39:48.005904Z","end":"2026-04-21T17:39:48.215243Z","steps":["trace[918917369] 'range keys from in-memory index tree' (duration: 206.326839ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:39:48.212791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.791057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-04-21T17:39:48.215426Z","caller":"traceutil/trace.go:171","msg":"trace[164717781] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:302; }","duration":"159.466816ms","start":"2026-04-21T17:39:48.055936Z","end":"2026-04-21T17:39:48.215403Z","steps":["trace[164717781] 'range keys from in-memory index tree' (duration: 156.706855ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:39:48.212838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.683145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-21T17:39:48.215498Z","caller":"traceutil/trace.go:171","msg":"trace[1933330110] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:302; }","duration":"110.370174ms","start":"2026-04-21T17:39:48.105109Z","end":"2026-04-21T17:39:48.215479Z","steps":["trace[1933330110] 'range keys from in-memory index tree' (duration: 107.558152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:39:55.0919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.40873113s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-21T17:39:55.091993Z","caller":"traceutil/trace.go:171","msg":"trace[921424333] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"1.781746614s","start":"2026-04-21T17:39:53.310233Z","end":"2026-04-21T17:39:55.091979Z","steps":["trace[921424333] 'process raft request' (duration: 372.576604ms)","trace[921424333] 'compare' (duration: 1.408251619s)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:39:55.092033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:39:53.310219Z","time spent":"1.781796776s","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T17:39:55.563594Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.566198352s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-21T17:39:56.009077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.242667ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-21T17:39:56.009188Z","caller":"traceutil/trace.go:171","msg":"trace[187212131] linearizableReadLoop","detail":"{readStateIndex:391; appliedIndex:388; }","duration":"2.671017011s","start":"2026-04-21T17:39:53.338161Z","end":"2026-04-21T17:39:56.009178Z","steps":["trace[187212131] 'read index received' (duration: 344.68946ms)","trace[187212131] 'applied index is now lower than readState.Index' (duration: 2.326326931s)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:39:56.00925Z","caller":"traceutil/trace.go:171","msg":"trace[271363423] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"2.69778186s","start":"2026-04-21T17:39:53.311463Z","end":"2026-04-21T17:39:56.009244Z","steps":["trace[271363423] 'process raft request' (duration: 2.252327739s)","trace[271363423] 'compare' (duration: 445.138775ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:39:56.009283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:39:53.311439Z","time spent":"2.697831322s","remote":"127.0.0.1:40176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3563,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T17:39:56.009458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.199343391s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-21T17:39:56.009478Z","caller":"traceutil/trace.go:171","msg":"trace[108825184] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:377; }","duration":"2.199401442s","start":"2026-04-21T17:39:53.810071Z","end":"2026-04-21T17:39:56.009472Z","steps":["trace[108825184] 'agreement among raft nodes before linearized reading' (duration: 2.199361681s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:39:56.009493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:39:53.810045Z","time spent":"2.199445394s","remote":"127.0.0.1:40280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-21T17:39:56.009596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.182042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-21T17:39:56.009627Z","caller":"traceutil/trace.go:171","msg":"trace[337275477] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:377; }","duration":"785.238603ms","start":"2026-04-21T17:39:55.224384Z","end":"2026-04-21T17:39:56.009623Z","steps":["trace[337275477] 'agreement among raft nodes before linearized reading' (duration: 785.196622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:39:56.00964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:39:55.224368Z","time spent":"785.269014ms","remote":"127.0.0.1:40280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-21T17:39:56.009592Z","caller":"traceutil/trace.go:171","msg":"trace[2110254862] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"2.695457979s","start":"2026-04-21T17:39:53.314106Z","end":"2026-04-21T17:39:56.009564Z","steps":["trace[2110254862] 'process raft request' (duration: 2.69503168s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:39:56.0097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"943.767808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:39:56.009712Z","caller":"traceutil/trace.go:171","msg":"trace[1400621193] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:377; }","duration":"943.808219ms","start":"2026-04-21T17:39:55.0659Z","end":"2026-04-21T17:39:56.009708Z","steps":["trace[1400621193] 'agreement among raft nodes before linearized reading' (duration: 943.789989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:39:56.009724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:39:55.065885Z","time spent":"943.83682ms","remote":"127.0.0.1:39960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T17:39:56.009792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.652219548s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-21T17:39:56.009808Z","caller":"traceutil/trace.go:171","msg":"trace[705826557] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:377; }","duration":"1.652262169s","start":"2026-04-21T17:39:54.357542Z","end":"2026-04-21T17:39:56.009804Z","steps":["trace[705826557] 'agreement among raft nodes before linearized reading' (duration: 1.652237139s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:39:56.00982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:39:54.357527Z","time spent":"1.65229014s","remote":"127.0.0.1:40280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-21T17:39:56.010029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:39:53.314097Z","time spent":"2.695612742s","remote":"127.0.0.1:40450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T17:39:56.012532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.674333633s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-21T17:39:56.01272Z","caller":"traceutil/trace.go:171","msg":"trace[368383125] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:377; }","duration":"2.674567849s","start":"2026-04-21T17:39:53.338112Z","end":"2026-04-21T17:39:56.01268Z","steps":["trace[368383125] 'agreement among raft nodes before linearized reading' (duration: 2.671177814s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:39:56.01745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:39:53.338093Z","time spent":"2.679331375s","remote":"127.0.0.1:40280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-21T17:40:07.260134Z","caller":"traceutil/trace.go:171","msg":"trace[305094078] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"111.327515ms","start":"2026-04-21T17:40:07.148723Z","end":"2026-04-21T17:40:07.260051Z","steps":["trace[305094078] 'process raft request' (duration: 111.168621ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:40:07.763594Z","caller":"traceutil/trace.go:171","msg":"trace[1886210412] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"604.918857ms","start":"2026-04-21T17:40:07.158638Z","end":"2026-04-21T17:40:07.763557Z","steps":["trace[1886210412] 'process raft request' (duration: 601.096583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:40:07.76376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:40:07.158627Z","time spent":"605.05124ms","remote":"127.0.0.1:40010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T17:40:07.766956Z","caller":"traceutil/trace.go:171","msg":"trace[1205598734] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"481.24232ms","start":"2026-04-21T17:40:07.285625Z","end":"2026-04-21T17:40:07.766867Z","steps":["trace[1205598734] 'process raft request' (duration: 480.910523ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:40:07.767292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:40:07.285598Z","time spent":"481.483006ms","remote":"127.0.0.1:40010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29546,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T17:40:07.767558Z","caller":"traceutil/trace.go:171","msg":"trace[2042202271] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"476.114798ms","start":"2026-04-21T17:40:07.29142Z","end":"2026-04-21T17:40:07.767535Z","steps":["trace[2042202271] 'process raft request' (duration: 475.376332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:40:07.767784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:40:07.291412Z","time spent":"476.25193ms","remote":"127.0.0.1:40010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T17:40:07.767943Z","caller":"traceutil/trace.go:171","msg":"trace[389946675] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"474.563523ms","start":"2026-04-21T17:40:07.293355Z","end":"2026-04-21T17:40:07.767918Z","steps":["trace[389946675] 'process raft request' (duration: 474.018771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:40:07.768052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:40:07.293336Z","time spent":"474.682815ms","remote":"127.0.0.1:40010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T17:40:14.548153Z","caller":"traceutil/trace.go:171","msg":"trace[91980940] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"751.581811ms","start":"2026-04-21T17:40:13.796546Z","end":"2026-04-21T17:40:14.548127Z","steps":["trace[91980940] 'process raft request' (duration: 751.340956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:40:14.548312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:40:13.796517Z","time spent":"751.718194ms","remote":"127.0.0.1:40176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T17:40:15.225822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.505488ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T17:40:15.225938Z","caller":"traceutil/trace.go:171","msg":"trace[2109404274] linearizableReadLoop","detail":"{readStateIndex:589; appliedIndex:587; }","duration":"1.071192527s","start":"2026-04-21T17:40:14.154728Z","end":"2026-04-21T17:40:15.225921Z","steps":["trace[2109404274] 'read index received' (duration: 393.358913ms)","trace[2109404274] 'applied index is now lower than readState.Index' (duration: 677.832524ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:40:15.226359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.071597297s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-21T17:40:15.226474Z","caller":"traceutil/trace.go:171","msg":"trace[1632831562] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:563; }","duration":"1.07176027s","start":"2026-04-21T17:40:14.154682Z","end":"2026-04-21T17:40:15.226442Z","steps":["trace[1632831562] 'agreement among raft nodes before linearized reading' (duration: 1.071404912s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:40:15.226524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:40:14.154624Z","time spent":"1.071889803s","remote":"127.0.0.1:40280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-21T17:40:15.226561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.066589606s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-21T17:40:15.226579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.064810616s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-21T17:40:15.226632Z","caller":"traceutil/trace.go:171","msg":"trace[1919613810] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:563; }","duration":"1.066683807s","start":"2026-04-21T17:40:14.15993Z","end":"2026-04-21T17:40:15.226613Z","steps":["trace[1919613810] 'agreement among raft nodes before linearized reading' (duration: 1.066461842s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:40:15.226668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:40:14.159923Z","time spent":"1.066738689s","remote":"127.0.0.1:40280","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-04-21T17:40:15.22666Z","caller":"traceutil/trace.go:171","msg":"trace[1255288290] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:563; }","duration":"1.064923989s","start":"2026-04-21T17:40:14.161714Z","end":"2026-04-21T17:40:15.226638Z","steps":["trace[1255288290] 'agreement among raft nodes before linearized reading' (duration: 1.064686704s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:40:15.226712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:40:14.161699Z","time spent":"1.0650044s","remote":"127.0.0.1:40280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-21T17:40:15.226739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.065281797s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-21T17:40:15.226771Z","caller":"traceutil/trace.go:171","msg":"trace[394800221] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"1.120700868s","start":"2026-04-21T17:40:14.106047Z","end":"2026-04-21T17:40:15.226748Z","steps":["trace[394800221] 'process raft request' (duration: 764.179999ms)","trace[394800221] 'compare' (duration: 355.088637ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:40:15.226798Z","caller":"traceutil/trace.go:171","msg":"trace[666522964] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:563; }","duration":"1.065382489s","start":"2026-04-21T17:40:14.161393Z","end":"2026-04-21T17:40:15.226775Z","steps":["trace[666522964] 'agreement among raft nodes before linearized reading' (duration: 1.065226465s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:40:15.226847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:40:14.161367Z","time spent":"1.06546943s","remote":"127.0.0.1:40280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-21T17:40:15.226904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.11891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T17:40:15.226898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:40:14.106017Z","time spent":"1.12078836s","remote":"127.0.0.1:40074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036153,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T17:40:15.226925Z","caller":"traceutil/trace.go:171","msg":"trace[692546634] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:563; }","duration":"160.183952ms","start":"2026-04-21T17:40:15.066735Z","end":"2026-04-21T17:40:15.226919Z","steps":["trace[692546634] 'agreement among raft nodes before linearized reading' (duration: 160.137181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:40:16.235804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"908.827668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:40:16.235896Z","caller":"traceutil/trace.go:171","msg":"trace[1594160960] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:568; }","duration":"908.989681ms","start":"2026-04-21T17:40:15.326885Z","end":"2026-04-21T17:40:16.235875Z","steps":["trace[1594160960] 'range keys from in-memory index tree' (duration: 908.693445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:40:16.235952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:40:15.326849Z","time spent":"909.092424ms","remote":"127.0.0.1:40348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-04-21T17:40:16.877789Z","caller":"traceutil/trace.go:171","msg":"trace[1942130325] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"243.877347ms","start":"2026-04-21T17:40:16.633881Z","end":"2026-04-21T17:40:16.877758Z","steps":["trace[1942130325] 'process raft request' (duration: 243.141661ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:40:18.207611Z","caller":"traceutil/trace.go:171","msg":"trace[247381546] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:608; }","duration":"139.751961ms","start":"2026-04-21T17:40:18.067822Z","end":"2026-04-21T17:40:18.207573Z","steps":["trace[247381546] 'read index received' (duration: 139.477845ms)","trace[247381546] 'applied index is now lower than readState.Index' (duration: 272.506µs)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:40:18.208136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.290203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:40:18.208264Z","caller":"traceutil/trace.go:171","msg":"trace[1458510955] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:582; }","duration":"140.451196ms","start":"2026-04-21T17:40:18.067755Z","end":"2026-04-21T17:40:18.208206Z","steps":["trace[1458510955] 'agreement among raft nodes before linearized reading' (duration: 139.971016ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:40:18.208997Z","caller":"traceutil/trace.go:171","msg":"trace[160651642] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"222.961176ms","start":"2026-04-21T17:40:17.986001Z","end":"2026-04-21T17:40:18.208962Z","steps":["trace[160651642] 'process raft request' (duration: 221.34751ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:40:30.87778Z","caller":"traceutil/trace.go:171","msg":"trace[2094777276] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"227.001275ms","start":"2026-04-21T17:40:30.650732Z","end":"2026-04-21T17:40:30.877733Z","steps":["trace[2094777276] 'process raft request' (duration: 226.757069ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:40:33.553598Z","caller":"traceutil/trace.go:171","msg":"trace[1410464043] linearizableReadLoop","detail":"{readStateIndex:774; appliedIndex:773; }","duration":"186.702597ms","start":"2026-04-21T17:40:33.366868Z","end":"2026-04-21T17:40:33.553571Z","steps":["trace[1410464043] 'read index received' (duration: 186.505903ms)","trace[1410464043] 'applied index is now lower than readState.Index' (duration: 195.034µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:40:33.553701Z","caller":"traceutil/trace.go:171","msg":"trace[1576556098] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"189.748214ms","start":"2026-04-21T17:40:33.363895Z","end":"2026-04-21T17:40:33.553643Z","steps":["trace[1576556098] 'process raft request' (duration: 189.521959ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:40:33.553885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.996863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-21T17:40:33.553955Z","caller":"traceutil/trace.go:171","msg":"trace[1572472297] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:743; }","duration":"187.100555ms","start":"2026-04-21T17:40:33.366843Z","end":"2026-04-21T17:40:33.553943Z","steps":["trace[1572472297] 'agreement among raft nodes before linearized reading' (duration: 186.896631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:40:33.554183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.587074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-21T17:40:33.554216Z","caller":"traceutil/trace.go:171","msg":"trace[1421812030] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:743; }","duration":"176.643105ms","start":"2026-04-21T17:40:33.377564Z","end":"2026-04-21T17:40:33.554208Z","steps":["trace[1421812030] 'agreement among raft nodes before linearized reading' (duration: 176.517862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:40:33.554308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.614306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-21T17:40:33.554546Z","caller":"traceutil/trace.go:171","msg":"trace[802635616] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:743; }","duration":"182.697968ms","start":"2026-04-21T17:40:33.371644Z","end":"2026-04-21T17:40:33.554342Z","steps":["trace[802635616] 'agreement among raft nodes before linearized reading' (duration: 182.380511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:41:08.63162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.309515ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T17:41:08.631715Z","caller":"traceutil/trace.go:171","msg":"trace[1576500779] linearizableReadLoop","detail":"{readStateIndex:996; appliedIndex:995; }","duration":"807.595288ms","start":"2026-04-21T17:41:07.824106Z","end":"2026-04-21T17:41:08.631701Z","steps":["trace[1576500779] 'read index received' (duration: 391.045268ms)","trace[1576500779] 'applied index is now lower than readState.Index' (duration: 416.54866ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:41:08.631757Z","caller":"traceutil/trace.go:171","msg":"trace[1909435796] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"808.49168ms","start":"2026-04-21T17:41:07.823229Z","end":"2026-04-21T17:41:08.63172Z","steps":["trace[1909435796] 'process raft request' (duration: 391.98849ms)","trace[1909435796] 'compare' (duration: 416.07446ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:41:08.631857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.746932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-21T17:41:08.631881Z","caller":"traceutil/trace.go:171","msg":"trace[1205056997] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:956; }","duration":"807.785762ms","start":"2026-04-21T17:41:07.824088Z","end":"2026-04-21T17:41:08.631874Z","steps":["trace[1205056997] 'agreement among raft nodes before linearized reading' (duration: 807.68369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:41:08.631903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:41:07.824037Z","time spent":"807.860955ms","remote":"127.0.0.1:40280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-21T17:41:08.631934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:41:07.823212Z","time spent":"808.629072ms","remote":"127.0.0.1:40280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T17:41:08.632148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.068752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:41:08.632181Z","caller":"traceutil/trace.go:171","msg":"trace[631496822] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:956; }","duration":"568.142834ms","start":"2026-04-21T17:41:08.064031Z","end":"2026-04-21T17:41:08.632173Z","steps":["trace[631496822] 'agreement among raft nodes before linearized reading' (duration: 568.092993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:41:08.632199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:41:08.064014Z","time spent":"568.180554ms","remote":"127.0.0.1:39960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T17:41:08.632589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.989587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-21T17:41:08.632679Z","caller":"traceutil/trace.go:171","msg":"trace[298336596] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:956; }","duration":"808.045118ms","start":"2026-04-21T17:41:07.824581Z","end":"2026-04-21T17:41:08.632626Z","steps":["trace[298336596] 'agreement among raft nodes before linearized reading' (duration: 807.458525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:41:08.6327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:41:07.824576Z","time spent":"808.11846ms","remote":"127.0.0.1:40280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-21T17:41:09.642541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.622568ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T17:41:09.642649Z","caller":"traceutil/trace.go:171","msg":"trace[1894175696] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"1.004260269s","start":"2026-04-21T17:41:08.63837Z","end":"2026-04-21T17:41:09.642631Z","steps":["trace[1894175696] 'process raft request' (duration: 441.467018ms)","trace[1894175696] 'compare' (duration: 562.395202ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:41:09.642712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:41:08.63834Z","time spent":"1.004340531s","remote":"127.0.0.1:40280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T17:41:09.698852Z","caller":"traceutil/trace.go:171","msg":"trace[939952666] linearizableReadLoop","detail":"{readStateIndex:998; appliedIndex:996; }","duration":"938.114892ms","start":"2026-04-21T17:41:08.760707Z","end":"2026-04-21T17:41:09.698822Z","steps":["trace[939952666] 'read index received' (duration: 319.135311ms)","trace[939952666] 'applied index is now lower than readState.Index' (duration: 618.978281ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:41:09.699421Z","caller":"traceutil/trace.go:171","msg":"trace[1201064096] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"1.059228301s","start":"2026-04-21T17:41:08.639664Z","end":"2026-04-21T17:41:09.698892Z","steps":["trace[1201064096] 'process raft request' (duration: 1.058995485s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:41:09.699606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:41:08.639656Z","time spent":"1.059846544s","remote":"127.0.0.1:40280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T17:41:09.706319Z","caller":"traceutil/trace.go:171","msg":"trace[844366856] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"477.480308ms","start":"2026-04-21T17:41:09.228813Z","end":"2026-04-21T17:41:09.706293Z","steps":["trace[844366856] 'process raft request' (duration: 473.793584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:41:09.706394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.928771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T17:41:09.706517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.680358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-21T17:41:09.706571Z","caller":"traceutil/trace.go:171","msg":"trace[1394440765] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:959; }","duration":"641.122736ms","start":"2026-04-21T17:41:09.065418Z","end":"2026-04-21T17:41:09.706541Z","steps":["trace[1394440765] 'agreement among raft nodes before linearized reading' (duration: 640.86655ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:41:09.706588Z","caller":"traceutil/trace.go:171","msg":"trace[982839966] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:959; }","duration":"626.77883ms","start":"2026-04-21T17:41:09.07978Z","end":"2026-04-21T17:41:09.706559Z","steps":["trace[982839966] 'agreement among raft nodes before linearized reading' (duration: 626.524473ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:41:09.70664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:41:09.079755Z","time spent":"626.873891ms","remote":"127.0.0.1:40190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-04-21T17:41:09.706665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:41:09.065398Z","time spent":"641.226948ms","remote":"127.0.0.1:39960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T17:41:09.706455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.755046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-21T17:41:09.707143Z","caller":"traceutil/trace.go:171","msg":"trace[277413256] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:958; }","duration":"946.454842ms","start":"2026-04-21T17:41:08.760652Z","end":"2026-04-21T17:41:09.707107Z","steps":["trace[277413256] 'agreement among raft nodes before linearized reading' (duration: 938.292576ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:41:09.707339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:41:08.760631Z","time spent":"946.672498ms","remote":"127.0.0.1:40280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-21T17:41:09.707715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:41:09.22879Z","time spent":"477.61632ms","remote":"127.0.0.1:40280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T17:41:35.389347Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367165319848720,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-21T17:41:35.472546Z","caller":"traceutil/trace.go:171","msg":"trace[1357906278] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"700.98178ms","start":"2026-04-21T17:41:34.771528Z","end":"2026-04-21T17:41:35.47251Z","steps":["trace[1357906278] 'process raft request' (duration: 700.783705ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:41:35.472936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:41:34.77151Z","time spent":"701.308897ms","remote":"127.0.0.1:40280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T17:41:36.002058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.596329ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T17:41:36.002196Z","caller":"traceutil/trace.go:171","msg":"trace[452141909] linearizableReadLoop","detail":"{readStateIndex:1088; appliedIndex:1086; }","duration":"1.113509277s","start":"2026-04-21T17:41:34.888664Z","end":"2026-04-21T17:41:36.002173Z","steps":["trace[452141909] 'read index received' (duration: 583.760209ms)","trace[452141909] 'applied index is now lower than readState.Index' (duration: 529.747068ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:41:36.002295Z","caller":"traceutil/trace.go:171","msg":"trace[1289624185] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"728.688941ms","start":"2026-04-21T17:41:35.273589Z","end":"2026-04-21T17:41:36.002278Z","steps":["trace[1289624185] 'process raft request' (duration: 545.739643ms)","trace[1289624185] 'compare' (duration: 182.314893ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:41:36.002385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:41:35.27356Z","time spent":"728.789833ms","remote":"127.0.0.1:40280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T17:41:36.00252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.113811815s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:41:36.002671Z","caller":"traceutil/trace.go:171","msg":"trace[1374045971] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1043; }","duration":"1.11404672s","start":"2026-04-21T17:41:34.888592Z","end":"2026-04-21T17:41:36.002638Z","steps":["trace[1374045971] 'agreement among raft nodes before linearized reading' (duration: 1.113755883s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:41:36.002732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:41:34.888567Z","time spent":"1.114153663s","remote":"127.0.0.1:40228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-04-21T17:41:36.041995Z","caller":"traceutil/trace.go:171","msg":"trace[706487786] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"238.270678ms","start":"2026-04-21T17:41:35.803703Z","end":"2026-04-21T17:41:36.041974Z","steps":["trace[706487786] 'process raft request' (duration: 238.014082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:41:36.042304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.338767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-21T17:41:36.042387Z","caller":"traceutil/trace.go:171","msg":"trace[1648981952] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1044; }","duration":"201.45663ms","start":"2026-04-21T17:41:35.840913Z","end":"2026-04-21T17:41:36.04237Z","steps":["trace[1648981952] 'agreement among raft nodes before linearized reading' (duration: 201.022809ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:41:36.042405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.57154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:41:36.042439Z","caller":"traceutil/trace.go:171","msg":"trace[1096154401] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1044; }","duration":"761.659162ms","start":"2026-04-21T17:41:35.280769Z","end":"2026-04-21T17:41:36.042428Z","steps":["trace[1096154401] 'agreement among raft nodes before linearized reading' (duration: 761.586541ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:41:36.042468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:41:35.280702Z","time spent":"761.760175ms","remote":"127.0.0.1:39450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-21T17:41:36.042545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.922853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-21T17:41:36.042685Z","caller":"traceutil/trace.go:171","msg":"trace[1023155815] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1044; }","duration":"210.102486ms","start":"2026-04-21T17:41:35.832558Z","end":"2026-04-21T17:41:36.042661Z","steps":["trace[1023155815] 'agreement among raft nodes before linearized reading' (duration: 209.83596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:41:36.042742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"975.850322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:41:36.042775Z","caller":"traceutil/trace.go:171","msg":"trace[1114792458] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1044; }","duration":"975.937745ms","start":"2026-04-21T17:41:35.066829Z","end":"2026-04-21T17:41:36.042767Z","steps":["trace[1114792458] 'agreement among raft nodes before linearized reading' (duration: 975.890924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:41:36.042796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:41:35.066787Z","time spent":"976.003966ms","remote":"127.0.0.1:39960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T17:41:36.043209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"976.412545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:41:36.043273Z","caller":"traceutil/trace.go:171","msg":"trace[410790142] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1044; }","duration":"976.531028ms","start":"2026-04-21T17:41:35.066725Z","end":"2026-04-21T17:41:36.043256Z","steps":["trace[410790142] 'agreement among raft nodes before linearized reading' (duration: 975.178746ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:41:36.043398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.875376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-21T17:41:36.043312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:41:35.066698Z","time spent":"976.60467ms","remote":"127.0.0.1:40320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-21T17:41:36.043458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.662721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-21T17:41:36.04353Z","caller":"traceutil/trace.go:171","msg":"trace[760572191] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1044; }","duration":"118.894638ms","start":"2026-04-21T17:41:35.924604Z","end":"2026-04-21T17:41:36.043498Z","steps":["trace[760572191] 'agreement among raft nodes before linearized reading' (duration: 117.727112ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:41:36.043624Z","caller":"traceutil/trace.go:171","msg":"trace[1120109780] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1044; }","duration":"214.836625ms","start":"2026-04-21T17:41:35.828731Z","end":"2026-04-21T17:41:36.043567Z","steps":["trace[1120109780] 'agreement among raft nodes before linearized reading' (duration: 213.493965ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:41:37.876994Z","caller":"traceutil/trace.go:171","msg":"trace[2072327422] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"393.941015ms","start":"2026-04-21T17:41:37.483027Z","end":"2026-04-21T17:41:37.876968Z","steps":["trace[2072327422] 'process raft request' (duration: 393.72799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:41:37.877199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:41:37.483005Z","time spent":"394.048128ms","remote":"127.0.0.1:40280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T17:41:38.243008Z","caller":"traceutil/trace.go:171","msg":"trace[1544675127] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"102.712361ms","start":"2026-04-21T17:41:38.140266Z","end":"2026-04-21T17:41:38.242979Z","steps":["trace[1544675127] 'process raft request' (duration: 64.036779ms)","trace[1544675127] 'compare' (duration: 38.484737ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:41:38.243463Z","caller":"traceutil/trace.go:171","msg":"trace[600930292] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"101.222716ms","start":"2026-04-21T17:41:38.1422Z","end":"2026-04-21T17:41:38.243423Z","steps":["trace[600930292] 'process raft request' (duration: 101.068802ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:41:38.243479Z","caller":"traceutil/trace.go:171","msg":"trace[1179274377] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"102.408862ms","start":"2026-04-21T17:41:38.14105Z","end":"2026-04-21T17:41:38.243459Z","steps":["trace[1179274377] 'process raft request' (duration: 101.892251ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:41:40.026216Z","caller":"traceutil/trace.go:171","msg":"trace[568691640] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"140.49334ms","start":"2026-04-21T17:41:39.885694Z","end":"2026-04-21T17:41:40.026188Z","steps":["trace[568691640] 'process raft request' (duration: 140.344857ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:41:50.356272Z","caller":"traceutil/trace.go:171","msg":"trace[695691244] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"109.894258ms","start":"2026-04-21T17:41:50.246324Z","end":"2026-04-21T17:41:50.356218Z","steps":["trace[695691244] 'process raft request' (duration: 107.494484ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:41:50.35649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.318166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-21T17:41:50.356605Z","caller":"traceutil/trace.go:171","msg":"trace[1846190706] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1140; }","duration":"100.48559ms","start":"2026-04-21T17:41:50.256093Z","end":"2026-04-21T17:41:50.356578Z","steps":["trace[1846190706] 'agreement among raft nodes before linearized reading' (duration: 100.158082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:41:52.864592Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367165319848958,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-21T17:41:53.231853Z","caller":"traceutil/trace.go:171","msg":"trace[638560884] linearizableReadLoop","detail":"{readStateIndex:1199; appliedIndex:1198; }","duration":"867.891432ms","start":"2026-04-21T17:41:52.363931Z","end":"2026-04-21T17:41:53.231822Z","steps":["trace[638560884] 'read index received' (duration: 867.670357ms)","trace[638560884] 'applied index is now lower than readState.Index' (duration: 220.264µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:41:53.232186Z","caller":"traceutil/trace.go:171","msg":"trace[1712803375] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"870.715054ms","start":"2026-04-21T17:41:52.361462Z","end":"2026-04-21T17:41:53.232177Z","steps":["trace[1712803375] 'process raft request' (duration: 870.216993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:41:53.232292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:41:52.361436Z","time spent":"870.775116ms","remote":"127.0.0.1:40280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T17:41:53.232543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"868.616198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-21T17:41:53.232575Z","caller":"traceutil/trace.go:171","msg":"trace[853529929] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1148; }","duration":"868.649599ms","start":"2026-04-21T17:41:52.363919Z","end":"2026-04-21T17:41:53.232568Z","steps":["trace[853529929] 'agreement among raft nodes before linearized reading' (duration: 868.515826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:41:53.232602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:41:52.363871Z","time spent":"868.72673ms","remote":"127.0.0.1:40280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-21T17:41:53.23277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"865.362506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-21T17:41:53.232832Z","caller":"traceutil/trace.go:171","msg":"trace[530197219] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1148; }","duration":"865.449948ms","start":"2026-04-21T17:41:52.367366Z","end":"2026-04-21T17:41:53.232816Z","steps":["trace[530197219] 'agreement among raft nodes before linearized reading' (duration: 865.306215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:41:53.232879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.430673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:41:53.232907Z","caller":"traceutil/trace.go:171","msg":"trace[667351389] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1148; }","duration":"167.524506ms","start":"2026-04-21T17:41:53.065376Z","end":"2026-04-21T17:41:53.232901Z","steps":["trace[667351389] 'agreement among raft nodes before linearized reading' (duration: 167.485335ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:41:53.232959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.770163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-21T17:41:53.232876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:41:52.367355Z","time spent":"865.513119ms","remote":"127.0.0.1:40280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-21T17:41:53.232986Z","caller":"traceutil/trace.go:171","msg":"trace[409379899] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1148; }","duration":"864.804924ms","start":"2026-04-21T17:41:52.368173Z","end":"2026-04-21T17:41:53.232978Z","steps":["trace[409379899] 'agreement among raft nodes before linearized reading' (duration: 864.723222ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:41:53.233013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:41:52.368169Z","time spent":"864.838554ms","remote":"127.0.0.1:40280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-21T17:41:53.232841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.620433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-21T17:41:53.233089Z","caller":"traceutil/trace.go:171","msg":"trace[501522022] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1148; }","duration":"343.924849ms","start":"2026-04-21T17:41:52.889158Z","end":"2026-04-21T17:41:53.233083Z","steps":["trace[501522022] 'agreement among raft nodes before linearized reading' (duration: 343.657803ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:41:53.233112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:41:52.889131Z","time spent":"343.97437ms","remote":"127.0.0.1:40170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":5,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-04-21T17:41:53.233217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"865.705384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-21T17:41:53.233245Z","caller":"traceutil/trace.go:171","msg":"trace[1251353032] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1148; }","duration":"865.745244ms","start":"2026-04-21T17:41:52.367491Z","end":"2026-04-21T17:41:53.233236Z","steps":["trace[1251353032] 'agreement among raft nodes before linearized reading' (duration: 865.664883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:41:53.233274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:41:52.367485Z","time spent":"865.783505ms","remote":"127.0.0.1:40280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-21T17:41:53.628354Z","caller":"traceutil/trace.go:171","msg":"trace[334771907] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"391.211987ms","start":"2026-04-21T17:41:53.23711Z","end":"2026-04-21T17:41:53.628322Z","steps":["trace[334771907] 'process raft request' (duration: 388.782543ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:41:53.628389Z","caller":"traceutil/trace.go:171","msg":"trace[915684601] linearizableReadLoop","detail":"{readStateIndex:1203; appliedIndex:1199; }","duration":"285.478842ms","start":"2026-04-21T17:41:53.342882Z","end":"2026-04-21T17:41:53.628361Z","steps":["trace[915684601] 'read index received' (duration: 282.919045ms)","trace[915684601] 'applied index is now lower than readState.Index' (duration: 2.558857ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:41:53.628499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:41:53.237094Z","time spent":"391.33129ms","remote":"127.0.0.1:40280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T17:41:53.628606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.703258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:41:53.628656Z","caller":"traceutil/trace.go:171","msg":"trace[678181653] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1152; }","duration":"285.778639ms","start":"2026-04-21T17:41:53.342864Z","end":"2026-04-21T17:41:53.628643Z","steps":["trace[678181653] 'agreement among raft nodes before linearized reading' (duration: 285.596085ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:41:53.628727Z","caller":"traceutil/trace.go:171","msg":"trace[781437209] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"391.543095ms","start":"2026-04-21T17:41:53.237178Z","end":"2026-04-21T17:41:53.628721Z","steps":["trace[781437209] 'process raft request' (duration: 391.013334ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:41:53.628764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:41:53.237162Z","time spent":"391.586146ms","remote":"127.0.0.1:40280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T17:41:53.628895Z","caller":"traceutil/trace.go:171","msg":"trace[1572986738] transaction","detail":"{read_only:false; response_revision:1151; number_of_response:1; }","duration":"387.568067ms","start":"2026-04-21T17:41:53.241316Z","end":"2026-04-21T17:41:53.628884Z","steps":["trace[1572986738] 'process raft request' (duration: 386.977884ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:41:53.628999Z","caller":"traceutil/trace.go:171","msg":"trace[1350508236] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"387.024025ms","start":"2026-04-21T17:41:53.24197Z","end":"2026-04-21T17:41:53.628994Z","steps":["trace[1350508236] 'process raft request' (duration: 386.35745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:41:53.628999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:41:53.241306Z","time spent":"387.628388ms","remote":"127.0.0.1:40280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T17:41:53.62904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:41:53.24196Z","time spent":"387.059876ms","remote":"127.0.0.1:40280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T17:42:05.796493Z","caller":"traceutil/trace.go:171","msg":"trace[150337370] linearizableReadLoop","detail":"{readStateIndex:1242; appliedIndex:1240; }","duration":"103.830583ms","start":"2026-04-21T17:42:05.692634Z","end":"2026-04-21T17:42:05.796465Z","steps":["trace[150337370] 'read index received' (duration: 43.176037ms)","trace[150337370] 'applied index is now lower than readState.Index' (duration: 60.653576ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:42:05.796633Z","caller":"traceutil/trace.go:171","msg":"trace[1380743790] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"104.795715ms","start":"2026-04-21T17:42:05.691786Z","end":"2026-04-21T17:42:05.796582Z","steps":["trace[1380743790] 'process raft request' (duration: 104.455087ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:42:05.796665Z","caller":"traceutil/trace.go:171","msg":"trace[2075678765] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"102.793101ms","start":"2026-04-21T17:42:05.693844Z","end":"2026-04-21T17:42:05.796637Z","steps":["trace[2075678765] 'process raft request' (duration: 102.571556ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:42:05.796696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.031387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-21T17:42:05.796735Z","caller":"traceutil/trace.go:171","msg":"trace[822537363] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1190; }","duration":"104.093789ms","start":"2026-04-21T17:42:05.692631Z","end":"2026-04-21T17:42:05.796725Z","steps":["trace[822537363] 'agreement among raft nodes before linearized reading' (duration: 103.935735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:42:08.855535Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367165319849094,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T17:42:09.112251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.616075ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-21T17:42:09.112442Z","caller":"traceutil/trace.go:171","msg":"trace[437915961] linearizableReadLoop","detail":"{readStateIndex:1262; appliedIndex:1261; }","duration":"757.875252ms","start":"2026-04-21T17:42:08.354546Z","end":"2026-04-21T17:42:09.112421Z","steps":["trace[437915961] 'read index received' (duration: 627.913228ms)","trace[437915961] 'applied index is now lower than readState.Index' (duration: 129.960454ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:42:09.112729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.163578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:42:09.112813Z","caller":"traceutil/trace.go:171","msg":"trace[680997880] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1208; }","duration":"758.315091ms","start":"2026-04-21T17:42:08.354481Z","end":"2026-04-21T17:42:09.112796Z","steps":["trace[680997880] 'agreement among raft nodes before linearized reading' (duration: 758.124807ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:42:09.112848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:42:08.35446Z","time spent":"758.381743ms","remote":"127.0.0.1:39330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"info","ts":"2026-04-21T17:42:09.112935Z","caller":"traceutil/trace.go:171","msg":"trace[205099657] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"872.724479ms","start":"2026-04-21T17:42:08.239712Z","end":"2026-04-21T17:42:09.112436Z","steps":["trace[205099657] 'process raft request' (duration: 742.771716ms)","trace[205099657] 'compare' (duration: 129.423881ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:42:09.113219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:42:08.239684Z","time spent":"873.447145ms","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":871,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T17:42:09.706172Z","caller":"traceutil/trace.go:171","msg":"trace[984036313] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"416.416917ms","start":"2026-04-21T17:42:09.289708Z","end":"2026-04-21T17:42:09.706125Z","steps":["trace[984036313] 'process raft request' (duration: 408.108522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:42:09.706363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:42:09.28968Z","time spent":"416.585561ms","remote":"127.0.0.1:40280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T17:42:10.913778Z","caller":"traceutil/trace.go:171","msg":"trace[803394791] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"359.911975ms","start":"2026-04-21T17:42:10.553833Z","end":"2026-04-21T17:42:10.913745Z","steps":["trace[803394791] 'process raft request' (duration: 359.7446ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:42:10.91395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:42:10.553807Z","time spent":"360.064607ms","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":757,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T17:42:11.546873Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367165319849112,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-21T17:42:11.629456Z","caller":"traceutil/trace.go:171","msg":"trace[467363817] linearizableReadLoop","detail":"{readStateIndex:1270; appliedIndex:1269; }","duration":"583.087484ms","start":"2026-04-21T17:42:11.046341Z","end":"2026-04-21T17:42:11.629428Z","steps":["trace[467363817] 'read index received' (duration: 582.741536ms)","trace[467363817] 'applied index is now lower than readState.Index' (duration: 344.888µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:42:11.629738Z","caller":"traceutil/trace.go:171","msg":"trace[2100508623] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"702.607566ms","start":"2026-04-21T17:42:10.927091Z","end":"2026-04-21T17:42:11.629698Z","steps":["trace[2100508623] 'process raft request' (duration: 702.168826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:42:11.629916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:42:10.92707Z","time spent":"702.756219ms","remote":"127.0.0.1:40172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5457,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T17:42:11.629981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.620396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-21T17:42:11.630085Z","caller":"traceutil/trace.go:171","msg":"trace[866899873] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:1216; }","duration":"583.785849ms","start":"2026-04-21T17:42:11.046266Z","end":"2026-04-21T17:42:11.630052Z","steps":["trace[866899873] 'agreement among raft nodes before linearized reading' (duration: 583.454112ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:42:11.630134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:42:11.046215Z","time spent":"583.910252ms","remote":"127.0.0.1:39994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.109\" "} {"level":"warn","ts":"2026-04-21T17:42:11.640893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.68349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-21T17:42:11.640961Z","caller":"traceutil/trace.go:171","msg":"trace[374667086] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1217; }","duration":"392.820044ms","start":"2026-04-21T17:42:11.248123Z","end":"2026-04-21T17:42:11.640943Z","steps":["trace[374667086] 'agreement among raft nodes before linearized reading' (duration: 392.65372ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:42:11.640996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:42:11.248019Z","time spent":"392.968527ms","remote":"127.0.0.1:39462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":4,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-04-21T17:42:11.641433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.4886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:42:11.641532Z","caller":"traceutil/trace.go:171","msg":"trace[458051111] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1217; }","duration":"459.207406ms","start":"2026-04-21T17:42:11.182303Z","end":"2026-04-21T17:42:11.64151Z","steps":["trace[458051111] 'agreement among raft nodes before linearized reading' (duration: 458.46359ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:42:11.641586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:42:11.182281Z","time spent":"459.289969ms","remote":"127.0.0.1:39272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-04-21T17:42:12.50895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.071928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-21T17:42:12.509136Z","caller":"traceutil/trace.go:171","msg":"trace[570657246] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1227; }","duration":"200.319193ms","start":"2026-04-21T17:42:12.308793Z","end":"2026-04-21T17:42:12.509112Z","steps":["trace[570657246] 'range keys from in-memory index tree' (duration: 199.826582ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:42:12.96963Z","caller":"traceutil/trace.go:171","msg":"trace[869483372] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"455.566895ms","start":"2026-04-21T17:42:12.514033Z","end":"2026-04-21T17:42:12.9696Z","steps":["trace[869483372] 'process raft request' (duration: 455.392391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:42:12.969808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:42:12.514004Z","time spent":"455.714248ms","remote":"127.0.0.1:40280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T17:42:12.995189Z","caller":"traceutil/trace.go:171","msg":"trace[1744206607] linearizableReadLoop","detail":"{readStateIndex:1284; appliedIndex:1282; }","duration":"402.801235ms","start":"2026-04-21T17:42:12.592361Z","end":"2026-04-21T17:42:12.995162Z","steps":["trace[1744206607] 'read index received' (duration: 377.107974ms)","trace[1744206607] 'applied index is now lower than readState.Index' (duration: 25.692291ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:42:12.995262Z","caller":"traceutil/trace.go:171","msg":"trace[2048119471] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"476.020229ms","start":"2026-04-21T17:42:12.519182Z","end":"2026-04-21T17:42:12.995202Z","steps":["trace[2048119471] 'process raft request' (duration: 475.830404ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:42:12.995443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.449107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T17:42:12.995458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:42:12.519165Z","time spent":"476.201374ms","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":763,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T17:42:12.995493Z","caller":"traceutil/trace.go:171","msg":"trace[1494198675] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1229; }","duration":"108.525248ms","start":"2026-04-21T17:42:12.886953Z","end":"2026-04-21T17:42:12.995478Z","steps":["trace[1494198675] 'agreement among raft nodes before linearized reading' (duration: 108.386055ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:42:12.995585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.190054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-vkhs8\" ","response":"range_response_count:1 size:11061"} {"level":"info","ts":"2026-04-21T17:42:12.995664Z","caller":"traceutil/trace.go:171","msg":"trace[1952238038] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-vkhs8; range_end:; response_count:1; response_revision:1229; }","duration":"403.320447ms","start":"2026-04-21T17:42:12.592311Z","end":"2026-04-21T17:42:12.995632Z","steps":["trace[1952238038] 'agreement among raft nodes before linearized reading' (duration: 403.00791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:42:12.995729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:42:12.592288Z","time spent":"403.425229ms","remote":"127.0.0.1:40176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":11084,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-vkhs8\" "} {"level":"info","ts":"2026-04-21T17:42:13.129711Z","caller":"traceutil/trace.go:171","msg":"trace[1978918300] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"123.374267ms","start":"2026-04-21T17:42:13.006292Z","end":"2026-04-21T17:42:13.129666Z","steps":["trace[1978918300] 'process raft request' (duration: 123.136491ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:42:13.377601Z","caller":"traceutil/trace.go:171","msg":"trace[592652272] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"241.302362ms","start":"2026-04-21T17:42:13.136266Z","end":"2026-04-21T17:42:13.377568Z","steps":["trace[592652272] 'process raft request' (duration: 184.426621ms)","trace[592652272] 'compare' (duration: 56.654166ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:42:13.37774Z","caller":"traceutil/trace.go:171","msg":"trace[433305157] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"236.539086ms","start":"2026-04-21T17:42:13.141173Z","end":"2026-04-21T17:42:13.377712Z","steps":["trace[433305157] 'process raft request' (duration: 236.339512ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:42:14.12768Z","caller":"traceutil/trace.go:171","msg":"trace[89148223] linearizableReadLoop","detail":"{readStateIndex:1290; appliedIndex:1289; }","duration":"167.732551ms","start":"2026-04-21T17:42:13.959912Z","end":"2026-04-21T17:42:14.127644Z","steps":["trace[89148223] 'read index received' (duration: 94.545758ms)","trace[89148223] 'applied index is now lower than readState.Index' (duration: 73.185753ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:42:14.127738Z","caller":"traceutil/trace.go:171","msg":"trace[261139790] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"310.14653ms","start":"2026-04-21T17:42:13.81755Z","end":"2026-04-21T17:42:14.127696Z","steps":["trace[261139790] 'process raft request' (duration: 237.010348ms)","trace[261139790] 'compare' (duration: 72.927578ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T17:42:14.127932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:42:13.817531Z","time spent":"310.290213ms","remote":"127.0.0.1:40280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T17:42:14.127911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.953266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T17:42:14.128012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.930443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-21T17:42:14.128125Z","caller":"traceutil/trace.go:171","msg":"trace[2075738689] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1235; }","duration":"167.111797ms","start":"2026-04-21T17:42:13.960999Z","end":"2026-04-21T17:42:14.128111Z","steps":["trace[2075738689] 'agreement among raft nodes before linearized reading' (duration: 166.831831ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:42:14.128012Z","caller":"traceutil/trace.go:171","msg":"trace[808891267] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1235; }","duration":"168.13299ms","start":"2026-04-21T17:42:13.959857Z","end":"2026-04-21T17:42:14.12799Z","steps":["trace[808891267] 'agreement among raft nodes before linearized reading' (duration: 167.930845ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:42:14.12825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.522895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-21T17:42:14.1283Z","caller":"traceutil/trace.go:171","msg":"trace[1978334914] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1235; }","duration":"116.618627ms","start":"2026-04-21T17:42:14.011672Z","end":"2026-04-21T17:42:14.12829Z","steps":["trace[1978334914] 'agreement among raft nodes before linearized reading' (duration: 116.439683ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:42:14.128482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.092065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-21T17:42:14.12875Z","caller":"traceutil/trace.go:171","msg":"trace[521418989] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1235; }","duration":"116.410012ms","start":"2026-04-21T17:42:14.012326Z","end":"2026-04-21T17:42:14.128736Z","steps":["trace[521418989] 'agreement among raft nodes before linearized reading' (duration: 115.593554ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:42:24.467002Z","caller":"traceutil/trace.go:171","msg":"trace[733128739] linearizableReadLoop","detail":"{readStateIndex:1371; appliedIndex:1370; }","duration":"282.732242ms","start":"2026-04-21T17:42:24.18424Z","end":"2026-04-21T17:42:24.466972Z","steps":["trace[733128739] 'read index received' (duration: 282.481137ms)","trace[733128739] 'applied index is now lower than readState.Index' (duration: 250.025µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:42:24.467155Z","caller":"traceutil/trace.go:171","msg":"trace[400870285] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"285.570965ms","start":"2026-04-21T17:42:24.181571Z","end":"2026-04-21T17:42:24.467142Z","steps":["trace[400870285] 'process raft request' (duration: 285.216387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:42:24.467579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.811473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-21T17:42:24.467702Z","caller":"traceutil/trace.go:171","msg":"trace[435523835] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1314; }","duration":"282.980807ms","start":"2026-04-21T17:42:24.18469Z","end":"2026-04-21T17:42:24.467671Z","steps":["trace[435523835] 'agreement among raft nodes before linearized reading' (duration: 282.65116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:42:24.467885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.54338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-21T17:42:24.467913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.925566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-21T17:42:24.467977Z","caller":"traceutil/trace.go:171","msg":"trace[2097055622] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1314; }","duration":"283.064529ms","start":"2026-04-21T17:42:24.184899Z","end":"2026-04-21T17:42:24.467963Z","steps":["trace[2097055622] 'agreement among raft nodes before linearized reading' (duration: 282.809103ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T17:42:24.467987Z","caller":"traceutil/trace.go:171","msg":"trace[494188428] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1314; }","duration":"283.788755ms","start":"2026-04-21T17:42:24.184169Z","end":"2026-04-21T17:42:24.467958Z","steps":["trace[494188428] 'agreement among raft nodes before linearized reading' (duration: 283.381896ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:42:25.077953Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367165319849303,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T17:42:25.578738Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367165319849303,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T17:42:25.683503Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.211709439s","expected-duration":"1s"} {"level":"info","ts":"2026-04-21T17:42:25.691517Z","caller":"traceutil/trace.go:171","msg":"trace[1534772552] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"1.219846729s","start":"2026-04-21T17:42:24.471635Z","end":"2026-04-21T17:42:25.691482Z","steps":["trace[1534772552] 'process raft request' (duration: 1.212132378s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:42:25.691716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:42:24.471609Z","time spent":"1.220002133s","remote":"127.0.0.1:40280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T17:42:25.694992Z","caller":"traceutil/trace.go:171","msg":"trace[1264128824] linearizableReadLoop","detail":"{readStateIndex:1374; appliedIndex:1371; }","duration":"1.117879477s","start":"2026-04-21T17:42:24.577083Z","end":"2026-04-21T17:42:25.694962Z","steps":["trace[1264128824] 'read index received' (duration: 1.106648648s)","trace[1264128824] 'applied index is now lower than readState.Index' (duration: 11.229779ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T17:42:25.695298Z","caller":"traceutil/trace.go:171","msg":"trace[1912532927] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"1.222719762s","start":"2026-04-21T17:42:24.472563Z","end":"2026-04-21T17:42:25.695282Z","steps":["trace[1912532927] 'process raft request' (duration: 1.222191611s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:42:25.695428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:42:24.472554Z","time spent":"1.222785924s","remote":"127.0.0.1:40280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T17:42:25.695624Z","caller":"traceutil/trace.go:171","msg":"trace[1715018123] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"1.222999159s","start":"2026-04-21T17:42:24.472616Z","end":"2026-04-21T17:42:25.695615Z","steps":["trace[1715018123] 'process raft request' (duration: 1.222301593s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:42:25.695684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:42:24.472611Z","time spent":"1.22304456s","remote":"127.0.0.1:40280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T17:42:25.6959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.118822298s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-21T17:42:25.695946Z","caller":"traceutil/trace.go:171","msg":"trace[127864115] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1317; }","duration":"1.11890688s","start":"2026-04-21T17:42:24.577025Z","end":"2026-04-21T17:42:25.695932Z","steps":["trace[127864115] 'agreement among raft nodes before linearized reading' (duration: 1.118766987s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:42:25.695977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:42:24.576999Z","time spent":"1.118971071s","remote":"127.0.0.1:40280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-21T17:42:25.696202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.573071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-21T17:42:25.696242Z","caller":"traceutil/trace.go:171","msg":"trace[1479793094] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1317; }","duration":"673.662973ms","start":"2026-04-21T17:42:25.022568Z","end":"2026-04-21T17:42:25.696231Z","steps":["trace[1479793094] 'agreement among raft nodes before linearized reading' (duration: 673.573381ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:42:25.69627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:42:25.022538Z","time spent":"673.725325ms","remote":"127.0.0.1:40280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-21T17:42:25.696391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.779151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:471"} {"level":"warn","ts":"2026-04-21T17:42:25.696543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.31748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:42:25.696633Z","caller":"traceutil/trace.go:171","msg":"trace[1414707302] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1317; }","duration":"629.479413ms","start":"2026-04-21T17:42:25.06713Z","end":"2026-04-21T17:42:25.696609Z","steps":["trace[1414707302] 'agreement among raft nodes before linearized reading' (duration: 629.295498ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:42:25.69668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:42:25.067101Z","time spent":"629.569526ms","remote":"127.0.0.1:39960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-21T17:42:25.696704Z","caller":"traceutil/trace.go:171","msg":"trace[1456424863] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1317; }","duration":"529.862074ms","start":"2026-04-21T17:42:25.166553Z","end":"2026-04-21T17:42:25.696415Z","steps":["trace[1456424863] 'agreement among raft nodes before linearized reading' (duration: 529.783782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T17:42:25.696745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T17:42:25.166517Z","time spent":"530.218342ms","remote":"127.0.0.1:40280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":494,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-21T17:45:49.216872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.724044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T17:45:49.216974Z","caller":"traceutil/trace.go:171","msg":"trace[1333384388] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2282; }","duration":"149.877037ms","start":"2026-04-21T17:45:49.067077Z","end":"2026-04-21T17:45:49.216954Z","steps":["trace[1333384388] 'range keys from in-memory index tree' (duration: 149.567061ms)"],"step_count":1}