{"level":"warn","ts":"2026-04-23T14:44:47.116948Z","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-23T14:44:47.117083Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.70: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.70:2380","--initial-cluster=instance=https://199.204.45.70:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.70:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.70: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-23T14:44:47.117179Z","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-23T14:44:47.117198Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-04-23T14:44:47.117224Z","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-23T14:44:47.118099Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"]} {"level":"info","ts":"2026-04-23T14:44:47.118199Z","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":16,"max-cpu-available":16,"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.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.70: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-23T14:44:47.122094Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.581677ms"} {"level":"info","ts":"2026-04-23T14:44:47.131283Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f8844a9a4263041d","cluster-id":"4749b7c221ae0648"} {"level":"info","ts":"2026-04-23T14:44:47.131415Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=()"} {"level":"info","ts":"2026-04-23T14:44:47.131464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 0"} {"level":"info","ts":"2026-04-23T14:44:47.131494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f8844a9a4263041d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-23T14:44:47.131514Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 1"} {"level":"info","ts":"2026-04-23T14:44:47.13159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"warn","ts":"2026-04-23T14:44:47.137269Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-23T14:44:47.140997Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-23T14:44:47.142676Z","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-23T14:44:47.145063Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f8844a9a4263041d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-23T14:44:47.145826Z","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-23T14:44:47.146011Z","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-23T14:44:47.146043Z","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-23T14:44:47.145907Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f8844a9a4263041d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-23T14:44:47.146877Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"info","ts":"2026-04-23T14:44:47.14727Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","added-peer-id":"f8844a9a4263041d","added-peer-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-04-23T14:44:47.148053Z","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-23T14:44:47.148224Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-04-23T14:44:47.148305Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-04-23T14:44:47.148335Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f8844a9a4263041d","initial-advertise-peer-urls":["https://199.204.45.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-23T14:44:47.148396Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-23T14:44:48.032271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d is starting a new election at term 1"} {"level":"info","ts":"2026-04-23T14:44:48.032374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-23T14:44:48.032415Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgPreVoteResp from f8844a9a4263041d at term 1"} {"level":"info","ts":"2026-04-23T14:44:48.032429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became candidate at term 2"} {"level":"info","ts":"2026-04-23T14:44:48.032437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgVoteResp from f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-04-23T14:44:48.032448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became leader at term 2"} {"level":"info","ts":"2026-04-23T14:44:48.032462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f8844a9a4263041d elected leader f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-04-23T14:44:48.033574Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T14:44:48.034505Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f8844a9a4263041d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.70:2379]}","request-path":"/0/members/f8844a9a4263041d/attributes","cluster-id":"4749b7c221ae0648","publish-timeout":"7s"} {"level":"info","ts":"2026-04-23T14:44:48.034777Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-23T14:44:48.034843Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-23T14:44:48.035211Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T14:44:48.035434Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T14:44:48.035529Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T14:44:48.038322Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-23T14:44:48.038925Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.70:2379"} {"level":"info","ts":"2026-04-23T14:44:48.04383Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-23T14:44:48.043872Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-23T14:45:22.396556Z","caller":"traceutil/trace.go:171","msg":"trace[1038336785] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"197.016718ms","start":"2026-04-23T14:45:22.199512Z","end":"2026-04-23T14:45:22.396529Z","steps":["trace[1038336785] 'process raft request' (duration: 196.829003ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:45:22.52373Z","caller":"traceutil/trace.go:171","msg":"trace[539899062] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:636; }","duration":"128.843058ms","start":"2026-04-23T14:45:22.394822Z","end":"2026-04-23T14:45:22.523665Z","steps":["trace[539899062] 'read index received' (duration: 1.605913ms)","trace[539899062] 'applied index is now lower than readState.Index' (duration: 127.236095ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T14:45:22.52377Z","caller":"traceutil/trace.go:171","msg":"trace[1475525213] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"217.339701ms","start":"2026-04-23T14:45:22.306376Z","end":"2026-04-23T14:45:22.523716Z","steps":["trace[1475525213] 'process raft request' (duration: 214.147283ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:45:22.523945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.093135ms","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-23T14:45:22.524226Z","caller":"traceutil/trace.go:171","msg":"trace[1613559836] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:614; }","duration":"129.439904ms","start":"2026-04-23T14:45:22.394767Z","end":"2026-04-23T14:45:22.524207Z","steps":["trace[1613559836] 'agreement among raft nodes before linearized reading' (duration: 129.014642ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:45:22.88717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.926615ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T14:45:22.887681Z","caller":"traceutil/trace.go:171","msg":"trace[1684690334] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"287.519145ms","start":"2026-04-23T14:45:22.600141Z","end":"2026-04-23T14:45:22.88766Z","steps":["trace[1684690334] 'process raft request' (duration: 287.355401ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:45:22.887753Z","caller":"traceutil/trace.go:171","msg":"trace[278485627] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"357.850895ms","start":"2026-04-23T14:45:22.529861Z","end":"2026-04-23T14:45:22.887711Z","steps":["trace[278485627] 'process raft request' (duration: 22.605004ms)","trace[278485627] 'compare' (duration: 333.753511ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T14:45:22.888162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:45:22.52983Z","time spent":"358.136033ms","remote":"127.0.0.1:40772","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-23T14:45:53.94295Z","caller":"traceutil/trace.go:171","msg":"trace[1822799050] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"115.918096ms","start":"2026-04-23T14:45:53.826999Z","end":"2026-04-23T14:45:53.942917Z","steps":["trace[1822799050] 'process raft request' (duration: 73.869434ms)","trace[1822799050] 'compare' (duration: 41.927702ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T14:46:02.202014Z","caller":"traceutil/trace.go:171","msg":"trace[1456313153] linearizableReadLoop","detail":"{readStateIndex:995; appliedIndex:994; }","duration":"203.576873ms","start":"2026-04-23T14:46:01.998409Z","end":"2026-04-23T14:46:02.201986Z","steps":["trace[1456313153] 'read index received' (duration: 203.41047ms)","trace[1456313153] 'applied index is now lower than readState.Index' (duration: 165.323µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T14:46:02.202134Z","caller":"traceutil/trace.go:171","msg":"trace[294838024] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"214.966438ms","start":"2026-04-23T14:46:01.987125Z","end":"2026-04-23T14:46:02.202091Z","steps":["trace[294838024] 'process raft request' (duration: 214.705172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:46:02.20221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.768947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T14:46:02.202252Z","caller":"traceutil/trace.go:171","msg":"trace[1459178507] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:961; }","duration":"203.88692ms","start":"2026-04-23T14:46:01.998353Z","end":"2026-04-23T14:46:02.20224Z","steps":["trace[1459178507] 'agreement among raft nodes before linearized reading' (duration: 203.795768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:46:02.202416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.066437ms","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-23T14:46:02.202532Z","caller":"traceutil/trace.go:171","msg":"trace[1648651096] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:961; }","duration":"149.216159ms","start":"2026-04-23T14:46:02.053273Z","end":"2026-04-23T14:46:02.20249Z","steps":["trace[1648651096] 'agreement among raft nodes before linearized reading' (duration: 148.947674ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:46:14.071049Z","caller":"traceutil/trace.go:171","msg":"trace[809022218] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"157.440944ms","start":"2026-04-23T14:46:13.913571Z","end":"2026-04-23T14:46:14.071012Z","steps":["trace[809022218] 'process raft request' (duration: 157.123317ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:46:15.283067Z","caller":"traceutil/trace.go:171","msg":"trace[2107045885] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"129.126912ms","start":"2026-04-23T14:46:15.153914Z","end":"2026-04-23T14:46:15.283041Z","steps":["trace[2107045885] 'process raft request' (duration: 128.965058ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:46:16.709981Z","caller":"traceutil/trace.go:171","msg":"trace[1412317917] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"114.132627ms","start":"2026-04-23T14:46:16.595822Z","end":"2026-04-23T14:46:16.709955Z","steps":["trace[1412317917] 'process raft request' (duration: 47.183883ms)","trace[1412317917] 'compare' (duration: 66.819051ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T14:46:18.296875Z","caller":"traceutil/trace.go:171","msg":"trace[602519316] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"197.085598ms","start":"2026-04-23T14:46:18.09976Z","end":"2026-04-23T14:46:18.296846Z","steps":["trace[602519316] 'process raft request' (duration: 196.871103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:46:18.817458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.720214ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T14:46:18.817597Z","caller":"traceutil/trace.go:171","msg":"trace[1300304833] linearizableReadLoop","detail":"{readStateIndex:1055; appliedIndex:1054; }","duration":"494.537056ms","start":"2026-04-23T14:46:18.323039Z","end":"2026-04-23T14:46:18.817576Z","steps":["trace[1300304833] 'read index received' (duration: 235.501295ms)","trace[1300304833] 'applied index is now lower than readState.Index' (duration: 259.033981ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T14:46:18.817696Z","caller":"traceutil/trace.go:171","msg":"trace[1041346021] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"496.699194ms","start":"2026-04-23T14:46:18.32097Z","end":"2026-04-23T14:46:18.817669Z","steps":["trace[1041346021] 'process raft request' (duration: 237.661833ms)","trace[1041346021] 'compare' (duration: 258.248204ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T14:46:18.817804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:46:18.320941Z","time spent":"496.816217ms","remote":"127.0.0.1:40772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T14:46:18.818284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.214202ms","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-23T14:46:18.818385Z","caller":"traceutil/trace.go:171","msg":"trace[1385826355] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1017; }","duration":"495.356564ms","start":"2026-04-23T14:46:18.323004Z","end":"2026-04-23T14:46:18.81836Z","steps":["trace[1385826355] 'agreement among raft nodes before linearized reading' (duration: 495.074978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:46:18.818436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:46:18.322993Z","time spent":"495.435286ms","remote":"127.0.0.1:40772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-23T14:46:19.320139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.528839ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T14:46:19.320432Z","caller":"traceutil/trace.go:171","msg":"trace[1384938574] linearizableReadLoop","detail":"{readStateIndex:1056; appliedIndex:1055; }","duration":"502.78466ms","start":"2026-04-23T14:46:18.817625Z","end":"2026-04-23T14:46:19.32041Z","steps":["trace[1384938574] 'read index received' (duration: 329.835531ms)","trace[1384938574] 'applied index is now lower than readState.Index' (duration: 172.947679ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T14:46:19.320579Z","caller":"traceutil/trace.go:171","msg":"trace[1057051168] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"723.013694ms","start":"2026-04-23T14:46:18.597547Z","end":"2026-04-23T14:46:19.32056Z","steps":["trace[1057051168] 'process raft request' (duration: 549.947583ms)","trace[1057051168] 'compare' (duration: 172.219333ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T14:46:19.320661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:46:18.597509Z","time spent":"723.110417ms","remote":"127.0.0.1:40772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T14:46:19.320842Z","caller":"traceutil/trace.go:171","msg":"trace[1393443405] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"498.049514ms","start":"2026-04-23T14:46:18.822782Z","end":"2026-04-23T14:46:19.320832Z","steps":["trace[1393443405] 'process raft request' (duration: 497.533562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:46:19.320907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:46:18.822753Z","time spent":"498.121716ms","remote":"127.0.0.1:40772","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-23T14:46:19.320925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.625638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T14:46:19.320914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.092182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T14:46:19.321039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.409224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-23T14:46:19.320985Z","caller":"traceutil/trace.go:171","msg":"trace[167449912] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1019; }","duration":"204.74427ms","start":"2026-04-23T14:46:19.116225Z","end":"2026-04-23T14:46:19.320969Z","steps":["trace[167449912] 'agreement among raft nodes before linearized reading' (duration: 204.622318ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:46:19.321146Z","caller":"traceutil/trace.go:171","msg":"trace[186834496] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1019; }","duration":"606.595698ms","start":"2026-04-23T14:46:18.714526Z","end":"2026-04-23T14:46:19.321122Z","steps":["trace[186834496] 'agreement among raft nodes before linearized reading' (duration: 606.144027ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:46:19.32107Z","caller":"traceutil/trace.go:171","msg":"trace[660651732] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1019; }","duration":"315.312377ms","start":"2026-04-23T14:46:19.005717Z","end":"2026-04-23T14:46:19.321029Z","steps":["trace[660651732] 'agreement among raft nodes before linearized reading' (duration: 315.080531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:46:19.321211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:46:18.714496Z","time spent":"606.703119ms","remote":"127.0.0.1:40772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-23T14:46:19.32122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:46:19.005687Z","time spent":"315.520971ms","remote":"127.0.0.1:37756","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-23T14:46:19.51004Z","caller":"traceutil/trace.go:171","msg":"trace[1408251664] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"110.815543ms","start":"2026-04-23T14:46:19.399195Z","end":"2026-04-23T14:46:19.510011Z","steps":["trace[1408251664] 'process raft request' (duration: 21.209914ms)","trace[1408251664] 'compare' (duration: 89.475547ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T14:46:21.856372Z","caller":"traceutil/trace.go:171","msg":"trace[1206351053] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"106.837146ms","start":"2026-04-23T14:46:21.749511Z","end":"2026-04-23T14:46:21.856348Z","steps":["trace[1206351053] 'process raft request' (duration: 106.642131ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:46:23.006745Z","caller":"traceutil/trace.go:171","msg":"trace[1004536468] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"128.372745ms","start":"2026-04-23T14:46:22.878354Z","end":"2026-04-23T14:46:23.006727Z","steps":["trace[1004536468] 'process raft request' (duration: 128.225101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:46:26.149436Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296566576104317886,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-23T14:46:26.218988Z","caller":"traceutil/trace.go:171","msg":"trace[451202923] linearizableReadLoop","detail":"{readStateIndex:1080; appliedIndex:1079; }","duration":"570.550782ms","start":"2026-04-23T14:46:25.648412Z","end":"2026-04-23T14:46:26.218963Z","steps":["trace[451202923] 'read index received' (duration: 570.340618ms)","trace[451202923] 'applied index is now lower than readState.Index' (duration: 209.224µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T14:46:26.219047Z","caller":"traceutil/trace.go:171","msg":"trace[798229378] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"587.771516ms","start":"2026-04-23T14:46:25.631239Z","end":"2026-04-23T14:46:26.21901Z","steps":["trace[798229378] 'process raft request' (duration: 587.582372ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:46:26.219216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:46:25.631204Z","time spent":"587.9231ms","remote":"127.0.0.1:40686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10755,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T14:46:26.219255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.599211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-23T14:46:26.219296Z","caller":"traceutil/trace.go:171","msg":"trace[641080946] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1041; }","duration":"556.691724ms","start":"2026-04-23T14:46:25.662595Z","end":"2026-04-23T14:46:26.219287Z","steps":["trace[641080946] 'agreement among raft nodes before linearized reading' (duration: 556.5311ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:46:26.219309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.87808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-23T14:46:26.219342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:46:25.662578Z","time spent":"556.754815ms","remote":"127.0.0.1:40772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-23T14:46:26.219402Z","caller":"traceutil/trace.go:171","msg":"trace[604382490] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1041; }","duration":"571.042464ms","start":"2026-04-23T14:46:25.648337Z","end":"2026-04-23T14:46:26.21938Z","steps":["trace[604382490] 'agreement among raft nodes before linearized reading' (duration: 570.750287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:46:26.219445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:46:25.648311Z","time spent":"571.128256ms","remote":"127.0.0.1:40772","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-23T14:46:27.594325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.011828ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T14:46:27.594449Z","caller":"traceutil/trace.go:171","msg":"trace[2091745782] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"151.844488ms","start":"2026-04-23T14:46:27.44258Z","end":"2026-04-23T14:46:27.594425Z","steps":["trace[2091745782] 'process raft request' (duration: 40.638766ms)","trace[2091745782] 'compare' (duration: 110.856265ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T14:46:30.665843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.436021ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T14:46:30.666018Z","caller":"traceutil/trace.go:171","msg":"trace[2055773698] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"187.248188ms","start":"2026-04-23T14:46:30.478739Z","end":"2026-04-23T14:46:30.665988Z","steps":["trace[2055773698] 'process raft request' (duration: 69.49929ms)","trace[2055773698] 'compare' (duration: 117.232296ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T14:46:32.570208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.033332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-23T14:46:32.57031Z","caller":"traceutil/trace.go:171","msg":"trace[35774586] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1062; }","duration":"143.161825ms","start":"2026-04-23T14:46:32.427127Z","end":"2026-04-23T14:46:32.570289Z","steps":["trace[35774586] 'range keys from in-memory index tree' (duration: 142.809328ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:46:43.798134Z","caller":"traceutil/trace.go:171","msg":"trace[735195825] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"230.848328ms","start":"2026-04-23T14:46:43.567251Z","end":"2026-04-23T14:46:43.7981Z","steps":["trace[735195825] 'process raft request' (duration: 230.46237ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:46:44.827739Z","caller":"traceutil/trace.go:171","msg":"trace[992573562] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"102.016079ms","start":"2026-04-23T14:46:44.725695Z","end":"2026-04-23T14:46:44.827711Z","steps":["trace[992573562] 'process raft request' (duration: 101.859286ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:46:51.134388Z","caller":"traceutil/trace.go:171","msg":"trace[795228417] linearizableReadLoop","detail":"{readStateIndex:1165; appliedIndex:1164; }","duration":"132.606881ms","start":"2026-04-23T14:46:51.00175Z","end":"2026-04-23T14:46:51.134357Z","steps":["trace[795228417] 'read index received' (duration: 132.406777ms)","trace[795228417] 'applied index is now lower than readState.Index' (duration: 198.664µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T14:46:51.134421Z","caller":"traceutil/trace.go:171","msg":"trace[1971610571] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"261.425382ms","start":"2026-04-23T14:46:50.87297Z","end":"2026-04-23T14:46:51.134395Z","steps":["trace[1971610571] 'process raft request' (duration: 261.187797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:46:51.13465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.872546ms","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-23T14:46:51.13469Z","caller":"traceutil/trace.go:171","msg":"trace[1130590297] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1121; }","duration":"133.02655ms","start":"2026-04-23T14:46:51.001656Z","end":"2026-04-23T14:46:51.134683Z","steps":["trace[1130590297] 'agreement among raft nodes before linearized reading' (duration: 132.802255ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:46:53.315805Z","caller":"traceutil/trace.go:171","msg":"trace[300240074] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"131.688962ms","start":"2026-04-23T14:46:53.184076Z","end":"2026-04-23T14:46:53.315765Z","steps":["trace[300240074] 'process raft request' (duration: 104.982041ms)","trace[300240074] 'compare' (duration: 26.503386ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T14:46:56.01649Z","caller":"traceutil/trace.go:171","msg":"trace[1221922889] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"125.129092ms","start":"2026-04-23T14:46:55.891321Z","end":"2026-04-23T14:46:56.01645Z","steps":["trace[1221922889] 'process raft request' (duration: 124.925557ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:47:03.953694Z","caller":"traceutil/trace.go:171","msg":"trace[442254555] linearizableReadLoop","detail":"{readStateIndex:1211; appliedIndex:1210; }","duration":"238.549043ms","start":"2026-04-23T14:47:03.715125Z","end":"2026-04-23T14:47:03.953674Z","steps":["trace[442254555] 'read index received' (duration: 238.43107ms)","trace[442254555] 'applied index is now lower than readState.Index' (duration: 117.233µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T14:47:03.953832Z","caller":"traceutil/trace.go:171","msg":"trace[488540311] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"464.803204ms","start":"2026-04-23T14:47:03.488991Z","end":"2026-04-23T14:47:03.953794Z","steps":["trace[488540311] 'process raft request' (duration: 464.551109ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:47:03.953884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.748547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-23T14:47:03.953909Z","caller":"traceutil/trace.go:171","msg":"trace[193034548] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1164; }","duration":"238.829289ms","start":"2026-04-23T14:47:03.71507Z","end":"2026-04-23T14:47:03.953899Z","steps":["trace[193034548] 'agreement among raft nodes before linearized reading' (duration: 238.689386ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:47:03.953976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:47:03.48897Z","time spent":"464.931197ms","remote":"127.0.0.1:40772","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-23T14:47:07.301468Z","caller":"traceutil/trace.go:171","msg":"trace[936603803] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"527.981011ms","start":"2026-04-23T14:47:06.773469Z","end":"2026-04-23T14:47:07.30145Z","steps":["trace[936603803] 'process raft request' (duration: 527.749767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:47:07.301554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.142283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-23T14:47:07.301593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:47:06.773428Z","time spent":"528.085734ms","remote":"127.0.0.1:40772","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-23T14:47:07.301609Z","caller":"traceutil/trace.go:171","msg":"trace[1604228611] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1175; }","duration":"400.238335ms","start":"2026-04-23T14:47:06.90135Z","end":"2026-04-23T14:47:07.301588Z","steps":["trace[1604228611] 'agreement among raft nodes before linearized reading' (duration: 400.144793ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:47:07.301425Z","caller":"traceutil/trace.go:171","msg":"trace[117187824] linearizableReadLoop","detail":"{readStateIndex:1223; appliedIndex:1222; }","duration":"400.01582ms","start":"2026-04-23T14:47:06.901386Z","end":"2026-04-23T14:47:07.301402Z","steps":["trace[117187824] 'read index received' (duration: 399.773765ms)","trace[117187824] 'applied index is now lower than readState.Index' (duration: 240.955µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T14:47:07.301654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:47:06.901337Z","time spent":"400.307727ms","remote":"127.0.0.1:40948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-04-23T14:47:07.30268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.02251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T14:47:07.302792Z","caller":"traceutil/trace.go:171","msg":"trace[2081126985] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1175; }","duration":"304.184495ms","start":"2026-04-23T14:47:06.998582Z","end":"2026-04-23T14:47:07.302767Z","steps":["trace[2081126985] 'agreement among raft nodes before linearized reading' (duration: 304.00703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:47:07.302837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:47:06.998559Z","time spent":"304.268916ms","remote":"127.0.0.1:37764","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-23T14:47:12.45659Z","caller":"traceutil/trace.go:171","msg":"trace[17625720] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"383.001706ms","start":"2026-04-23T14:47:12.073564Z","end":"2026-04-23T14:47:12.456566Z","steps":["trace[17625720] 'process raft request' (duration: 348.50571ms)","trace[17625720] 'compare' (duration: 34.234401ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T14:47:12.456701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:47:12.073537Z","time spent":"383.107789ms","remote":"127.0.0.1:40772","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-23T14:47:12.456763Z","caller":"traceutil/trace.go:171","msg":"trace[2072003275] linearizableReadLoop","detail":"{readStateIndex:1241; appliedIndex:1239; }","duration":"354.092939ms","start":"2026-04-23T14:47:12.102629Z","end":"2026-04-23T14:47:12.456722Z","steps":["trace[2072003275] 'read index received' (duration: 319.572723ms)","trace[2072003275] 'applied index is now lower than readState.Index' (duration: 34.517936ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T14:47:12.456813Z","caller":"traceutil/trace.go:171","msg":"trace[331625687] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"371.688025ms","start":"2026-04-23T14:47:12.085097Z","end":"2026-04-23T14:47:12.456785Z","steps":["trace[331625687] 'process raft request' (duration: 371.436749ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:47:12.456957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:47:12.085078Z","time spent":"371.792748ms","remote":"127.0.0.1:40772","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-23T14:47:12.457023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.376615ms","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-23T14:47:12.457066Z","caller":"traceutil/trace.go:171","msg":"trace[1953122499] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1192; }","duration":"354.454407ms","start":"2026-04-23T14:47:12.102596Z","end":"2026-04-23T14:47:12.45705Z","steps":["trace[1953122499] 'agreement among raft nodes before linearized reading' (duration: 354.241253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:47:12.457102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:47:12.102583Z","time spent":"354.511408ms","remote":"127.0.0.1:40772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-23T14:47:14.565806Z","caller":"traceutil/trace.go:171","msg":"trace[499828273] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"104.00241ms","start":"2026-04-23T14:47:14.461768Z","end":"2026-04-23T14:47:14.56577Z","steps":["trace[499828273] 'process raft request' (duration: 103.760315ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:47:14.58396Z","caller":"traceutil/trace.go:171","msg":"trace[537123506] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"120.061443ms","start":"2026-04-23T14:47:14.463857Z","end":"2026-04-23T14:47:14.583919Z","steps":["trace[537123506] 'process raft request' (duration: 119.770237ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:47:20.429066Z","caller":"traceutil/trace.go:171","msg":"trace[525469375] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"177.369867ms","start":"2026-04-23T14:47:20.251649Z","end":"2026-04-23T14:47:20.429019Z","steps":["trace[525469375] 'process raft request' (duration: 177.091881ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:47:23.14104Z","caller":"traceutil/trace.go:171","msg":"trace[1347150813] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"162.354756ms","start":"2026-04-23T14:47:22.978651Z","end":"2026-04-23T14:47:23.141005Z","steps":["trace[1347150813] 'process raft request' (duration: 162.133062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:47:26.443314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.806928ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-23T14:47:37.069565Z","caller":"traceutil/trace.go:171","msg":"trace[715826608] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"158.232608ms","start":"2026-04-23T14:47:36.911247Z","end":"2026-04-23T14:47:37.06948Z","steps":["trace[715826608] 'process raft request' (duration: 71.19906ms)","trace[715826608] 'compare' (duration: 86.784313ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T14:47:37.069677Z","caller":"traceutil/trace.go:171","msg":"trace[1933969991] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"156.645164ms","start":"2026-04-23T14:47:36.912995Z","end":"2026-04-23T14:47:37.06964Z","steps":["trace[1933969991] 'process raft request' (duration: 156.398979ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:47:38.059332Z","caller":"traceutil/trace.go:171","msg":"trace[93725463] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"128.82052ms","start":"2026-04-23T14:47:37.930473Z","end":"2026-04-23T14:47:38.059294Z","steps":["trace[93725463] 'process raft request' (duration: 34.23091ms)","trace[93725463] 'compare' (duration: 94.362886ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T14:47:39.029382Z","caller":"traceutil/trace.go:171","msg":"trace[157769070] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"115.569217ms","start":"2026-04-23T14:47:38.913793Z","end":"2026-04-23T14:47:39.029363Z","steps":["trace[157769070] 'process raft request' (duration: 115.284011ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:47:40.865461Z","caller":"traceutil/trace.go:171","msg":"trace[453112952] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"218.051751ms","start":"2026-04-23T14:47:40.647383Z","end":"2026-04-23T14:47:40.865435Z","steps":["trace[453112952] 'process raft request' (duration: 217.678173ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:47:41.110094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.377713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T14:47:41.110169Z","caller":"traceutil/trace.go:171","msg":"trace[193985300] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1283; }","duration":"108.502515ms","start":"2026-04-23T14:47:41.00165Z","end":"2026-04-23T14:47:41.110153Z","steps":["trace[193985300] 'range keys from in-memory index tree' (duration: 108.283301ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:47:41.50635Z","caller":"traceutil/trace.go:171","msg":"trace[686125873] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"261.963773ms","start":"2026-04-23T14:47:41.244355Z","end":"2026-04-23T14:47:41.506318Z","steps":["trace[686125873] 'process raft request' (duration: 261.771079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:47:42.213778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.395782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-23T14:47:42.214059Z","caller":"traceutil/trace.go:171","msg":"trace[1589976098] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1287; }","duration":"263.731798ms","start":"2026-04-23T14:47:41.950306Z","end":"2026-04-23T14:47:42.214038Z","steps":["trace[1589976098] 'range keys from in-memory index tree' (duration: 263.193518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:47:42.214013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.483264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-23T14:47:42.214151Z","caller":"traceutil/trace.go:171","msg":"trace[140402428] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1287; }","duration":"117.647377ms","start":"2026-04-23T14:47:42.096489Z","end":"2026-04-23T14:47:42.214136Z","steps":["trace[140402428] 'range keys from in-memory index tree' (duration: 117.200648ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:47:42.332326Z","caller":"traceutil/trace.go:171","msg":"trace[121212407] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"114.642028ms","start":"2026-04-23T14:47:42.217624Z","end":"2026-04-23T14:47:42.332266Z","steps":["trace[121212407] 'process raft request' (duration: 114.398073ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:47:42.480102Z","caller":"traceutil/trace.go:171","msg":"trace[1182755734] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"261.012945ms","start":"2026-04-23T14:47:42.219062Z","end":"2026-04-23T14:47:42.480075Z","steps":["trace[1182755734] 'process raft request' (duration: 237.090389ms)","trace[1182755734] 'compare' (duration: 23.820724ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T14:47:43.30338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.993199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T14:47:43.303465Z","caller":"traceutil/trace.go:171","msg":"trace[1720078611] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1290; }","duration":"302.130862ms","start":"2026-04-23T14:47:43.001316Z","end":"2026-04-23T14:47:43.303447Z","steps":["trace[1720078611] 'range keys from in-memory index tree' (duration: 301.895467ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:47:43.303513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:47:43.001293Z","time spent":"302.213163ms","remote":"127.0.0.1:37756","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-23T14:47:43.303642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.034487ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T14:47:43.303766Z","caller":"traceutil/trace.go:171","msg":"trace[1389578594] linearizableReadLoop","detail":"{readStateIndex:1346; appliedIndex:1345; }","duration":"164.453067ms","start":"2026-04-23T14:47:43.139291Z","end":"2026-04-23T14:47:43.303744Z","steps":["trace[1389578594] 'read index received' (duration: 30.73µs)","trace[1389578594] 'applied index is now lower than readState.Index' (duration: 164.420297ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T14:47:43.303895Z","caller":"traceutil/trace.go:171","msg":"trace[1467788198] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"189.457104ms","start":"2026-04-23T14:47:43.1144Z","end":"2026-04-23T14:47:43.303857Z","steps":["trace[1467788198] 'process raft request' (duration: 16.08056ms)","trace[1467788198] 'compare' (duration: 172.495026ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T14:47:43.304082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.788344ms","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-23T14:47:43.304129Z","caller":"traceutil/trace.go:171","msg":"trace[487663566] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1291; }","duration":"164.870055ms","start":"2026-04-23T14:47:43.139247Z","end":"2026-04-23T14:47:43.304117Z","steps":["trace[487663566] 'agreement among raft nodes before linearized reading' (duration: 164.60858ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:47:43.304202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.00199ms","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-23T14:47:43.304334Z","caller":"traceutil/trace.go:171","msg":"trace[2023061617] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1291; }","duration":"143.171454ms","start":"2026-04-23T14:47:43.161139Z","end":"2026-04-23T14:47:43.304311Z","steps":["trace[2023061617] 'agreement among raft nodes before linearized reading' (duration: 142.874528ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:47:43.483449Z","caller":"traceutil/trace.go:171","msg":"trace[795933203] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"174.431745ms","start":"2026-04-23T14:47:43.308985Z","end":"2026-04-23T14:47:43.483417Z","steps":["trace[795933203] 'process raft request' (duration: 174.263561ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:47:45.640424Z","caller":"traceutil/trace.go:171","msg":"trace[1877232215] linearizableReadLoop","detail":"{readStateIndex:1353; appliedIndex:1352; }","duration":"277.855299ms","start":"2026-04-23T14:47:45.362536Z","end":"2026-04-23T14:47:45.640391Z","steps":["trace[1877232215] 'read index received' (duration: 277.590444ms)","trace[1877232215] 'applied index is now lower than readState.Index' (duration: 263.385µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T14:47:45.640474Z","caller":"traceutil/trace.go:171","msg":"trace[1712147839] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"330.365333ms","start":"2026-04-23T14:47:45.310074Z","end":"2026-04-23T14:47:45.640439Z","steps":["trace[1712147839] 'process raft request' (duration: 330.073197ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:47:45.640639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:47:45.310043Z","time spent":"330.510535ms","remote":"127.0.0.1:40772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T14:47:45.640734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.327515ms","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-23T14:47:45.640782Z","caller":"traceutil/trace.go:171","msg":"trace[2134526991] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1298; }","duration":"152.503689ms","start":"2026-04-23T14:47:45.488267Z","end":"2026-04-23T14:47:45.64077Z","steps":["trace[2134526991] 'agreement among raft nodes before linearized reading' (duration: 152.300335ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:47:45.641024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.479261ms","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-23T14:47:45.641142Z","caller":"traceutil/trace.go:171","msg":"trace[452980069] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1298; }","duration":"278.636844ms","start":"2026-04-23T14:47:45.362491Z","end":"2026-04-23T14:47:45.641128Z","steps":["trace[452980069] 'agreement among raft nodes before linearized reading' (duration: 278.136354ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:47:46.104427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.755834ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T14:47:46.104995Z","caller":"traceutil/trace.go:171","msg":"trace[673403093] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"459.442336ms","start":"2026-04-23T14:47:45.645529Z","end":"2026-04-23T14:47:46.104972Z","steps":["trace[673403093] 'process raft request' (duration: 162.980897ms)","trace[673403093] 'compare' (duration: 295.574251ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T14:47:46.105105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:47:45.6455Z","time spent":"459.555908ms","remote":"127.0.0.1:40772","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-23T14:47:46.105267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.703224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T14:47:46.105406Z","caller":"traceutil/trace.go:171","msg":"trace[1481277662] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1300; }","duration":"434.875828ms","start":"2026-04-23T14:47:45.670479Z","end":"2026-04-23T14:47:46.105355Z","steps":["trace[1481277662] 'agreement among raft nodes before linearized reading' (duration: 434.559702ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:47:46.104923Z","caller":"traceutil/trace.go:171","msg":"trace[1048041426] linearizableReadLoop","detail":"{readStateIndex:1355; appliedIndex:1353; }","duration":"434.405169ms","start":"2026-04-23T14:47:45.670493Z","end":"2026-04-23T14:47:46.104898Z","steps":["trace[1048041426] 'read index received' (duration: 3.106422ms)","trace[1048041426] 'applied index is now lower than readState.Index' (duration: 431.296547ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T14:47:46.105489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:47:45.670416Z","time spent":"435.039271ms","remote":"127.0.0.1:40906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-04-23T14:47:46.768888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.572737ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-23T14:47:46.769146Z","caller":"traceutil/trace.go:171","msg":"trace[1505960588] linearizableReadLoop","detail":"{readStateIndex:1357; appliedIndex:1355; }","duration":"417.576375ms","start":"2026-04-23T14:47:46.35155Z","end":"2026-04-23T14:47:46.769126Z","steps":["trace[1505960588] 'read index received' (duration: 82.989438ms)","trace[1505960588] 'applied index is now lower than readState.Index' (duration: 334.585807ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T14:47:46.769247Z","caller":"traceutil/trace.go:171","msg":"trace[2015177752] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"424.256708ms","start":"2026-04-23T14:47:46.344969Z","end":"2026-04-23T14:47:46.769226Z","steps":["trace[2015177752] 'process raft request' (duration: 424.031023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:47:46.76937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.823569ms","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-23T14:47:46.769369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:47:46.344918Z","time spent":"424.37975ms","remote":"127.0.0.1:40772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T14:47:46.769401Z","caller":"traceutil/trace.go:171","msg":"trace[68694083] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1301; }","duration":"417.902911ms","start":"2026-04-23T14:47:46.351489Z","end":"2026-04-23T14:47:46.769392Z","steps":["trace[68694083] 'agreement among raft nodes before linearized reading' (duration: 417.756178ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:47:46.769433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:47:46.351471Z","time spent":"417.954982ms","remote":"127.0.0.1:40772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-23T14:47:46.769777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.751074ms","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-23T14:47:46.769825Z","caller":"traceutil/trace.go:171","msg":"trace[105719271] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1301; }","duration":"153.842426ms","start":"2026-04-23T14:47:46.61597Z","end":"2026-04-23T14:47:46.769812Z","steps":["trace[105719271] 'agreement among raft nodes before linearized reading' (duration: 153.612322ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:47:47.084249Z","caller":"traceutil/trace.go:171","msg":"trace[1548231620] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"311.088379ms","start":"2026-04-23T14:47:46.773128Z","end":"2026-04-23T14:47:47.084216Z","steps":["trace[1548231620] 'process raft request' (duration: 262.76532ms)","trace[1548231620] 'compare' (duration: 48.157386ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T14:47:47.084471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:47:46.773107Z","time spent":"311.280283ms","remote":"127.0.0.1:40772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T14:47:47.380788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.1971ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T14:47:47.380916Z","caller":"traceutil/trace.go:171","msg":"trace[1685883237] linearizableReadLoop","detail":"{readStateIndex:1359; appliedIndex:1357; }","duration":"407.773599ms","start":"2026-04-23T14:47:46.973122Z","end":"2026-04-23T14:47:47.380896Z","steps":["trace[1685883237] 'read index received' (duration: 62.822518ms)","trace[1685883237] 'applied index is now lower than readState.Index' (duration: 344.949711ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T14:47:47.380979Z","caller":"traceutil/trace.go:171","msg":"trace[280389067] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"605.599139ms","start":"2026-04-23T14:47:46.775359Z","end":"2026-04-23T14:47:47.380958Z","steps":["trace[280389067] 'process raft request' (duration: 435.148954ms)","trace[280389067] 'compare' (duration: 169.950276ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T14:47:47.381077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:47:46.775314Z","time spent":"605.719442ms","remote":"127.0.0.1:40772","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-23T14:47:47.381142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.414647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T14:47:47.381093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.985424ms","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-23T14:47:47.38119Z","caller":"traceutil/trace.go:171","msg":"trace[638812776] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1303; }","duration":"380.496768ms","start":"2026-04-23T14:47:47.000681Z","end":"2026-04-23T14:47:47.381177Z","steps":["trace[638812776] 'agreement among raft nodes before linearized reading' (duration: 380.437686ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:47:47.381286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:47:47.000654Z","time spent":"380.622391ms","remote":"127.0.0.1:37756","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-23T14:47:47.381203Z","caller":"traceutil/trace.go:171","msg":"trace[1846321426] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1303; }","duration":"408.163177ms","start":"2026-04-23T14:47:46.973031Z","end":"2026-04-23T14:47:47.381194Z","steps":["trace[1846321426] 'agreement among raft nodes before linearized reading' (duration: 407.955843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:47:47.381388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:47:46.973008Z","time spent":"408.369381ms","remote":"127.0.0.1:40772","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-23T14:47:47.719249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.082949ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T14:47:47.719363Z","caller":"traceutil/trace.go:171","msg":"trace[1221064530] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"334.255879ms","start":"2026-04-23T14:47:47.385085Z","end":"2026-04-23T14:47:47.719341Z","steps":["trace[1221064530] 'process raft request' (duration: 234.004668ms)","trace[1221064530] 'compare' (duration: 99.962285ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T14:47:47.719428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:47:47.385061Z","time spent":"334.33461ms","remote":"127.0.0.1:40772","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-23T14:47:47.977107Z","caller":"traceutil/trace.go:171","msg":"trace[536697652] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"249.345413ms","start":"2026-04-23T14:47:47.727725Z","end":"2026-04-23T14:47:47.977071Z","steps":["trace[536697652] 'process raft request' (duration: 157.354755ms)","trace[536697652] 'compare' (duration: 91.778413ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T14:47:48.400037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.967961ms","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-23T14:47:48.400111Z","caller":"traceutil/trace.go:171","msg":"trace[445032565] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1306; }","duration":"290.094833ms","start":"2026-04-23T14:47:48.109999Z","end":"2026-04-23T14:47:48.400094Z","steps":["trace[445032565] 'range keys from in-memory index tree' (duration: 289.772287ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:47:48.556777Z","caller":"traceutil/trace.go:171","msg":"trace[1788625733] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"152.692374ms","start":"2026-04-23T14:47:48.40405Z","end":"2026-04-23T14:47:48.556743Z","steps":["trace[1788625733] 'process raft request' (duration: 152.457639ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:47:49.276806Z","caller":"traceutil/trace.go:171","msg":"trace[751191679] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"184.628368ms","start":"2026-04-23T14:47:49.092144Z","end":"2026-04-23T14:47:49.276773Z","steps":["trace[751191679] 'process raft request' (duration: 184.415583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:47:49.722179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.847844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"warn","ts":"2026-04-23T14:47:49.722255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.527618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:119040"} {"level":"info","ts":"2026-04-23T14:47:49.722273Z","caller":"traceutil/trace.go:171","msg":"trace[1821096193] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1308; }","duration":"310.981947ms","start":"2026-04-23T14:47:49.411266Z","end":"2026-04-23T14:47:49.722248Z","steps":["trace[1821096193] 'range keys from in-memory index tree' (duration: 310.735462ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:47:49.72231Z","caller":"traceutil/trace.go:171","msg":"trace[1886549912] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1308; }","duration":"312.737212ms","start":"2026-04-23T14:47:49.409558Z","end":"2026-04-23T14:47:49.722295Z","steps":["trace[1886549912] 'range keys from in-memory index tree' (duration: 312.214852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:47:49.722343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:47:49.40952Z","time spent":"312.815124ms","remote":"127.0.0.1:40542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":119063,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-04-23T14:47:49.722319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:47:49.411246Z","time spent":"311.064388ms","remote":"127.0.0.1:40554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":141,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-04-23T14:47:49.722495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.393761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-23T14:47:49.722587Z","caller":"traceutil/trace.go:171","msg":"trace[1367778268] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1308; }","duration":"337.548264ms","start":"2026-04-23T14:47:49.385015Z","end":"2026-04-23T14:47:49.722564Z","steps":["trace[1367778268] 'range keys from in-memory index tree' (duration: 337.142606ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:47:49.722643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:47:49.384994Z","time spent":"337.635867ms","remote":"127.0.0.1:40772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-23T14:47:49.843541Z","caller":"traceutil/trace.go:171","msg":"trace[1532392460] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"115.623236ms","start":"2026-04-23T14:47:49.727882Z","end":"2026-04-23T14:47:49.843505Z","steps":["trace[1532392460] 'process raft request' (duration: 113.387072ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:47:49.843936Z","caller":"traceutil/trace.go:171","msg":"trace[1501098562] linearizableReadLoop","detail":"{readStateIndex:1366; appliedIndex:1365; }","duration":"115.622906ms","start":"2026-04-23T14:47:49.728273Z","end":"2026-04-23T14:47:49.843895Z","steps":["trace[1501098562] 'read index received' (duration: 21.295453ms)","trace[1501098562] 'applied index is now lower than readState.Index' (duration: 94.324473ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T14:47:49.843969Z","caller":"traceutil/trace.go:171","msg":"trace[1510039902] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"114.116816ms","start":"2026-04-23T14:47:49.729811Z","end":"2026-04-23T14:47:49.843928Z","steps":["trace[1510039902] 'process raft request' (duration: 113.639346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:47:49.844183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.886761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:119040"} {"level":"info","ts":"2026-04-23T14:47:49.844254Z","caller":"traceutil/trace.go:171","msg":"trace[1800320303] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1311; }","duration":"115.985535ms","start":"2026-04-23T14:47:49.728252Z","end":"2026-04-23T14:47:49.844238Z","steps":["trace[1800320303] 'agreement among raft nodes before linearized reading' (duration: 115.752399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:47:50.314306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.544154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T14:47:50.314376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.925003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-23T14:47:50.314428Z","caller":"traceutil/trace.go:171","msg":"trace[1940185282] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1311; }","duration":"192.719087ms","start":"2026-04-23T14:47:50.121681Z","end":"2026-04-23T14:47:50.3144Z","steps":["trace[1940185282] 'range keys from in-memory index tree' (duration: 192.431772ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:47:50.314477Z","caller":"traceutil/trace.go:171","msg":"trace[1072902384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1311; }","duration":"333.117167ms","start":"2026-04-23T14:47:49.981339Z","end":"2026-04-23T14:47:50.314456Z","steps":["trace[1072902384] 'range keys from in-memory index tree' (duration: 332.679068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:47:50.314547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:47:49.981318Z","time spent":"333.211779ms","remote":"127.0.0.1:40772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-23T14:47:50.517448Z","caller":"traceutil/trace.go:171","msg":"trace[466857758] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"197.197217ms","start":"2026-04-23T14:47:50.320225Z","end":"2026-04-23T14:47:50.517423Z","steps":["trace[466857758] 'process raft request' (duration: 197.023674ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:47:51.665602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.141149ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T14:47:51.665749Z","caller":"traceutil/trace.go:171","msg":"trace[777588670] linearizableReadLoop","detail":"{readStateIndex:1372; appliedIndex:1370; }","duration":"308.436026ms","start":"2026-04-23T14:47:51.3573Z","end":"2026-04-23T14:47:51.665736Z","steps":["trace[777588670] 'read index received' (duration: 131.208655ms)","trace[777588670] 'applied index is now lower than readState.Index' (duration: 177.226301ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T14:47:51.665815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.511798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T14:47:51.66584Z","caller":"traceutil/trace.go:171","msg":"trace[1191377499] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1315; }","duration":"308.557199ms","start":"2026-04-23T14:47:51.357275Z","end":"2026-04-23T14:47:51.665833Z","steps":["trace[1191377499] 'agreement among raft nodes before linearized reading' (duration: 308.495378ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:47:51.665865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:47:51.357266Z","time spent":"308.59441ms","remote":"127.0.0.1:40774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"info","ts":"2026-04-23T14:47:51.665916Z","caller":"traceutil/trace.go:171","msg":"trace[769621035] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"343.115316ms","start":"2026-04-23T14:47:51.322765Z","end":"2026-04-23T14:47:51.665881Z","steps":["trace[769621035] 'process raft request' (duration: 342.930152ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:47:51.665927Z","caller":"traceutil/trace.go:171","msg":"trace[214804221] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"345.456573ms","start":"2026-04-23T14:47:51.320436Z","end":"2026-04-23T14:47:51.665892Z","steps":["trace[214804221] 'process raft request' (duration: 167.959927ms)","trace[214804221] 'compare' (duration: 177.026067ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T14:47:51.666552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:47:51.322744Z","time spent":"343.251899ms","remote":"127.0.0.1:40772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T14:47:51.666773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:47:51.32041Z","time spent":"345.590355ms","remote":"127.0.0.1:40516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T14:47:51.88548Z","caller":"traceutil/trace.go:171","msg":"trace[803047087] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"130.046293ms","start":"2026-04-23T14:47:51.755397Z","end":"2026-04-23T14:47:51.885443Z","steps":["trace[803047087] 'process raft request' (duration: 129.729077ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:47:52.25973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.960653ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T14:47:52.259909Z","caller":"traceutil/trace.go:171","msg":"trace[1587760600] linearizableReadLoop","detail":"{readStateIndex:1375; appliedIndex:1373; }","duration":"257.949224ms","start":"2026-04-23T14:47:52.001942Z","end":"2026-04-23T14:47:52.259891Z","steps":["trace[1587760600] 'read index received' (duration: 66.717156ms)","trace[1587760600] 'applied index is now lower than readState.Index' (duration: 191.231078ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T14:47:52.259978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.039026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T14:47:52.260013Z","caller":"traceutil/trace.go:171","msg":"trace[1396331570] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1318; }","duration":"258.106367ms","start":"2026-04-23T14:47:52.001894Z","end":"2026-04-23T14:47:52.260001Z","steps":["trace[1396331570] 'agreement among raft nodes before linearized reading' (duration: 258.048496ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:47:52.260017Z","caller":"traceutil/trace.go:171","msg":"trace[93511438] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"369.730454ms","start":"2026-04-23T14:47:51.890258Z","end":"2026-04-23T14:47:52.259989Z","steps":["trace[93511438] 'process raft request' (duration: 178.419124ms)","trace[93511438] 'compare' (duration: 190.8209ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T14:47:52.260095Z","caller":"traceutil/trace.go:171","msg":"trace[1730075680] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"369.333477ms","start":"2026-04-23T14:47:51.890684Z","end":"2026-04-23T14:47:52.260018Z","steps":["trace[1730075680] 'process raft request' (duration: 369.157963ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:47:52.260136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:47:51.890238Z","time spent":"369.839347ms","remote":"127.0.0.1:40772","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-23T14:47:52.260397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:47:51.89066Z","time spent":"369.55888ms","remote":"127.0.0.1:40772","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-23T14:47:52.781819Z","caller":"traceutil/trace.go:171","msg":"trace[475234105] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"155.766514ms","start":"2026-04-23T14:47:52.62602Z","end":"2026-04-23T14:47:52.781787Z","steps":["trace[475234105] 'process raft request' (duration: 155.59156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:47:53.118479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.867324ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T14:47:53.118597Z","caller":"traceutil/trace.go:171","msg":"trace[1789360340] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"492.212946ms","start":"2026-04-23T14:47:52.626365Z","end":"2026-04-23T14:47:53.118578Z","steps":["trace[1789360340] 'process raft request' (duration: 321.160678ms)","trace[1789360340] 'compare' (duration: 170.769433ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T14:47:53.118655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:47:52.626348Z","time spent":"492.279908ms","remote":"127.0.0.1:40772","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-23T14:47:53.502345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.859332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T14:47:53.502551Z","caller":"traceutil/trace.go:171","msg":"trace[1071777818] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1320; }","duration":"182.147298ms","start":"2026-04-23T14:47:53.320378Z","end":"2026-04-23T14:47:53.502526Z","steps":["trace[1071777818] 'count revisions from in-memory index tree' (duration: 181.702159ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:47:53.823468Z","caller":"traceutil/trace.go:171","msg":"trace[626554534] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"145.779386ms","start":"2026-04-23T14:47:53.677663Z","end":"2026-04-23T14:47:53.823442Z","steps":["trace[626554534] 'process raft request' (duration: 145.616323ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:47:54.086462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.928216ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T14:47:54.086853Z","caller":"traceutil/trace.go:171","msg":"trace[1794604209] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"195.549164ms","start":"2026-04-23T14:47:53.891263Z","end":"2026-04-23T14:47:54.086813Z","steps":["trace[1794604209] 'process raft request' (duration: 195.34957ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:47:54.086975Z","caller":"traceutil/trace.go:171","msg":"trace[1839911504] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"280.477781ms","start":"2026-04-23T14:47:53.806467Z","end":"2026-04-23T14:47:54.086945Z","steps":["trace[1839911504] 'process raft request' (duration: 112.974223ms)","trace[1839911504] 'compare' (duration: 166.724712ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T14:47:56.170038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.403166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T14:47:56.17023Z","caller":"traceutil/trace.go:171","msg":"trace[1700718746] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1328; }","duration":"168.657681ms","start":"2026-04-23T14:47:56.001535Z","end":"2026-04-23T14:47:56.170193Z","steps":["trace[1700718746] 'range keys from in-memory index tree' (duration: 168.305003ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:47:56.520449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.925609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-23T14:47:56.520568Z","caller":"traceutil/trace.go:171","msg":"trace[1165366551] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1329; }","duration":"218.113333ms","start":"2026-04-23T14:47:56.30243Z","end":"2026-04-23T14:47:56.520543Z","steps":["trace[1165366551] 'range keys from in-memory index tree' (duration: 217.648793ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:47:56.520709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.022661ms","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-23T14:47:56.5208Z","caller":"traceutil/trace.go:171","msg":"trace[138393258] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1329; }","duration":"219.162663ms","start":"2026-04-23T14:47:56.301615Z","end":"2026-04-23T14:47:56.520777Z","steps":["trace[138393258] 'range keys from in-memory index tree' (duration: 218.833146ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:47:57.135578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.608283ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-23T14:47:57.13598Z","caller":"traceutil/trace.go:171","msg":"trace[582652085] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"610.146559ms","start":"2026-04-23T14:47:56.525816Z","end":"2026-04-23T14:47:57.135963Z","steps":["trace[582652085] 'process raft request' (duration: 609.937875ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:47:57.136078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:47:56.525794Z","time spent":"610.227361ms","remote":"127.0.0.1:40772","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-23T14:47:57.259152Z","caller":"traceutil/trace.go:171","msg":"trace[681989178] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"733.160503ms","start":"2026-04-23T14:47:56.525973Z","end":"2026-04-23T14:47:57.259133Z","steps":["trace[681989178] 'process raft request' (duration: 730.181733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:47:57.259267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:47:56.52592Z","time spent":"733.285785ms","remote":"127.0.0.1:40772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T14:47:57.259271Z","caller":"traceutil/trace.go:171","msg":"trace[1466866750] linearizableReadLoop","detail":"{readStateIndex:1390; appliedIndex:1386; }","duration":"462.851494ms","start":"2026-04-23T14:47:56.796399Z","end":"2026-04-23T14:47:57.25925Z","steps":["trace[1466866750] 'read index received' (duration: 300.229684ms)","trace[1466866750] 'applied index is now lower than readState.Index' (duration: 162.62047ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T14:47:57.259362Z","caller":"traceutil/trace.go:171","msg":"trace[187056644] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"465.782802ms","start":"2026-04-23T14:47:56.793547Z","end":"2026-04-23T14:47:57.25933Z","steps":["trace[187056644] 'process raft request' (duration: 465.549108ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:47:57.259506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:47:56.793493Z","time spent":"465.945136ms","remote":"127.0.0.1:40772","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-23T14:47:57.259628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.211611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-23T14:47:57.259725Z","caller":"traceutil/trace.go:171","msg":"trace[597643608] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1332; }","duration":"463.371665ms","start":"2026-04-23T14:47:56.796332Z","end":"2026-04-23T14:47:57.259704Z","steps":["trace[597643608] 'agreement among raft nodes before linearized reading' (duration: 463.042937ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:47:57.259772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:47:56.796272Z","time spent":"463.493997ms","remote":"127.0.0.1:40772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-23T14:47:57.259914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.872672ms","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-23T14:47:57.260014Z","caller":"traceutil/trace.go:171","msg":"trace[1542066448] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1332; }","duration":"117.445033ms","start":"2026-04-23T14:47:57.142552Z","end":"2026-04-23T14:47:57.259997Z","steps":["trace[1542066448] 'agreement among raft nodes before linearized reading' (duration: 116.80561ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:48:26.171859Z","caller":"traceutil/trace.go:171","msg":"trace[1389230149] transaction","detail":"{read_only:false; response_revision:1428; number_of_response:1; }","duration":"112.968954ms","start":"2026-04-23T14:48:26.058865Z","end":"2026-04-23T14:48:26.171833Z","steps":["trace[1389230149] 'process raft request' (duration: 112.811911ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:48:26.492494Z","caller":"traceutil/trace.go:171","msg":"trace[607371611] transaction","detail":"{read_only:false; response_revision:1429; number_of_response:1; }","duration":"161.308734ms","start":"2026-04-23T14:48:26.331149Z","end":"2026-04-23T14:48:26.492458Z","steps":["trace[607371611] 'process raft request' (duration: 68.231265ms)","trace[607371611] 'compare' (duration: 92.938376ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T14:48:32.306701Z","caller":"traceutil/trace.go:171","msg":"trace[210921153] transaction","detail":"{read_only:false; response_revision:1449; number_of_response:1; }","duration":"103.458466ms","start":"2026-04-23T14:48:32.203206Z","end":"2026-04-23T14:48:32.306664Z","steps":["trace[210921153] 'process raft request' (duration: 103.224371ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:48:32.652573Z","caller":"traceutil/trace.go:171","msg":"trace[1975815091] transaction","detail":"{read_only:false; response_revision:1450; number_of_response:1; }","duration":"140.987921ms","start":"2026-04-23T14:48:32.511553Z","end":"2026-04-23T14:48:32.652541Z","steps":["trace[1975815091] 'process raft request' (duration: 140.695686ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:48:38.840868Z","caller":"traceutil/trace.go:171","msg":"trace[1942725039] linearizableReadLoop","detail":"{readStateIndex:1535; appliedIndex:1534; }","duration":"196.042414ms","start":"2026-04-23T14:48:38.644795Z","end":"2026-04-23T14:48:38.840837Z","steps":["trace[1942725039] 'read index received' (duration: 184.516766ms)","trace[1942725039] 'applied index is now lower than readState.Index' (duration: 11.524468ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T14:48:38.841007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.197237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T14:48:38.841041Z","caller":"traceutil/trace.go:171","msg":"trace[1121204513] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1469; }","duration":"196.302019ms","start":"2026-04-23T14:48:38.644728Z","end":"2026-04-23T14:48:38.84103Z","steps":["trace[1121204513] 'agreement among raft nodes before linearized reading' (duration: 196.213017ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:48:38.841002Z","caller":"traceutil/trace.go:171","msg":"trace[2043708717] transaction","detail":"{read_only:false; response_revision:1469; number_of_response:1; }","duration":"389.394025ms","start":"2026-04-23T14:48:38.451572Z","end":"2026-04-23T14:48:38.840966Z","steps":["trace[2043708717] 'process raft request' (duration: 377.719913ms)","trace[2043708717] 'compare' (duration: 11.128661ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T14:48:38.841184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:48:38.451547Z","time spent":"389.540507ms","remote":"127.0.0.1:40772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T14:48:38.853711Z","caller":"traceutil/trace.go:171","msg":"trace[1543247537] transaction","detail":"{read_only:false; response_revision:1470; number_of_response:1; }","duration":"178.369292ms","start":"2026-04-23T14:48:38.675323Z","end":"2026-04-23T14:48:38.853692Z","steps":["trace[1543247537] 'process raft request' (duration: 178.221389ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:48:44.603143Z","caller":"traceutil/trace.go:171","msg":"trace[885895007] transaction","detail":"{read_only:false; response_revision:1488; number_of_response:1; }","duration":"132.828419ms","start":"2026-04-23T14:48:44.470283Z","end":"2026-04-23T14:48:44.603111Z","steps":["trace[885895007] 'process raft request' (duration: 132.627025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:48:45.290976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.607601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T14:48:45.291109Z","caller":"traceutil/trace.go:171","msg":"trace[439008419] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1489; }","duration":"289.818546ms","start":"2026-04-23T14:48:45.001263Z","end":"2026-04-23T14:48:45.291082Z","steps":["trace[439008419] 'range keys from in-memory index tree' (duration: 289.469888ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:48:51.261135Z","caller":"traceutil/trace.go:171","msg":"trace[1355268950] linearizableReadLoop","detail":"{readStateIndex:1577; appliedIndex:1576; }","duration":"260.40794ms","start":"2026-04-23T14:48:51.000685Z","end":"2026-04-23T14:48:51.261093Z","steps":["trace[1355268950] 'read index received' (duration: 245.960902ms)","trace[1355268950] 'applied index is now lower than readState.Index' (duration: 14.445328ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T14:48:51.261407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.710687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T14:48:51.261496Z","caller":"traceutil/trace.go:171","msg":"trace[1648217743] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1509; }","duration":"260.85403ms","start":"2026-04-23T14:48:51.000615Z","end":"2026-04-23T14:48:51.261469Z","steps":["trace[1648217743] 'agreement among raft nodes before linearized reading' (duration: 260.723416ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:48:51.261545Z","caller":"traceutil/trace.go:171","msg":"trace[142701207] transaction","detail":"{read_only:false; response_revision:1509; number_of_response:1; }","duration":"327.601862ms","start":"2026-04-23T14:48:50.933915Z","end":"2026-04-23T14:48:51.261517Z","steps":["trace[142701207] 'process raft request' (duration: 312.805535ms)","trace[142701207] 'compare' (duration: 13.903197ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T14:48:51.261678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:48:50.933892Z","time spent":"327.714924ms","remote":"127.0.0.1:40772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T14:48:51.67308Z","caller":"traceutil/trace.go:171","msg":"trace[949603522] linearizableReadLoop","detail":"{readStateIndex:1579; appliedIndex:1578; }","duration":"260.930101ms","start":"2026-04-23T14:48:51.412121Z","end":"2026-04-23T14:48:51.673051Z","steps":["trace[949603522] 'read index received' (duration: 260.733546ms)","trace[949603522] 'applied index is now lower than readState.Index' (duration: 195.385µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T14:48:51.673125Z","caller":"traceutil/trace.go:171","msg":"trace[2056805429] transaction","detail":"{read_only:false; response_revision:1510; number_of_response:1; }","duration":"361.287659ms","start":"2026-04-23T14:48:51.311787Z","end":"2026-04-23T14:48:51.673075Z","steps":["trace[2056805429] 'process raft request' (duration: 361.029294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:48:51.673331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:48:51.31177Z","time spent":"361.444623ms","remote":"127.0.0.1:40516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T14:48:51.673463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.548123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-23T14:48:51.673516Z","caller":"traceutil/trace.go:171","msg":"trace[1068144658] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1510; }","duration":"115.670376ms","start":"2026-04-23T14:48:51.557828Z","end":"2026-04-23T14:48:51.673499Z","steps":["trace[1068144658] 'agreement among raft nodes before linearized reading' (duration: 115.40701ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:48:51.67355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.659024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-23T14:48:51.673578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.416011ms","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-23T14:48:51.6737Z","caller":"traceutil/trace.go:171","msg":"trace[1333817752] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1510; }","duration":"261.629736ms","start":"2026-04-23T14:48:51.412048Z","end":"2026-04-23T14:48:51.673678Z","steps":["trace[1333817752] 'agreement among raft nodes before linearized reading' (duration: 261.146536ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:48:51.673632Z","caller":"traceutil/trace.go:171","msg":"trace[1120930824] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1510; }","duration":"114.785966ms","start":"2026-04-23T14:48:51.558825Z","end":"2026-04-23T14:48:51.673611Z","steps":["trace[1120930824] 'agreement among raft nodes before linearized reading' (duration: 114.542521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:48:51.673847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.99027ms","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-23T14:48:51.673956Z","caller":"traceutil/trace.go:171","msg":"trace[368268998] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1510; }","duration":"115.162604ms","start":"2026-04-23T14:48:51.558762Z","end":"2026-04-23T14:48:51.673925Z","steps":["trace[368268998] 'agreement among raft nodes before linearized reading' (duration: 114.753045ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:48:51.967234Z","caller":"traceutil/trace.go:171","msg":"trace[1560677628] transaction","detail":"{read_only:false; response_revision:1511; number_of_response:1; }","duration":"289.580011ms","start":"2026-04-23T14:48:51.67762Z","end":"2026-04-23T14:48:51.9672Z","steps":["trace[1560677628] 'process raft request' (duration: 210.275621ms)","trace[1560677628] 'compare' (duration: 79.072975ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T14:48:51.967536Z","caller":"traceutil/trace.go:171","msg":"trace[179343335] transaction","detail":"{read_only:false; response_revision:1512; number_of_response:1; }","duration":"289.414958ms","start":"2026-04-23T14:48:51.678095Z","end":"2026-04-23T14:48:51.96751Z","steps":["trace[179343335] 'process raft request' (duration: 289.05419ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:48:51.967822Z","caller":"traceutil/trace.go:171","msg":"trace[1972930971] transaction","detail":"{read_only:false; response_revision:1513; number_of_response:1; }","duration":"289.683563ms","start":"2026-04-23T14:48:51.678113Z","end":"2026-04-23T14:48:51.967797Z","steps":["trace[1972930971] 'process raft request' (duration: 289.286975ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:48:51.967937Z","caller":"traceutil/trace.go:171","msg":"trace[164686313] transaction","detail":"{read_only:false; response_revision:1514; number_of_response:1; }","duration":"289.649123ms","start":"2026-04-23T14:48:51.678276Z","end":"2026-04-23T14:48:51.967925Z","steps":["trace[164686313] 'process raft request' (duration: 289.434449ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:48:51.96795Z","caller":"traceutil/trace.go:171","msg":"trace[352749835] linearizableReadLoop","detail":"{readStateIndex:1583; appliedIndex:1579; }","duration":"289.610372ms","start":"2026-04-23T14:48:51.678308Z","end":"2026-04-23T14:48:51.967918Z","steps":["trace[352749835] 'read index received' (duration: 209.688378ms)","trace[352749835] 'applied index is now lower than readState.Index' (duration: 79.920334ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T14:48:51.968274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.938989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-23T14:48:51.968491Z","caller":"traceutil/trace.go:171","msg":"trace[195800890] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:1514; }","duration":"290.176154ms","start":"2026-04-23T14:48:51.678288Z","end":"2026-04-23T14:48:51.968464Z","steps":["trace[195800890] 'agreement among raft nodes before linearized reading' (duration: 289.762856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:48:57.665956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.044538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/goldpinger\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T14:48:57.666079Z","caller":"traceutil/trace.go:171","msg":"trace[939325019] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/goldpinger; range_end:; response_count:0; response_revision:1529; }","duration":"234.234262ms","start":"2026-04-23T14:48:57.431819Z","end":"2026-04-23T14:48:57.666053Z","steps":["trace[939325019] 'range keys from in-memory index tree' (duration: 233.920015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:48:58.282843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.025754ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-23T14:48:58.282932Z","caller":"traceutil/trace.go:171","msg":"trace[61769443] transaction","detail":"{read_only:false; response_revision:1531; number_of_response:1; }","duration":"329.978762ms","start":"2026-04-23T14:48:57.952938Z","end":"2026-04-23T14:48:58.282917Z","steps":["trace[61769443] 'process raft request' (duration: 143.827196ms)","trace[61769443] 'compare' (duration: 185.788929ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T14:48:58.282964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:48:57.952874Z","time spent":"330.077304ms","remote":"127.0.0.1:40700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T14:48:58.334494Z","caller":"traceutil/trace.go:171","msg":"trace[55598761] transaction","detail":"{read_only:false; response_revision:1532; number_of_response:1; }","duration":"380.853036ms","start":"2026-04-23T14:48:57.953618Z","end":"2026-04-23T14:48:58.334471Z","steps":["trace[55598761] 'process raft request' (duration: 380.646721ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:48:58.334565Z","caller":"traceutil/trace.go:171","msg":"trace[538398390] transaction","detail":"{read_only:false; response_revision:1533; number_of_response:1; }","duration":"378.288261ms","start":"2026-04-23T14:48:57.95624Z","end":"2026-04-23T14:48:58.334528Z","steps":["trace[538398390] 'process raft request' (duration: 378.165478ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:48:58.334608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.108411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T14:48:58.334628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:48:57.953594Z","time spent":"380.971729ms","remote":"127.0.0.1:40598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1729,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T14:48:58.334478Z","caller":"traceutil/trace.go:171","msg":"trace[2002874893] linearizableReadLoop","detail":"{readStateIndex:1603; appliedIndex:1600; }","duration":"372.965847ms","start":"2026-04-23T14:48:57.961484Z","end":"2026-04-23T14:48:58.33445Z","steps":["trace[2002874893] 'read index received' (duration: 135.304574ms)","trace[2002874893] 'applied index is now lower than readState.Index' (duration: 237.660313ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T14:48:58.334709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:48:57.956215Z","time spent":"378.430574ms","remote":"127.0.0.1:40592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10249,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T14:48:58.334656Z","caller":"traceutil/trace.go:171","msg":"trace[1038912371] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:1533; }","duration":"373.185492ms","start":"2026-04-23T14:48:57.961459Z","end":"2026-04-23T14:48:58.334644Z","steps":["trace[1038912371] 'agreement among raft nodes before linearized reading' (duration: 373.07117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:48:58.334778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:48:57.961449Z","time spent":"373.320125ms","remote":"127.0.0.1:40592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" "} {"level":"warn","ts":"2026-04-23T14:48:58.334871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.632827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/openvswitch-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T14:48:58.334953Z","caller":"traceutil/trace.go:171","msg":"trace[1424237934] range","detail":"{range_begin:/registry/serviceaccounts/openstack/openvswitch-server; range_end:; response_count:0; response_revision:1533; }","duration":"217.76405ms","start":"2026-04-23T14:48:58.117172Z","end":"2026-04-23T14:48:58.334936Z","steps":["trace[1424237934] 'agreement among raft nodes before linearized reading' (duration: 217.616416ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:48:58.33495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.798218ms","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-23T14:48:58.335019Z","caller":"traceutil/trace.go:171","msg":"trace[966969851] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1533; }","duration":"344.927511ms","start":"2026-04-23T14:48:57.990074Z","end":"2026-04-23T14:48:58.335001Z","steps":["trace[966969851] 'agreement among raft nodes before linearized reading' (duration: 344.706485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:48:58.335063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.84081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/ingress-nginx/\" range_end:\"/registry/secrets/ingress-nginx0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T14:48:58.335081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:48:57.990046Z","time spent":"345.024333ms","remote":"127.0.0.1:40772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-23T14:48:58.335111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.452659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T14:48:58.335159Z","caller":"traceutil/trace.go:171","msg":"trace[1538023380] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1533; }","duration":"335.523341ms","start":"2026-04-23T14:48:57.999623Z","end":"2026-04-23T14:48:58.335147Z","steps":["trace[1538023380] 'agreement among raft nodes before linearized reading' (duration: 335.451799ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:48:58.33509Z","caller":"traceutil/trace.go:171","msg":"trace[136011801] range","detail":"{range_begin:/registry/secrets/ingress-nginx/; range_end:/registry/secrets/ingress-nginx0; response_count:0; response_revision:1533; }","duration":"176.938771ms","start":"2026-04-23T14:48:58.158141Z","end":"2026-04-23T14:48:58.335079Z","steps":["trace[136011801] 'agreement among raft nodes before linearized reading' (duration: 176.8767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:48:58.335194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:48:57.999612Z","time spent":"335.575652ms","remote":"127.0.0.1:37756","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-23T14:48:58.335208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.03267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-23T14:48:58.335246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.187471ms","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-23T14:48:58.335204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.761357ms","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-23T14:48:58.335263Z","caller":"traceutil/trace.go:171","msg":"trace[1940983170] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1533; }","duration":"341.247752ms","start":"2026-04-23T14:48:57.994011Z","end":"2026-04-23T14:48:58.335259Z","steps":["trace[1940983170] 'agreement among raft nodes before linearized reading' (duration: 341.204841ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:48:58.335265Z","caller":"traceutil/trace.go:171","msg":"trace[1026774991] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1533; }","duration":"335.106412ms","start":"2026-04-23T14:48:58.000146Z","end":"2026-04-23T14:48:58.335252Z","steps":["trace[1026774991] 'agreement among raft nodes before linearized reading' (duration: 334.935708ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:48:58.335289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:48:57.993988Z","time spent":"341.297073ms","remote":"127.0.0.1:40772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-23T14:48:58.335305Z","caller":"traceutil/trace.go:171","msg":"trace[1220546090] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1533; }","duration":"344.848678ms","start":"2026-04-23T14:48:57.990421Z","end":"2026-04-23T14:48:58.33527Z","steps":["trace[1220546090] 'agreement among raft nodes before linearized reading' (duration: 344.709695ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:48:58.335308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:48:58.000138Z","time spent":"335.160293ms","remote":"127.0.0.1:40772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-23T14:48:58.335349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:48:57.990409Z","time spent":"344.92932ms","remote":"127.0.0.1:40772","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-23T14:48:59.340874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.073794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T14:48:59.340959Z","caller":"traceutil/trace.go:171","msg":"trace[1300479286] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:1607; }","duration":"169.214166ms","start":"2026-04-23T14:48:59.171724Z","end":"2026-04-23T14:48:59.340938Z","steps":["trace[1300479286] 'range keys from in-memory index tree' (duration: 168.989402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:48:59.340928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.825138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T14:48:59.341023Z","caller":"traceutil/trace.go:171","msg":"trace[826685472] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:1607; }","duration":"168.94333ms","start":"2026-04-23T14:48:59.172052Z","end":"2026-04-23T14:48:59.340995Z","steps":["trace[826685472] 'range keys from in-memory index tree' (duration: 168.789288ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:48:59.341192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.204857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T14:48:59.341268Z","caller":"traceutil/trace.go:171","msg":"trace[1686936418] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:1607; }","duration":"169.310379ms","start":"2026-04-23T14:48:59.171942Z","end":"2026-04-23T14:48:59.341253Z","steps":["trace[1686936418] 'count revisions from in-memory index tree' (duration: 169.171845ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:48:59.341335Z","caller":"traceutil/trace.go:171","msg":"trace[1319418962] linearizableReadLoop","detail":"{readStateIndex:1680; appliedIndex:1679; }","duration":"106.867107ms","start":"2026-04-23T14:48:59.234436Z","end":"2026-04-23T14:48:59.341303Z","steps":["trace[1319418962] 'read index received' (duration: 104.006456ms)","trace[1319418962] 'applied index is now lower than readState.Index' (duration: 2.858521ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T14:48:59.34138Z","caller":"traceutil/trace.go:171","msg":"trace[1658952625] transaction","detail":"{read_only:false; response_revision:1608; number_of_response:1; }","duration":"128.537329ms","start":"2026-04-23T14:48:59.212831Z","end":"2026-04-23T14:48:59.341368Z","steps":["trace[1658952625] 'process raft request' (duration: 125.631717ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:48:59.341466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.035841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T14:48:59.3415Z","caller":"traceutil/trace.go:171","msg":"trace[750464649] range","detail":"{range_begin:/registry/serviceaccounts/ingress-nginx/ingress-nginx; range_end:; response_count:0; response_revision:1608; }","duration":"107.103932ms","start":"2026-04-23T14:48:59.234388Z","end":"2026-04-23T14:48:59.341492Z","steps":["trace[750464649] 'agreement among raft nodes before linearized reading' (duration: 107.01364ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:48:59.716836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.612155ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T14:48:59.716976Z","caller":"traceutil/trace.go:171","msg":"trace[219999600] linearizableReadLoop","detail":"{readStateIndex:1681; appliedIndex:1680; }","duration":"372.10576ms","start":"2026-04-23T14:48:59.344847Z","end":"2026-04-23T14:48:59.716952Z","steps":["trace[219999600] 'read index received' (duration: 202.2642ms)","trace[219999600] 'applied index is now lower than readState.Index' (duration: 169.83986ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T14:48:59.717009Z","caller":"traceutil/trace.go:171","msg":"trace[66832691] transaction","detail":"{read_only:false; response_revision:1609; number_of_response:1; }","duration":"375.133134ms","start":"2026-04-23T14:48:59.341835Z","end":"2026-04-23T14:48:59.716968Z","steps":["trace[66832691] 'process raft request' (duration: 205.261694ms)","trace[66832691] 'compare' (duration: 169.430381ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T14:48:59.71709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.247143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/rook-ceph/rook-ceph-mgr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T14:48:59.717135Z","caller":"traceutil/trace.go:171","msg":"trace[1466332308] range","detail":"{range_begin:/registry/serviceaccounts/rook-ceph/rook-ceph-mgr; range_end:; response_count:0; response_revision:1609; }","duration":"372.325095ms","start":"2026-04-23T14:48:59.344796Z","end":"2026-04-23T14:48:59.717121Z","steps":["trace[1466332308] 'agreement among raft nodes before linearized reading' (duration: 372.245143ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:48:59.717185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:48:59.344777Z","time spent":"372.399806ms","remote":"127.0.0.1:40700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/rook-ceph/rook-ceph-mgr\" "} {"level":"warn","ts":"2026-04-23T14:48:59.717201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:48:59.341804Z","time spent":"375.292878ms","remote":"127.0.0.1:40772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T14:49:00.061514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.952975ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-23T14:49:00.061625Z","caller":"traceutil/trace.go:171","msg":"trace[725681220] linearizableReadLoop","detail":"{readStateIndex:1682; appliedIndex:1681; }","duration":"344.597343ms","start":"2026-04-23T14:48:59.717009Z","end":"2026-04-23T14:49:00.061606Z","steps":["trace[725681220] 'read index received' (duration: 156.415684ms)","trace[725681220] 'applied index is now lower than readState.Index' (duration: 188.180199ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T14:49:00.061707Z","caller":"traceutil/trace.go:171","msg":"trace[1278534813] transaction","detail":"{read_only:false; response_revision:1610; number_of_response:1; }","duration":"713.595537ms","start":"2026-04-23T14:48:59.3481Z","end":"2026-04-23T14:49:00.061696Z","steps":["trace[1278534813] 'process raft request' (duration: 525.386107ms)","trace[1278534813] 'compare' (duration: 187.72155ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T14:49:00.061777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:48:59.348068Z","time spent":"713.684209ms","remote":"127.0.0.1:52270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2431,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T14:49:00.062045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.788649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T14:49:00.062019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.615716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T14:49:00.06205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.97375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-cainjector\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T14:49:00.062086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.931578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx-backend\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T14:49:00.062137Z","caller":"traceutil/trace.go:171","msg":"trace[529136948] range","detail":"{range_begin:/registry/serviceaccounts/ingress-nginx/ingress-nginx-backend; range_end:; response_count:0; response_revision:1610; }","duration":"717.013719ms","start":"2026-04-23T14:48:59.345109Z","end":"2026-04-23T14:49:00.062123Z","steps":["trace[529136948] 'agreement among raft nodes before linearized reading' (duration: 716.916037ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:49:00.062137Z","caller":"traceutil/trace.go:171","msg":"trace[663032944] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-cainjector; range_end:; response_count:0; response_revision:1610; }","duration":"367.112423ms","start":"2026-04-23T14:48:59.695005Z","end":"2026-04-23T14:49:00.062118Z","steps":["trace[663032944] 'agreement among raft nodes before linearized reading' (duration: 366.96297ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:49:00.062146Z","caller":"traceutil/trace.go:171","msg":"trace[2124055041] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:1610; }","duration":"666.757679ms","start":"2026-04-23T14:48:59.395341Z","end":"2026-04-23T14:49:00.062099Z","steps":["trace[2124055041] 'agreement among raft nodes before linearized reading' (duration: 666.494613ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:49:00.062183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:48:59.69497Z","time spent":"367.199805ms","remote":"127.0.0.1:40700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-cainjector\" "} {"level":"warn","ts":"2026-04-23T14:49:00.062209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:48:59.395335Z","time spent":"666.862401ms","remote":"127.0.0.1:60220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":93,"response count":0,"response size":28,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" limit:10000 "} {"level":"warn","ts":"2026-04-23T14:49:00.062235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.083247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:1 size:10243"} {"level":"info","ts":"2026-04-23T14:49:00.062273Z","caller":"traceutil/trace.go:171","msg":"trace[19152257] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:1; response_revision:1610; }","duration":"470.121148ms","start":"2026-04-23T14:48:59.592144Z","end":"2026-04-23T14:49:00.062265Z","steps":["trace[19152257] 'agreement among raft nodes before linearized reading' (duration: 469.985885ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:49:00.062303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:48:59.592118Z","time spent":"470.17744ms","remote":"127.0.0.1:40592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":10266,"request content":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" "} {"level":"warn","ts":"2026-04-23T14:49:00.062165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:48:59.345088Z","time spent":"717.070861ms","remote":"127.0.0.1:40700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx-backend\" "} {"level":"warn","ts":"2026-04-23T14:49:00.062265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.108786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T14:49:00.062452Z","caller":"traceutil/trace.go:171","msg":"trace[1295614091] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:1610; }","duration":"667.359412ms","start":"2026-04-23T14:48:59.395063Z","end":"2026-04-23T14:49:00.062423Z","steps":["trace[1295614091] 'agreement among raft nodes before linearized reading' (duration: 667.056405ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:49:00.062091Z","caller":"traceutil/trace.go:171","msg":"trace[2135557725] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:1610; }","duration":"666.907452ms","start":"2026-04-23T14:48:59.395169Z","end":"2026-04-23T14:49:00.062076Z","steps":["trace[2135557725] 'agreement among raft nodes before linearized reading' (duration: 666.824761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:49:00.062535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:48:59.395039Z","time spent":"667.468483ms","remote":"127.0.0.1:60220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":93,"response count":0,"response size":28,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" limit:500 "} {"level":"warn","ts":"2026-04-23T14:49:00.062543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:48:59.395133Z","time spent":"667.393472ms","remote":"127.0.0.1:60220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":0,"response size":28,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true "} {"level":"warn","ts":"2026-04-23T14:49:00.534257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.290133ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T14:49:00.534341Z","caller":"traceutil/trace.go:171","msg":"trace[1330893513] linearizableReadLoop","detail":"{readStateIndex:1683; appliedIndex:1682; }","duration":"472.677934ms","start":"2026-04-23T14:49:00.061652Z","end":"2026-04-23T14:49:00.53433Z","steps":["trace[1330893513] 'read index received' (duration: 170.177327ms)","trace[1330893513] 'applied index is now lower than readState.Index' (duration: 302.499886ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T14:49:00.534411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.9928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T14:49:00.534429Z","caller":"traceutil/trace.go:171","msg":"trace[656357838] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1611; }","duration":"535.045901ms","start":"2026-04-23T14:48:59.999377Z","end":"2026-04-23T14:49:00.534423Z","steps":["trace[656357838] 'agreement among raft nodes before linearized reading' (duration: 535.010581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:49:00.534447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:48:59.999355Z","time spent":"535.088463ms","remote":"127.0.0.1:37756","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-23T14:49:00.534577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.245417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-ndrjw\" ","response":"range_response_count:1 size:11624"} {"level":"warn","ts":"2026-04-23T14:49:00.534651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.711144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/ingress-nginx/ingress-nginx-tcp\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T14:49:00.534693Z","caller":"traceutil/trace.go:171","msg":"trace[1886956535] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-ndrjw; range_end:; response_count:1; response_revision:1611; }","duration":"505.415191ms","start":"2026-04-23T14:49:00.029253Z","end":"2026-04-23T14:49:00.534668Z","steps":["trace[1886956535] 'agreement among raft nodes before linearized reading' (duration: 505.113394ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:49:00.534735Z","caller":"traceutil/trace.go:171","msg":"trace[203077318] range","detail":"{range_begin:/registry/configmaps/ingress-nginx/ingress-nginx-tcp; range_end:; response_count:0; response_revision:1611; }","duration":"465.819147ms","start":"2026-04-23T14:49:00.068894Z","end":"2026-04-23T14:49:00.534713Z","steps":["trace[203077318] 'agreement among raft nodes before linearized reading' (duration: 465.676934ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:49:00.534751Z","caller":"traceutil/trace.go:171","msg":"trace[508725709] transaction","detail":"{read_only:false; response_revision:1611; number_of_response:1; }","duration":"639.971038ms","start":"2026-04-23T14:48:59.89477Z","end":"2026-04-23T14:49:00.534741Z","steps":["trace[508725709] 'process raft request' (duration: 337.133905ms)","trace[508725709] 'compare' (duration: 302.186469ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T14:49:00.534772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:49:00.029229Z","time spent":"505.532074ms","remote":"127.0.0.1:40690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11647,"request content":"key:\"/registry/pods/openstack/openvswitch-ndrjw\" "} {"level":"warn","ts":"2026-04-23T14:49:00.534779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:49:00.068862Z","time spent":"465.907009ms","remote":"127.0.0.1:40598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/configmaps/ingress-nginx/ingress-nginx-tcp\" "} {"level":"warn","ts":"2026-04-23T14:49:00.534799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:48:59.894748Z","time spent":"640.02888ms","remote":"127.0.0.1:40686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11596,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T14:49:00.534884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.501119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-23T14:49:00.534914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.329265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-23T14:49:00.534924Z","caller":"traceutil/trace.go:171","msg":"trace[1707811065] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1611; }","duration":"105.5553ms","start":"2026-04-23T14:49:00.429359Z","end":"2026-04-23T14:49:00.534914Z","steps":["trace[1707811065] 'agreement among raft nodes before linearized reading' (duration: 105.411807ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:49:00.534933Z","caller":"traceutil/trace.go:171","msg":"trace[971794296] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1611; }","duration":"105.352385ms","start":"2026-04-23T14:49:00.429576Z","end":"2026-04-23T14:49:00.534928Z","steps":["trace[971794296] 'agreement among raft nodes before linearized reading' (duration: 105.280984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:49:00.534984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.585888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/rook-ceph/rook-ceph-cmd-reporter\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T14:49:00.534987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.19283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/memcached-metrics\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T14:49:00.535009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.909728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T14:49:00.535094Z","caller":"traceutil/trace.go:171","msg":"trace[1965616472] range","detail":"{range_begin:/registry/services/specs/openstack/memcached-metrics; range_end:; response_count:0; response_revision:1611; }","duration":"447.366124ms","start":"2026-04-23T14:49:00.0877Z","end":"2026-04-23T14:49:00.535067Z","steps":["trace[1965616472] 'agreement among raft nodes before linearized reading' (duration: 447.15354ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:49:00.535063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.889716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:1 size:10243"} {"level":"warn","ts":"2026-04-23T14:49:00.535145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:49:00.087674Z","time spent":"447.465045ms","remote":"127.0.0.1:40698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/openstack/memcached-metrics\" "} {"level":"info","ts":"2026-04-23T14:49:00.535119Z","caller":"traceutil/trace.go:171","msg":"trace[543731964] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager; range_end:; response_count:0; response_revision:1611; }","duration":"466.081612ms","start":"2026-04-23T14:49:00.069012Z","end":"2026-04-23T14:49:00.535093Z","steps":["trace[543731964] 'agreement among raft nodes before linearized reading' (duration: 465.882387ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:49:00.535195Z","caller":"traceutil/trace.go:171","msg":"trace[149183719] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:1; response_revision:1611; }","duration":"235.034999ms","start":"2026-04-23T14:49:00.300125Z","end":"2026-04-23T14:49:00.53516Z","steps":["trace[149183719] 'agreement among raft nodes before linearized reading' (duration: 234.673811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:49:00.535252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:49:00.06898Z","time spent":"466.264446ms","remote":"127.0.0.1:40700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/cert-manager/cert-manager\" "} {"level":"info","ts":"2026-04-23T14:49:00.535065Z","caller":"traceutil/trace.go:171","msg":"trace[398141662] range","detail":"{range_begin:/registry/serviceaccounts/rook-ceph/rook-ceph-cmd-reporter; range_end:; response_count:0; response_revision:1611; }","duration":"813.723951ms","start":"2026-04-23T14:48:59.721323Z","end":"2026-04-23T14:49:00.535047Z","steps":["trace[398141662] 'agreement among raft nodes before linearized reading' (duration: 813.557457ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:49:00.535253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.721393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-23T14:49:00.535358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:48:59.721312Z","time spent":"814.018017ms","remote":"127.0.0.1:40700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/rook-ceph/rook-ceph-cmd-reporter\" "} {"level":"info","ts":"2026-04-23T14:49:00.535385Z","caller":"traceutil/trace.go:171","msg":"trace[1098165235] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1611; }","duration":"105.877006ms","start":"2026-04-23T14:49:00.429477Z","end":"2026-04-23T14:49:00.535354Z","steps":["trace[1098165235] 'agreement among raft nodes before linearized reading' (duration: 105.478768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:49:00.535257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.572871ms","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-23T14:49:00.535516Z","caller":"traceutil/trace.go:171","msg":"trace[1379143856] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1611; }","duration":"106.912238ms","start":"2026-04-23T14:49:00.428596Z","end":"2026-04-23T14:49:00.535508Z","steps":["trace[1379143856] 'agreement among raft nodes before linearized reading' (duration: 106.338896ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:49:00.874652Z","caller":"traceutil/trace.go:171","msg":"trace[2031398571] transaction","detail":"{read_only:false; response_revision:1612; number_of_response:1; }","duration":"335.751455ms","start":"2026-04-23T14:49:00.538866Z","end":"2026-04-23T14:49:00.874618Z","steps":["trace[2031398571] 'process raft request' (duration: 335.53897ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:49:00.874847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:49:00.538839Z","time spent":"335.913718ms","remote":"127.0.0.1:40772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T14:49:01.260509Z","caller":"traceutil/trace.go:171","msg":"trace[1298601869] transaction","detail":"{read_only:false; response_revision:1613; number_of_response:1; }","duration":"720.917642ms","start":"2026-04-23T14:49:00.539562Z","end":"2026-04-23T14:49:01.26048Z","steps":["trace[1298601869] 'process raft request' (duration: 720.688978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:49:01.260658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:49:00.539532Z","time spent":"721.053235ms","remote":"127.0.0.1:40772","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-23T14:49:01.260737Z","caller":"traceutil/trace.go:171","msg":"trace[1436088944] transaction","detail":"{read_only:false; response_revision:1614; number_of_response:1; }","duration":"720.987713ms","start":"2026-04-23T14:49:00.539731Z","end":"2026-04-23T14:49:01.260719Z","steps":["trace[1436088944] 'process raft request' (duration: 720.708648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:49:01.26084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:49:00.539701Z","time spent":"721.092006ms","remote":"127.0.0.1:40772","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-23T14:49:01.261023Z","caller":"traceutil/trace.go:171","msg":"trace[527319225] transaction","detail":"{read_only:false; response_revision:1615; number_of_response:1; }","duration":"718.354968ms","start":"2026-04-23T14:49:00.542632Z","end":"2026-04-23T14:49:01.260987Z","steps":["trace[527319225] 'process raft request' (duration: 718.019171ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:49:01.261209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.366081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/rook-ceph/rook-ceph-purge-osd\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T14:49:01.261259Z","caller":"traceutil/trace.go:171","msg":"trace[1263537687] range","detail":"{range_begin:/registry/serviceaccounts/rook-ceph/rook-ceph-purge-osd; range_end:; response_count:0; response_revision:1616; }","duration":"721.439223ms","start":"2026-04-23T14:49:00.539811Z","end":"2026-04-23T14:49:01.26125Z","steps":["trace[1263537687] 'agreement among raft nodes before linearized reading' (duration: 721.280969ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:49:01.261284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:49:00.539803Z","time spent":"721.476244ms","remote":"127.0.0.1:40700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/rook-ceph/rook-ceph-purge-osd\" "} {"level":"info","ts":"2026-04-23T14:49:01.261296Z","caller":"traceutil/trace.go:171","msg":"trace[1494272705] transaction","detail":"{read_only:false; response_revision:1616; number_of_response:1; }","duration":"715.877195ms","start":"2026-04-23T14:49:00.545399Z","end":"2026-04-23T14:49:01.261276Z","steps":["trace[1494272705] 'process raft request' (duration: 715.493977ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:49:01.261394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:49:00.545389Z","time spent":"715.963727ms","remote":"127.0.0.1:40690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11811,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T14:49:01.2613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:49:00.542621Z","time spent":"718.499991ms","remote":"127.0.0.1:40772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T14:49:01.261071Z","caller":"traceutil/trace.go:171","msg":"trace[925286549] linearizableReadLoop","detail":"{readStateIndex:1686; appliedIndex:1683; }","duration":"721.226939ms","start":"2026-04-23T14:49:00.539834Z","end":"2026-04-23T14:49:01.261061Z","steps":["trace[925286549] 'read index received' (duration: 334.558039ms)","trace[925286549] 'applied index is now lower than readState.Index' (duration: 386.66833ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T14:49:01.26159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.678118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-webhook\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T14:49:01.261633Z","caller":"traceutil/trace.go:171","msg":"trace[1237309712] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-webhook; range_end:; response_count:0; response_revision:1616; }","duration":"721.756659ms","start":"2026-04-23T14:49:00.539863Z","end":"2026-04-23T14:49:01.26162Z","steps":["trace[1237309712] 'agreement among raft nodes before linearized reading' (duration: 721.696758ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:49:01.261666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:49:00.539845Z","time spent":"721.814041ms","remote":"127.0.0.1:40700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-webhook\" "} {"level":"warn","ts":"2026-04-23T14:49:01.261679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.907206ms","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-23T14:49:01.261707Z","caller":"traceutil/trace.go:171","msg":"trace[1989915337] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1616; }","duration":"556.047319ms","start":"2026-04-23T14:49:00.705651Z","end":"2026-04-23T14:49:01.261698Z","steps":["trace[1989915337] 'agreement among raft nodes before linearized reading' (duration: 555.994608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:49:01.261737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:49:00.705629Z","time spent":"556.1023ms","remote":"127.0.0.1:52074","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":"warn","ts":"2026-04-23T14:49:01.261808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.459546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:1 size:10243"} {"level":"warn","ts":"2026-04-23T14:49:01.26186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.440448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T14:49:01.261914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.779514ms","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-23T14:49:01.261912Z","caller":"traceutil/trace.go:171","msg":"trace[16621751] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1616; }","duration":"717.50554ms","start":"2026-04-23T14:49:00.544393Z","end":"2026-04-23T14:49:01.261899Z","steps":["trace[16621751] 'agreement among raft nodes before linearized reading' (duration: 717.434119ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:49:01.261938Z","caller":"traceutil/trace.go:171","msg":"trace[2080580699] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1616; }","duration":"540.847696ms","start":"2026-04-23T14:49:00.721085Z","end":"2026-04-23T14:49:01.261933Z","steps":["trace[2080580699] 'agreement among raft nodes before linearized reading' (duration: 540.756774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:49:01.261957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:49:00.721059Z","time spent":"540.894426ms","remote":"127.0.0.1:40772","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":"info","ts":"2026-04-23T14:49:01.261872Z","caller":"traceutil/trace.go:171","msg":"trace[706337650] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:1; response_revision:1616; }","duration":"715.527187ms","start":"2026-04-23T14:49:00.546328Z","end":"2026-04-23T14:49:01.261855Z","steps":["trace[706337650] 'agreement among raft nodes before linearized reading' (duration: 715.326573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:49:01.261992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:49:00.546306Z","time spent":"715.676771ms","remote":"127.0.0.1:40592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":10266,"request content":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" "} {"level":"warn","ts":"2026-04-23T14:49:01.26195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:49:00.544385Z","time spent":"717.557761ms","remote":"127.0.0.1:37764","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-23T14:49:01.262237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.051786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/ingress-nginx/ingress-nginx-udp\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T14:49:01.262276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.283772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:121214"} {"level":"info","ts":"2026-04-23T14:49:01.262294Z","caller":"traceutil/trace.go:171","msg":"trace[1736439139] range","detail":"{range_begin:/registry/configmaps/ingress-nginx/ingress-nginx-udp; range_end:; response_count:0; response_revision:1616; }","duration":"722.121268ms","start":"2026-04-23T14:49:00.54016Z","end":"2026-04-23T14:49:01.262281Z","steps":["trace[1736439139] 'agreement among raft nodes before linearized reading' (duration: 722.040115ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:49:01.262343Z","caller":"traceutil/trace.go:171","msg":"trace[1989696205] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1616; }","duration":"714.363303ms","start":"2026-04-23T14:49:00.547963Z","end":"2026-04-23T14:49:01.262326Z","steps":["trace[1989696205] 'agreement among raft nodes before linearized reading' (duration: 713.934313ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:49:01.262395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:49:00.547957Z","time spent":"714.426414ms","remote":"127.0.0.1:40542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":121237,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-04-23T14:49:01.262348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:49:00.540153Z","time spent":"722.184819ms","remote":"127.0.0.1:40598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/configmaps/ingress-nginx/ingress-nginx-udp\" "} {"level":"warn","ts":"2026-04-23T14:49:01.675765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.030911ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-04-23T14:49:01.675887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:49:01.263299Z","time spent":"412.583311ms","remote":"127.0.0.1:40516","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-23T14:49:01.69598Z","caller":"traceutil/trace.go:171","msg":"trace[1300628359] linearizableReadLoop","detail":"{readStateIndex:1690; appliedIndex:1688; }","duration":"429.231137ms","start":"2026-04-23T14:49:01.266727Z","end":"2026-04-23T14:49:01.695958Z","steps":["trace[1300628359] 'read index received' (duration: 73.044657ms)","trace[1300628359] 'applied index is now lower than readState.Index' (duration: 356.18554ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T14:49:01.695987Z","caller":"traceutil/trace.go:171","msg":"trace[1680620883] transaction","detail":"{read_only:false; response_revision:1617; number_of_response:1; }","duration":"430.459843ms","start":"2026-04-23T14:49:01.265483Z","end":"2026-04-23T14:49:01.695943Z","steps":["trace[1680620883] 'process raft request' (duration: 430.152146ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:49:01.69608Z","caller":"traceutil/trace.go:171","msg":"trace[873537666] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1618; }","duration":"408.537795ms","start":"2026-04-23T14:49:01.287498Z","end":"2026-04-23T14:49:01.696036Z","steps":["trace[873537666] 'process raft request' (duration: 408.388452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:49:01.696128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.38318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/rook-ceph/rook-ceph-rgw\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T14:49:01.696111Z","caller":"traceutil/trace.go:171","msg":"trace[1588817652] transaction","detail":"{read_only:false; response_revision:1618; number_of_response:1; }","duration":"428.503671ms","start":"2026-04-23T14:49:01.267571Z","end":"2026-04-23T14:49:01.696074Z","steps":["trace[1588817652] 'process raft request' (duration: 428.254076ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:49:01.696179Z","caller":"traceutil/trace.go:171","msg":"trace[2002921313] range","detail":"{range_begin:/registry/serviceaccounts/rook-ceph/rook-ceph-rgw; range_end:; response_count:0; response_revision:1618; }","duration":"429.481771ms","start":"2026-04-23T14:49:01.266688Z","end":"2026-04-23T14:49:01.696169Z","steps":["trace[2002921313] 'agreement among raft nodes before linearized reading' (duration: 429.39337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:49:01.696194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:49:01.265449Z","time spent":"430.653066ms","remote":"127.0.0.1:40542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121200,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T14:49:01.696215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:49:01.266669Z","time spent":"429.538113ms","remote":"127.0.0.1:40700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/rook-ceph/rook-ceph-rgw\" "} {"level":"info","ts":"2026-04-23T14:49:01.696117Z","caller":"traceutil/trace.go:171","msg":"trace[648563326] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1618; }","duration":"405.628033ms","start":"2026-04-23T14:49:01.290472Z","end":"2026-04-23T14:49:01.6961Z","steps":["trace[648563326] 'process raft request' (duration: 405.452899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:49:01.69626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:49:01.287483Z","time spent":"408.684348ms","remote":"127.0.0.1:40536","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T14:49:01.696305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:49:01.267552Z","time spent":"428.641884ms","remote":"127.0.0.1:40772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T14:49:01.696314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:49:01.290465Z","time spent":"405.823397ms","remote":"127.0.0.1:40536","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T14:49:02.940991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.211842ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T14:49:02.941172Z","caller":"traceutil/trace.go:171","msg":"trace[648513885] transaction","detail":"{read_only:false; response_revision:1622; number_of_response:1; }","duration":"622.142228ms","start":"2026-04-23T14:49:02.319015Z","end":"2026-04-23T14:49:02.941158Z","steps":["trace[648513885] 'process raft request' (duration: 622.108047ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:49:02.941218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:49:02.318982Z","time spent":"622.219809ms","remote":"127.0.0.1:40564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T14:49:02.941328Z","caller":"traceutil/trace.go:171","msg":"trace[1488058941] transaction","detail":"{read_only:false; response_revision:1620; number_of_response:1; }","duration":"1.243735724s","start":"2026-04-23T14:49:01.69758Z","end":"2026-04-23T14:49:02.941315Z","steps":["trace[1488058941] 'process raft request' (duration: 1.243481999s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:49:02.941376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:49:01.697556Z","time spent":"1.243800015s","remote":"127.0.0.1:40698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":746,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T14:49:02.941443Z","caller":"traceutil/trace.go:171","msg":"trace[2064713377] transaction","detail":"{read_only:false; response_revision:1621; number_of_response:1; }","duration":"1.219209481s","start":"2026-04-23T14:49:01.722225Z","end":"2026-04-23T14:49:02.941435Z","steps":["trace[2064713377] 'process raft request' (duration: 1.218875324s)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:49:02.941483Z","caller":"traceutil/trace.go:171","msg":"trace[515245735] transaction","detail":"{read_only:false; response_revision:1619; number_of_response:1; }","duration":"1.264428475s","start":"2026-04-23T14:49:01.677047Z","end":"2026-04-23T14:49:02.941475Z","steps":["trace[515245735] 'process raft request' (duration: 326.675301ms)","trace[515245735] 'compare' (duration: 936.908446ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T14:49:02.941484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:49:01.722196Z","time spent":"1.219264492s","remote":"127.0.0.1:40772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T14:49:02.941514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:49:01.677026Z","time spent":"1.264469496s","remote":"127.0.0.1:40516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T14:49:02.941604Z","caller":"traceutil/trace.go:171","msg":"trace[564341257] linearizableReadLoop","detail":"{readStateIndex:1694; appliedIndex:1693; }","duration":"1.245545233s","start":"2026-04-23T14:49:01.696052Z","end":"2026-04-23T14:49:02.941597Z","steps":["trace[564341257] 'read index received' (duration: 307.681147ms)","trace[564341257] 'applied index is now lower than readState.Index' (duration: 937.862736ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T14:49:02.941782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.244187784s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/nodefeaturerules.nfd.k8s-sigs.io\" ","response":"range_response_count:1 size:10381"} {"level":"warn","ts":"2026-04-23T14:49:02.941774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.673121364s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T14:49:02.941815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"986.645286ms","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-23T14:49:02.94183Z","caller":"traceutil/trace.go:171","msg":"trace[606596731] range","detail":"{range_begin:/registry/configmaps/ingress-nginx/ingress-nginx-controller; range_end:; response_count:0; response_revision:1622; }","duration":"1.673175645s","start":"2026-04-23T14:49:01.268638Z","end":"2026-04-23T14:49:02.941813Z","steps":["trace[606596731] 'agreement among raft nodes before linearized reading' (duration: 1.673096783s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:49:02.941808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.673112564s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/cert-manager/cert-manager-webhook\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T14:49:02.941823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.672191965s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T14:49:02.941873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:49:01.268616Z","time spent":"1.673250687s","remote":"127.0.0.1:40598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":28,"request content":"key:\"/registry/configmaps/ingress-nginx/ingress-nginx-controller\" "} {"level":"warn","ts":"2026-04-23T14:49:02.941876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.244273206s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/nodefeatures.nfd.k8s-sigs.io\" ","response":"range_response_count:1 size:4678"} {"level":"info","ts":"2026-04-23T14:49:02.941909Z","caller":"traceutil/trace.go:171","msg":"trace[457769919] range","detail":"{range_begin:/registry/configmaps/cert-manager/cert-manager-webhook; range_end:; response_count:0; response_revision:1622; }","duration":"1.673226086s","start":"2026-04-23T14:49:01.268653Z","end":"2026-04-23T14:49:02.941879Z","steps":["trace[457769919] 'agreement among raft nodes before linearized reading' (duration: 1.673025092s)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:49:02.941925Z","caller":"traceutil/trace.go:171","msg":"trace[594497549] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1622; }","duration":"1.672332708s","start":"2026-04-23T14:49:01.269571Z","end":"2026-04-23T14:49:02.941903Z","steps":["trace[594497549] 'agreement among raft nodes before linearized reading' (duration: 1.672132523s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:49:02.941954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:49:01.268649Z","time spent":"1.673296387s","remote":"127.0.0.1:40598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/configmaps/cert-manager/cert-manager-webhook\" "} {"level":"warn","ts":"2026-04-23T14:49:02.941978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:49:01.269564Z","time spent":"1.672405209s","remote":"127.0.0.1:37756","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-23T14:49:02.941837Z","caller":"traceutil/trace.go:171","msg":"trace[743894311] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1622; }","duration":"986.710987ms","start":"2026-04-23T14:49:01.95512Z","end":"2026-04-23T14:49:02.941831Z","steps":["trace[743894311] 'agreement among raft nodes before linearized reading' (duration: 986.657716ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:49:02.941975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.67432178s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/goldpinger-6fkjc\" ","response":"range_response_count:1 size:4991"} {"level":"info","ts":"2026-04-23T14:49:02.941988Z","caller":"traceutil/trace.go:171","msg":"trace[453483165] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/nodefeatures.nfd.k8s-sigs.io; range_end:; response_count:1; response_revision:1622; }","duration":"1.244359957s","start":"2026-04-23T14:49:01.697576Z","end":"2026-04-23T14:49:02.941936Z","steps":["trace[453483165] 'agreement among raft nodes before linearized reading' (duration: 1.244252495s)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:49:02.942058Z","caller":"traceutil/trace.go:171","msg":"trace[1404989552] range","detail":"{range_begin:/registry/pods/monitoring/goldpinger-6fkjc; range_end:; response_count:1; response_revision:1622; }","duration":"1.674458903s","start":"2026-04-23T14:49:01.267578Z","end":"2026-04-23T14:49:02.942037Z","steps":["trace[1404989552] 'agreement among raft nodes before linearized reading' (duration: 1.674199068s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:49:02.942066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:49:01.955088Z","time spent":"986.951841ms","remote":"127.0.0.1:52070","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":"warn","ts":"2026-04-23T14:49:02.941808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.240627698s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/rook-ceph/rook-ceph-default\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T14:49:02.942107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:49:01.267547Z","time spent":"1.674548335s","remote":"127.0.0.1:40690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":5014,"request content":"key:\"/registry/pods/monitoring/goldpinger-6fkjc\" "} {"level":"info","ts":"2026-04-23T14:49:02.941817Z","caller":"traceutil/trace.go:171","msg":"trace[1840038248] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/nodefeaturerules.nfd.k8s-sigs.io; range_end:; response_count:1; response_revision:1622; }","duration":"1.244259396s","start":"2026-04-23T14:49:01.697547Z","end":"2026-04-23T14:49:02.941807Z","steps":["trace[1840038248] 'agreement among raft nodes before linearized reading' (duration: 1.244159963s)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:49:02.942195Z","caller":"traceutil/trace.go:171","msg":"trace[2052105292] range","detail":"{range_begin:/registry/serviceaccounts/rook-ceph/rook-ceph-default; range_end:; response_count:0; response_revision:1622; }","duration":"1.241014136s","start":"2026-04-23T14:49:01.701123Z","end":"2026-04-23T14:49:02.942137Z","steps":["trace[2052105292] 'agreement among raft nodes before linearized reading' (duration: 1.240608398s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:49:02.942234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:49:01.697527Z","time spent":"1.244696514s","remote":"127.0.0.1:40536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":91,"response count":1,"response size":10404,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/nodefeaturerules.nfd.k8s-sigs.io\" "} {"level":"warn","ts":"2026-04-23T14:49:02.942292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:49:01.701107Z","time spent":"1.241160729s","remote":"127.0.0.1:40700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/rook-ceph/rook-ceph-default\" "} {"level":"warn","ts":"2026-04-23T14:49:02.943162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:49:01.697565Z","time spent":"1.245577044s","remote":"127.0.0.1:40536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":1,"response size":4701,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/nodefeatures.nfd.k8s-sigs.io\" "} {"level":"warn","ts":"2026-04-23T14:49:03.441676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.11523ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T14:49:03.441778Z","caller":"traceutil/trace.go:171","msg":"trace[991701185] linearizableReadLoop","detail":"{readStateIndex:1698; appliedIndex:1697; }","duration":"491.79572ms","start":"2026-04-23T14:49:02.949966Z","end":"2026-04-23T14:49:03.441762Z","steps":["trace[991701185] 'read index received' (duration: 187.592427ms)","trace[991701185] 'applied index is now lower than readState.Index' (duration: 304.201713ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T14:49:03.441847Z","caller":"traceutil/trace.go:171","msg":"trace[1905027030] transaction","detail":"{read_only:false; response_revision:1623; number_of_response:1; }","duration":"493.608049ms","start":"2026-04-23T14:49:02.948208Z","end":"2026-04-23T14:49:03.441816Z","steps":["trace[1905027030] 'process raft request' (duration: 189.270814ms)","trace[1905027030] 'compare' (duration: 303.849214ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T14:49:03.44196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.987725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-23T14:49:03.441997Z","caller":"traceutil/trace.go:171","msg":"trace[180077413] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:1623; }","duration":"492.039916ms","start":"2026-04-23T14:49:02.949947Z","end":"2026-04-23T14:49:03.441987Z","steps":["trace[180077413] 'agreement among raft nodes before linearized reading' (duration: 491.895253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:49:03.442006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:49:02.948192Z","time spent":"493.741662ms","remote":"127.0.0.1:40772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T14:49:03.442035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:49:02.949939Z","time spent":"492.088217ms","remote":"127.0.0.1:40516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-04-23T14:49:03.46219Z","caller":"traceutil/trace.go:171","msg":"trace[1868379594] transaction","detail":"{read_only:false; response_revision:1624; number_of_response:1; }","duration":"509.771884ms","start":"2026-04-23T14:49:02.952392Z","end":"2026-04-23T14:49:03.462164Z","steps":["trace[1868379594] 'process raft request' (duration: 509.572979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:49:03.46232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:49:02.952382Z","time spent":"509.877556ms","remote":"127.0.0.1:40690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4982,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T14:49:03.462338Z","caller":"traceutil/trace.go:171","msg":"trace[609328039] transaction","detail":"{read_only:false; response_revision:1626; number_of_response:1; }","duration":"507.798331ms","start":"2026-04-23T14:49:02.95452Z","end":"2026-04-23T14:49:03.462318Z","steps":["trace[609328039] 'process raft request' (duration: 507.711959ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:49:03.462364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.625878ms","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-23T14:49:03.462419Z","caller":"traceutil/trace.go:171","msg":"trace[1401208775] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1626; }","duration":"271.724361ms","start":"2026-04-23T14:49:03.190681Z","end":"2026-04-23T14:49:03.462406Z","steps":["trace[1401208775] 'agreement among raft nodes before linearized reading' (duration: 271.618708ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:49:03.462422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:49:02.954514Z","time spent":"507.867842ms","remote":"127.0.0.1:40774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1005,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T14:49:03.46247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.898584ms","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-23T14:49:03.462489Z","caller":"traceutil/trace.go:171","msg":"trace[1098517734] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1626; }","duration":"194.917604ms","start":"2026-04-23T14:49:03.267565Z","end":"2026-04-23T14:49:03.462483Z","steps":["trace[1098517734] 'agreement among raft nodes before linearized reading' (duration: 194.833912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:49:03.462556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.193979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T14:49:03.462571Z","caller":"traceutil/trace.go:171","msg":"trace[1688654896] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1626; }","duration":"507.240579ms","start":"2026-04-23T14:49:02.955326Z","end":"2026-04-23T14:49:03.462567Z","steps":["trace[1688654896] 'agreement among raft nodes before linearized reading' (duration: 507.214938ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:49:03.462588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:49:02.955301Z","time spent":"507.28454ms","remote":"127.0.0.1:37756","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-23T14:49:03.4626Z","caller":"traceutil/trace.go:171","msg":"trace[873079865] transaction","detail":"{read_only:false; response_revision:1625; number_of_response:1; }","duration":"508.968056ms","start":"2026-04-23T14:49:02.953622Z","end":"2026-04-23T14:49:03.46259Z","steps":["trace[873079865] 'process raft request' (duration: 508.457746ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:49:03.46265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.396007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/rook-ceph/rook-csi-cephfs-plugin-sa\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T14:49:03.462651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.051029ms","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-23T14:49:03.46252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.275114ms","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-23T14:49:03.462682Z","caller":"traceutil/trace.go:171","msg":"trace[2128855124] range","detail":"{range_begin:/registry/serviceaccounts/rook-ceph/rook-csi-cephfs-plugin-sa; range_end:; response_count:0; response_revision:1626; }","duration":"510.431407ms","start":"2026-04-23T14:49:02.952231Z","end":"2026-04-23T14:49:03.462662Z","steps":["trace[2128855124] 'agreement among raft nodes before linearized reading' (duration: 510.401436ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:49:03.4627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:49:02.952224Z","time spent":"510.472238ms","remote":"127.0.0.1:40700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/rook-ceph/rook-csi-cephfs-plugin-sa\" "} {"level":"info","ts":"2026-04-23T14:49:03.462697Z","caller":"traceutil/trace.go:171","msg":"trace[253549094] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1626; }","duration":"197.50216ms","start":"2026-04-23T14:49:03.265188Z","end":"2026-04-23T14:49:03.462691Z","steps":["trace[253549094] 'agreement among raft nodes before linearized reading' (duration: 197.099861ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:49:03.462696Z","caller":"traceutil/trace.go:171","msg":"trace[2049049504] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1626; }","duration":"197.11808ms","start":"2026-04-23T14:49:03.265562Z","end":"2026-04-23T14:49:03.46268Z","steps":["trace[2049049504] 'agreement among raft nodes before linearized reading' (duration: 197.020818ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:49:03.462781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.212222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/certificates.cert-manager.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T14:49:03.462773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.298427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/ingress-nginx\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T14:49:03.462808Z","caller":"traceutil/trace.go:171","msg":"trace[741916041] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/certificates.cert-manager.io; range_end:; response_count:0; response_revision:1626; }","duration":"510.242213ms","start":"2026-04-23T14:49:02.952553Z","end":"2026-04-23T14:49:03.462795Z","steps":["trace[741916041] 'agreement among raft nodes before linearized reading' (duration: 510.210232ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:49:03.462812Z","caller":"traceutil/trace.go:171","msg":"trace[1160511141] range","detail":"{range_begin:/registry/clusterrolebindings/ingress-nginx; range_end:; response_count:0; response_revision:1626; }","duration":"512.354358ms","start":"2026-04-23T14:49:02.950446Z","end":"2026-04-23T14:49:03.4628Z","steps":["trace[1160511141] 'agreement among raft nodes before linearized reading' (duration: 512.292017ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:49:03.462839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:49:02.952549Z","time spent":"510.281915ms","remote":"127.0.0.1:40536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/certificates.cert-manager.io\" "} {"level":"warn","ts":"2026-04-23T14:49:03.462842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:49:02.95044Z","time spent":"512.39552ms","remote":"127.0.0.1:40836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/clusterrolebindings/ingress-nginx\" "} {"level":"warn","ts":"2026-04-23T14:49:03.462672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T14:49:02.953613Z","time spent":"509.036848ms","remote":"127.0.0.1:40680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":524,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T14:49:35.558145Z","caller":"traceutil/trace.go:171","msg":"trace[1844914953] transaction","detail":"{read_only:false; response_revision:2343; number_of_response:1; }","duration":"120.806564ms","start":"2026-04-23T14:49:35.437302Z","end":"2026-04-23T14:49:35.558108Z","steps":["trace[1844914953] 'process raft request' (duration: 120.520228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:49:41.333819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.36193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.70\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-23T14:49:41.333931Z","caller":"traceutil/trace.go:171","msg":"trace[25433991] range","detail":"{range_begin:/registry/masterleases/199.204.45.70; range_end:; response_count:1; response_revision:2364; }","duration":"132.546885ms","start":"2026-04-23T14:49:41.201357Z","end":"2026-04-23T14:49:41.333903Z","steps":["trace[25433991] 'range keys from in-memory index tree' (duration: 132.117915ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:49:41.543266Z","caller":"traceutil/trace.go:171","msg":"trace[275905166] linearizableReadLoop","detail":"{readStateIndex:2450; appliedIndex:2449; }","duration":"142.052427ms","start":"2026-04-23T14:49:41.40117Z","end":"2026-04-23T14:49:41.543223Z","steps":["trace[275905166] 'read index received' (duration: 141.828032ms)","trace[275905166] 'applied index is now lower than readState.Index' (duration: 223.015µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T14:49:41.543452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.265602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T14:49:41.543521Z","caller":"traceutil/trace.go:171","msg":"trace[1727516895] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2364; }","duration":"142.403035ms","start":"2026-04-23T14:49:41.401098Z","end":"2026-04-23T14:49:41.543501Z","steps":["trace[1727516895] 'agreement among raft nodes before linearized reading' (duration: 142.273813ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:49:41.741453Z","caller":"traceutil/trace.go:171","msg":"trace[727823271] linearizableReadLoop","detail":"{readStateIndex:2451; appliedIndex:2450; }","duration":"104.20638ms","start":"2026-04-23T14:49:41.637221Z","end":"2026-04-23T14:49:41.741428Z","steps":["trace[727823271] 'read index received' (duration: 98.550379ms)","trace[727823271] 'applied index is now lower than readState.Index' (duration: 5.654961ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T14:49:41.741455Z","caller":"traceutil/trace.go:171","msg":"trace[2026214574] transaction","detail":"{read_only:false; response_revision:2365; number_of_response:1; }","duration":"197.027188ms","start":"2026-04-23T14:49:41.544401Z","end":"2026-04-23T14:49:41.741428Z","steps":["trace[2026214574] 'process raft request' (duration: 191.419738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:49:41.741829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.598949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-23T14:49:41.741875Z","caller":"traceutil/trace.go:171","msg":"trace[1167474361] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2365; }","duration":"104.69777ms","start":"2026-04-23T14:49:41.637169Z","end":"2026-04-23T14:49:41.741867Z","steps":["trace[1167474361] 'agreement among raft nodes before linearized reading' (duration: 104.340123ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:50:03.999627Z","caller":"traceutil/trace.go:171","msg":"trace[1291728311] transaction","detail":"{read_only:false; response_revision:2444; number_of_response:1; }","duration":"125.352973ms","start":"2026-04-23T14:50:03.874256Z","end":"2026-04-23T14:50:03.999609Z","steps":["trace[1291728311] 'process raft request' (duration: 124.535524ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:50:03.999589Z","caller":"traceutil/trace.go:171","msg":"trace[927861316] linearizableReadLoop","detail":"{readStateIndex:2534; appliedIndex:2533; }","duration":"124.836692ms","start":"2026-04-23T14:50:03.874721Z","end":"2026-04-23T14:50:03.999558Z","steps":["trace[927861316] 'read index received' (duration: 123.993773ms)","trace[927861316] 'applied index is now lower than readState.Index' (duration: 841.549µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T14:50:03.999818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.071107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-23T14:50:03.999885Z","caller":"traceutil/trace.go:171","msg":"trace[1126197557] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2444; }","duration":"125.201479ms","start":"2026-04-23T14:50:03.874669Z","end":"2026-04-23T14:50:03.99987Z","steps":["trace[1126197557] 'agreement among raft nodes before linearized reading' (duration: 125.002035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:50:03.9999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.374101ms","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-23T14:50:03.999922Z","caller":"traceutil/trace.go:171","msg":"trace[24456291] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2444; }","duration":"124.394932ms","start":"2026-04-23T14:50:03.875521Z","end":"2026-04-23T14:50:03.999916Z","steps":["trace[24456291] 'agreement among raft nodes before linearized reading' (duration: 124.344521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T14:50:03.999842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.971335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-23T14:50:04.000001Z","caller":"traceutil/trace.go:171","msg":"trace[1324990494] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2444; }","duration":"125.155238ms","start":"2026-04-23T14:50:03.87484Z","end":"2026-04-23T14:50:03.999995Z","steps":["trace[1324990494] 'agreement among raft nodes before linearized reading' (duration: 124.944613ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:50:04.119727Z","caller":"traceutil/trace.go:171","msg":"trace[1720332730] transaction","detail":"{read_only:false; response_revision:2445; number_of_response:1; }","duration":"115.818935ms","start":"2026-04-23T14:50:04.003776Z","end":"2026-04-23T14:50:04.119595Z","steps":["trace[1720332730] 'process raft request' (duration: 106.148373ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:50:04.12675Z","caller":"traceutil/trace.go:171","msg":"trace[497010350] transaction","detail":"{read_only:false; response_revision:2446; number_of_response:1; }","duration":"122.388018ms","start":"2026-04-23T14:50:04.004327Z","end":"2026-04-23T14:50:04.126715Z","steps":["trace[497010350] 'process raft request' (duration: 122.179454ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:50:04.12688Z","caller":"traceutil/trace.go:171","msg":"trace[374542542] transaction","detail":"{read_only:false; response_revision:2447; number_of_response:1; }","duration":"122.315135ms","start":"2026-04-23T14:50:04.004532Z","end":"2026-04-23T14:50:04.126847Z","steps":["trace[374542542] 'process raft request' (duration: 122.06966ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T14:54:48.0699Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2391} {"level":"info","ts":"2026-04-23T14:54:48.143072Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2391,"took":"71.892839ms","hash":3027286276,"current-db-size-bytes":36212736,"current-db-size":"36 MB","current-db-size-in-use-bytes":13869056,"current-db-size-in-use":"14 MB"} {"level":"info","ts":"2026-04-23T14:54:48.143126Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3027286276,"revision":2391,"compact-revision":-1} {"level":"info","ts":"2026-04-23T14:59:48.076341Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3873} {"level":"info","ts":"2026-04-23T14:59:48.118639Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3873,"took":"40.803065ms","hash":930038818,"current-db-size-bytes":36212736,"current-db-size":"36 MB","current-db-size-in-use-bytes":18579456,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-04-23T14:59:48.118705Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":930038818,"revision":3873,"compact-revision":2391} {"level":"info","ts":"2026-04-23T15:03:00.542452Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002312.wal"} {"level":"info","ts":"2026-04-23T15:03:36.980974Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f8844a9a4263041d","local-member-applied-index":10003,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-23T15:03:36.986206Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10003} {"level":"info","ts":"2026-04-23T15:03:36.986353Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5003} {"level":"warn","ts":"2026-04-23T15:04:36.022086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.93358ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-23T15:04:36.022329Z","caller":"traceutil/trace.go:171","msg":"trace[150246396] transaction","detail":"{read_only:false; response_revision:10716; number_of_response:1; }","duration":"216.716099ms","start":"2026-04-23T15:04:35.805577Z","end":"2026-04-23T15:04:36.022294Z","steps":["trace[150246396] 'process raft request' (duration: 56.460312ms)","trace[150246396] 'compare' (duration: 159.696025ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T15:04:36.043083Z","caller":"traceutil/trace.go:171","msg":"trace[1200283989] transaction","detail":"{read_only:false; response_revision:10717; number_of_response:1; }","duration":"234.360913ms","start":"2026-04-23T15:04:35.808683Z","end":"2026-04-23T15:04:36.043044Z","steps":["trace[1200283989] 'process raft request' (duration: 234.069676ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T15:04:42.921651Z","caller":"traceutil/trace.go:171","msg":"trace[1360155723] linearizableReadLoop","detail":"{readStateIndex:11261; appliedIndex:11260; }","duration":"101.104117ms","start":"2026-04-23T15:04:42.820525Z","end":"2026-04-23T15:04:42.921629Z","steps":["trace[1360155723] 'read index received' (duration: 100.933313ms)","trace[1360155723] 'applied index is now lower than readState.Index' (duration: 169.814µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T15:04:42.921764Z","caller":"traceutil/trace.go:171","msg":"trace[643043649] transaction","detail":"{read_only:false; response_revision:10888; number_of_response:1; }","duration":"111.603719ms","start":"2026-04-23T15:04:42.810153Z","end":"2026-04-23T15:04:42.921757Z","steps":["trace[643043649] 'process raft request' (duration: 111.291693ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:04:42.921912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.372542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-z7mpr\" ","response":"range_response_count:1 size:20664"} {"level":"info","ts":"2026-04-23T15:04:42.921933Z","caller":"traceutil/trace.go:171","msg":"trace[1402739641] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-z7mpr; range_end:; response_count:1; response_revision:10888; }","duration":"101.438824ms","start":"2026-04-23T15:04:42.820488Z","end":"2026-04-23T15:04:42.921927Z","steps":["trace[1402739641] 'agreement among raft nodes before linearized reading' (duration: 101.372422ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T15:04:42.959714Z","caller":"traceutil/trace.go:171","msg":"trace[943103558] transaction","detail":"{read_only:false; response_revision:10889; number_of_response:1; }","duration":"126.717168ms","start":"2026-04-23T15:04:42.83297Z","end":"2026-04-23T15:04:42.959687Z","steps":["trace[943103558] 'process raft request' (duration: 126.410221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:04:42.959947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.711115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T15:04:42.960074Z","caller":"traceutil/trace.go:171","msg":"trace[1885109770] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:10889; }","duration":"106.910729ms","start":"2026-04-23T15:04:42.85315Z","end":"2026-04-23T15:04:42.960061Z","steps":["trace[1885109770] 'agreement among raft nodes before linearized reading' (duration: 106.742475ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:04:42.960227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.461343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T15:04:42.960296Z","caller":"traceutil/trace.go:171","msg":"trace[621287326] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:10889; }","duration":"109.514944ms","start":"2026-04-23T15:04:42.850727Z","end":"2026-04-23T15:04:42.960242Z","steps":["trace[621287326] 'agreement among raft nodes before linearized reading' (duration: 109.474214ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T15:04:48.087767Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6323} {"level":"info","ts":"2026-04-23T15:04:48.149951Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6323,"took":"60.202422ms","hash":2546879372,"current-db-size-bytes":52527104,"current-db-size":"52 MB","current-db-size-in-use-bytes":46641152,"current-db-size-in-use":"47 MB"} {"level":"info","ts":"2026-04-23T15:04:48.15001Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2546879372,"revision":6323,"compact-revision":3873} {"level":"info","ts":"2026-04-23T15:09:48.094791Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10930} {"level":"info","ts":"2026-04-23T15:09:48.227286Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10930,"took":"128.980582ms","hash":2389763083,"current-db-size-bytes":56668160,"current-db-size":"57 MB","current-db-size-in-use-bytes":29110272,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-23T15:09:48.227337Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2389763083,"revision":10930,"compact-revision":6323}