{"level":"warn","ts":"2026-02-06T00:44:25.243993Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-06T00:44:25.244073Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.69: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.69:2380","--initial-cluster=instance=https://199.204.45.69:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.69:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.69: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-02-06T00:44:25.244134Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-06T00:44:25.244142Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.69:2380"]} {"level":"info","ts":"2026-02-06T00:44:25.244157Z","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-02-06T00:44:25.245142Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.69:2379"]} {"level":"info","ts":"2026-02-06T00:44:25.245211Z","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":2,"max-cpu-available":2,"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.69:2380"],"listen-peer-urls":["https://199.204.45.69:2380"],"advertise-client-urls":["https://199.204.45.69:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.69:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.69: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-02-06T00:44:25.248664Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.282882ms"} {"level":"info","ts":"2026-02-06T00:44:25.270263Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fe334179879fd0aa","cluster-id":"d79a8cccc23ced73"} {"level":"info","ts":"2026-02-06T00:44:25.270335Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa switched to configuration voters=()"} {"level":"info","ts":"2026-02-06T00:44:25.270355Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became follower at term 0"} {"level":"info","ts":"2026-02-06T00:44:25.270365Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fe334179879fd0aa [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-06T00:44:25.270373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became follower at term 1"} {"level":"info","ts":"2026-02-06T00:44:25.270401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa switched to configuration voters=(18317056099668185258)"} {"level":"warn","ts":"2026-02-06T00:44:25.286555Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-06T00:44:25.288142Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-06T00:44:25.289443Z","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-02-06T00:44:25.291536Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fe334179879fd0aa","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-06T00:44:25.301582Z","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-02-06T00:44:25.301662Z","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-02-06T00:44:25.301671Z","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-02-06T00:44:25.302101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa switched to configuration voters=(18317056099668185258)"} {"level":"info","ts":"2026-02-06T00:44:25.302223Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d79a8cccc23ced73","local-member-id":"fe334179879fd0aa","added-peer-id":"fe334179879fd0aa","added-peer-peer-urls":["https://199.204.45.69:2380"]} {"level":"info","ts":"2026-02-06T00:44:25.307794Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fe334179879fd0aa","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-06T00:44:25.3085Z","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-02-06T00:44:25.308695Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fe334179879fd0aa","initial-advertise-peer-urls":["https://199.204.45.69:2380"],"listen-peer-urls":["https://199.204.45.69:2380"],"advertise-client-urls":["https://199.204.45.69:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.69:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-06T00:44:25.308717Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-06T00:44:25.308793Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.69:2380"} {"level":"info","ts":"2026-02-06T00:44:25.308804Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.69:2380"} {"level":"info","ts":"2026-02-06T00:44:26.272395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa is starting a new election at term 1"} {"level":"info","ts":"2026-02-06T00:44:26.27244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became pre-candidate at term 1"} {"level":"info","ts":"2026-02-06T00:44:26.272468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa received MsgPreVoteResp from fe334179879fd0aa at term 1"} {"level":"info","ts":"2026-02-06T00:44:26.272482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became candidate at term 2"} {"level":"info","ts":"2026-02-06T00:44:26.272491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa received MsgVoteResp from fe334179879fd0aa at term 2"} {"level":"info","ts":"2026-02-06T00:44:26.272505Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became leader at term 2"} {"level":"info","ts":"2026-02-06T00:44:26.272512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fe334179879fd0aa elected leader fe334179879fd0aa at term 2"} {"level":"info","ts":"2026-02-06T00:44:26.277043Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T00:44:26.277915Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fe334179879fd0aa","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.69:2379]}","request-path":"/0/members/fe334179879fd0aa/attributes","cluster-id":"d79a8cccc23ced73","publish-timeout":"7s"} {"level":"info","ts":"2026-02-06T00:44:26.278062Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-06T00:44:26.278488Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-06T00:44:26.280594Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-06T00:44:26.280653Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-06T00:44:26.283085Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.69:2379"} {"level":"info","ts":"2026-02-06T00:44:26.284243Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d79a8cccc23ced73","local-member-id":"fe334179879fd0aa","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T00:44:26.284308Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T00:44:26.284326Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T00:44:26.285597Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-06T00:44:29.384434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.780138ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:44:29.384528Z","caller":"traceutil/trace.go:171","msg":"trace[1170953779] transaction","detail":"{read_only:false; response_revision:236; number_of_response:1; }","duration":"230.768605ms","start":"2026-02-06T00:44:29.153749Z","end":"2026-02-06T00:44:29.384517Z","steps":["trace[1170953779] 'compare' (duration: 222.625874ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:44:34.787676Z","caller":"traceutil/trace.go:171","msg":"trace[824780380] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"126.950547ms","start":"2026-02-06T00:44:34.660697Z","end":"2026-02-06T00:44:34.787648Z","steps":["trace[824780380] 'process raft request' (duration: 126.804703ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:44:34.788317Z","caller":"traceutil/trace.go:171","msg":"trace[372440857] linearizableReadLoop","detail":"{readStateIndex:351; appliedIndex:351; }","duration":"110.961238ms","start":"2026-02-06T00:44:34.677339Z","end":"2026-02-06T00:44:34.7883Z","steps":["trace[372440857] 'read index received' (duration: 110.953738ms)","trace[372440857] 'applied index is now lower than readState.Index' (duration: 5.86µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:44:34.788739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.321427ms","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-02-06T00:44:34.789032Z","caller":"traceutil/trace.go:171","msg":"trace[1667466504] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:338; }","duration":"111.715117ms","start":"2026-02-06T00:44:34.677294Z","end":"2026-02-06T00:44:34.789009Z","steps":["trace[1667466504] 'agreement among raft nodes before linearized reading' (duration: 111.249505ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:55.38843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.331055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:44:55.388512Z","caller":"traceutil/trace.go:171","msg":"trace[379662157] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:509; }","duration":"225.458248ms","start":"2026-02-06T00:44:55.163037Z","end":"2026-02-06T00:44:55.388495Z","steps":["trace[379662157] 'range keys from in-memory index tree' (duration: 225.275925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:55.389452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.234899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4061"} {"level":"info","ts":"2026-02-06T00:44:55.389499Z","caller":"traceutil/trace.go:171","msg":"trace[1631996849] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:509; }","duration":"129.308641ms","start":"2026-02-06T00:44:55.260178Z","end":"2026-02-06T00:44:55.389487Z","steps":["trace[1631996849] 'range keys from in-memory index tree' (duration: 128.092834ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:44:56.184297Z","caller":"traceutil/trace.go:171","msg":"trace[1760121829] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"159.916348ms","start":"2026-02-06T00:44:56.024366Z","end":"2026-02-06T00:44:56.184283Z","steps":["trace[1760121829] 'process raft request' (duration: 159.786695ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:56.741168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.88271ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:44:56.741249Z","caller":"traceutil/trace.go:171","msg":"trace[183924841] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:531; }","duration":"442.0797ms","start":"2026-02-06T00:44:56.299157Z","end":"2026-02-06T00:44:56.741237Z","steps":["trace[183924841] 'read index received' (duration: 237.934104ms)","trace[183924841] 'applied index is now lower than readState.Index' (duration: 204.144696ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:44:56.741308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.153902ms","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-02-06T00:44:56.741323Z","caller":"traceutil/trace.go:171","msg":"trace[861499891] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:512; }","duration":"442.204553ms","start":"2026-02-06T00:44:56.299113Z","end":"2026-02-06T00:44:56.741318Z","steps":["trace[861499891] 'agreement among raft nodes before linearized reading' (duration: 442.154622ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:56.741345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:56.2991Z","time spent":"442.240054ms","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-06T00:44:56.741575Z","caller":"traceutil/trace.go:171","msg":"trace[113222624] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"717.194736ms","start":"2026-02-06T00:44:56.024371Z","end":"2026-02-06T00:44:56.741566Z","steps":["trace[113222624] 'process raft request' (duration: 512.769624ms)","trace[113222624] 'compare' (duration: 203.800398ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:44:56.741611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:56.02435Z","time spent":"717.242547ms","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:45:02.063954Z","caller":"traceutil/trace.go:171","msg":"trace[1957194970] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"149.315974ms","start":"2026-02-06T00:45:01.914621Z","end":"2026-02-06T00:45:02.063937Z","steps":["trace[1957194970] 'process raft request' (duration: 149.195561ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:45:02.865709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.553491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:45:02.865757Z","caller":"traceutil/trace.go:171","msg":"trace[1811996669] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:525; }","duration":"701.644553ms","start":"2026-02-06T00:45:02.164101Z","end":"2026-02-06T00:45:02.865746Z","steps":["trace[1811996669] 'range keys from in-memory index tree' (duration: 701.49256ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:45:02.865781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:45:02.164066Z","time spent":"701.707284ms","remote":"127.0.0.1:40012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T00:45:02.865962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.972413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-06T00:45:02.865981Z","caller":"traceutil/trace.go:171","msg":"trace[268316097] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:525; }","duration":"637.018993ms","start":"2026-02-06T00:45:02.228956Z","end":"2026-02-06T00:45:02.865975Z","steps":["trace[268316097] 'range keys from in-memory index tree' (duration: 636.909901ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:45:02.865999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:45:02.228941Z","time spent":"637.051995ms","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-06T00:45:03.014836Z","caller":"traceutil/trace.go:171","msg":"trace[748659272] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"142.105124ms","start":"2026-02-06T00:45:02.872711Z","end":"2026-02-06T00:45:03.014816Z","steps":["trace[748659272] 'process raft request' (duration: 142.041763ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:45:03.015067Z","caller":"traceutil/trace.go:171","msg":"trace[1766607413] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"142.392901ms","start":"2026-02-06T00:45:02.872631Z","end":"2026-02-06T00:45:03.015024Z","steps":["trace[1766607413] 'process raft request' (duration: 139.519997ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:45:03.399494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.681619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-snv2r\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-06T00:45:03.399595Z","caller":"traceutil/trace.go:171","msg":"trace[442196996] range","detail":"{range_begin:/registry/pods/kube-system/cilium-snv2r; range_end:; response_count:1; response_revision:532; }","duration":"176.786951ms","start":"2026-02-06T00:45:03.222772Z","end":"2026-02-06T00:45:03.399559Z","steps":["trace[442196996] 'range keys from in-memory index tree' (duration: 176.595607ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:45:05.6679Z","caller":"traceutil/trace.go:171","msg":"trace[2146143999] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"248.415617ms","start":"2026-02-06T00:45:05.41943Z","end":"2026-02-06T00:45:05.667846Z","steps":["trace[2146143999] 'process raft request' (duration: 184.40005ms)","trace[2146143999] 'compare' (duration: 63.595827ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:45:05.667989Z","caller":"traceutil/trace.go:171","msg":"trace[1744788140] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:574; }","duration":"223.339401ms","start":"2026-02-06T00:45:05.444629Z","end":"2026-02-06T00:45:05.667968Z","steps":["trace[1744788140] 'read index received' (duration: 159.210472ms)","trace[1744788140] 'applied index is now lower than readState.Index' (duration: 64.128039ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:45:05.668106Z","caller":"traceutil/trace.go:171","msg":"trace[267005721] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"248.485028ms","start":"2026-02-06T00:45:05.419609Z","end":"2026-02-06T00:45:05.668094Z","steps":["trace[267005721] 'process raft request' (duration: 247.993087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:45:05.668457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.433766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-06T00:45:05.668488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.838553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:45:05.668498Z","caller":"traceutil/trace.go:171","msg":"trace[1694809587] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:556; }","duration":"220.497797ms","start":"2026-02-06T00:45:05.447987Z","end":"2026-02-06T00:45:05.668485Z","steps":["trace[1694809587] 'agreement among raft nodes before linearized reading' (duration: 220.397275ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:45:05.668548Z","caller":"traceutil/trace.go:171","msg":"trace[1583517465] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:556; }","duration":"223.939164ms","start":"2026-02-06T00:45:05.444592Z","end":"2026-02-06T00:45:05.668531Z","steps":["trace[1583517465] 'agreement among raft nodes before linearized reading' (duration: 223.74278ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:45:05.668643Z","caller":"traceutil/trace.go:171","msg":"trace[478778824] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"221.91204ms","start":"2026-02-06T00:45:05.446722Z","end":"2026-02-06T00:45:05.668634Z","steps":["trace[478778824] 'process raft request' (duration: 221.121193ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:45:05.668821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.960585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:45:05.668843Z","caller":"traceutil/trace.go:171","msg":"trace[1346581501] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"217.43647ms","start":"2026-02-06T00:45:05.451397Z","end":"2026-02-06T00:45:05.668834Z","steps":["trace[1346581501] 'process raft request' (duration: 216.50943ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:45:05.668855Z","caller":"traceutil/trace.go:171","msg":"trace[546379906] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:556; }","duration":"214.999256ms","start":"2026-02-06T00:45:05.453845Z","end":"2026-02-06T00:45:05.668844Z","steps":["trace[546379906] 'agreement among raft nodes before linearized reading' (duration: 214.951895ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:45:05.668962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.113079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:45:05.669015Z","caller":"traceutil/trace.go:171","msg":"trace[2043117437] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:556; }","duration":"215.17467ms","start":"2026-02-06T00:45:05.453832Z","end":"2026-02-06T00:45:05.669007Z","steps":["trace[2043117437] 'agreement among raft nodes before linearized reading' (duration: 215.103838ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:45:05.669095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.282772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:45:05.669128Z","caller":"traceutil/trace.go:171","msg":"trace[2068363053] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:556; }","duration":"215.338453ms","start":"2026-02-06T00:45:05.45378Z","end":"2026-02-06T00:45:05.669118Z","steps":["trace[2068363053] 'agreement among raft nodes before linearized reading' (duration: 215.291242ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:45:05.669232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.39315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:45:05.669259Z","caller":"traceutil/trace.go:171","msg":"trace[1860732188] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:556; }","duration":"217.432041ms","start":"2026-02-06T00:45:05.451818Z","end":"2026-02-06T00:45:05.66925Z","steps":["trace[1860732188] 'agreement among raft nodes before linearized reading' (duration: 217.39265ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:45:06.36796Z","caller":"traceutil/trace.go:171","msg":"trace[311415741] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"138.236058ms","start":"2026-02-06T00:45:06.229702Z","end":"2026-02-06T00:45:06.367938Z","steps":["trace[311415741] 'process raft request' (duration: 137.736517ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:45:06.37451Z","caller":"traceutil/trace.go:171","msg":"trace[877119533] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:584; }","duration":"131.183492ms","start":"2026-02-06T00:45:06.243318Z","end":"2026-02-06T00:45:06.374501Z","steps":["trace[877119533] 'read index received' (duration: 124.379962ms)","trace[877119533] 'applied index is now lower than readState.Index' (duration: 6.80303ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:45:06.374679Z","caller":"traceutil/trace.go:171","msg":"trace[1241504799] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"131.384256ms","start":"2026-02-06T00:45:06.243259Z","end":"2026-02-06T00:45:06.374644Z","steps":["trace[1241504799] 'process raft request' (duration: 131.202772ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:45:06.374803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.471938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-snv2r\" ","response":"range_response_count:1 size:17167"} {"level":"info","ts":"2026-02-06T00:45:06.375191Z","caller":"traceutil/trace.go:171","msg":"trace[1562595182] range","detail":"{range_begin:/registry/pods/kube-system/cilium-snv2r; range_end:; response_count:1; response_revision:563; }","duration":"131.884828ms","start":"2026-02-06T00:45:06.243298Z","end":"2026-02-06T00:45:06.375183Z","steps":["trace[1562595182] 'agreement among raft nodes before linearized reading' (duration: 131.413557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:45:08.49777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.859208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-02-06T00:45:08.49787Z","caller":"traceutil/trace.go:171","msg":"trace[129988713] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:607; }","duration":"103.980911ms","start":"2026-02-06T00:45:08.39387Z","end":"2026-02-06T00:45:08.49785Z","steps":["trace[129988713] 'range keys from in-memory index tree' (duration: 103.753316ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:45:17.986607Z","caller":"traceutil/trace.go:171","msg":"trace[2062051515] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"241.549943ms","start":"2026-02-06T00:45:17.745041Z","end":"2026-02-06T00:45:17.986591Z","steps":["trace[2062051515] 'process raft request' (duration: 241.441181ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:45:18.66313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.060119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-4f9dn\" ","response":"range_response_count:1 size:4823"} {"level":"info","ts":"2026-02-06T00:45:18.663202Z","caller":"traceutil/trace.go:171","msg":"trace[1993071402] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-4f9dn; range_end:; response_count:1; response_revision:709; }","duration":"356.16189ms","start":"2026-02-06T00:45:18.307023Z","end":"2026-02-06T00:45:18.663185Z","steps":["trace[1993071402] 'range keys from in-memory index tree' (duration: 355.912376ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:45:18.663241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:45:18.307008Z","time spent":"356.223212ms","remote":"127.0.0.1:40230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4847,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-4f9dn\" "} {"level":"warn","ts":"2026-02-06T00:45:18.663766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.925782ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:45:18.663829Z","caller":"traceutil/trace.go:171","msg":"trace[2102620156] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"356.765214ms","start":"2026-02-06T00:45:18.307051Z","end":"2026-02-06T00:45:18.663816Z","steps":["trace[2102620156] 'process raft request' (duration: 77.829412ms)","trace[2102620156] 'compare' (duration: 278.603445ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:45:18.663871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:45:18.307033Z","time spent":"356.818215ms","remote":"127.0.0.1:40094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":723,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-06T00:45:19.498296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.4761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:45:19.498381Z","caller":"traceutil/trace.go:171","msg":"trace[32561741] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:710; }","duration":"707.588973ms","start":"2026-02-06T00:45:18.790772Z","end":"2026-02-06T00:45:19.498361Z","steps":["trace[32561741] 'range keys from in-memory index tree' (duration: 707.398429ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:45:19.49842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:45:18.790759Z","time spent":"707.653034ms","remote":"127.0.0.1:40012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T00:45:19.498413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.21983ms","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-02-06T00:45:19.49848Z","caller":"traceutil/trace.go:171","msg":"trace[1373542675] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:710; }","duration":"474.322023ms","start":"2026-02-06T00:45:19.024141Z","end":"2026-02-06T00:45:19.498463Z","steps":["trace[1373542675] 'range keys from in-memory index tree' (duration: 474.028106ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:45:19.49852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:45:19.024121Z","time spent":"474.391145ms","remote":"127.0.0.1:40308","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-02-06T00:45:19.547304Z","caller":"traceutil/trace.go:171","msg":"trace[1003779194] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"500.188045ms","start":"2026-02-06T00:45:19.047099Z","end":"2026-02-06T00:45:19.547287Z","steps":["trace[1003779194] 'process raft request' (duration: 500.059342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:45:19.547403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:45:19.047075Z","time spent":"500.273678ms","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:45:19.547725Z","caller":"traceutil/trace.go:171","msg":"trace[457488950] linearizableReadLoop","detail":"{readStateIndex:737; appliedIndex:737; }","duration":"384.145239ms","start":"2026-02-06T00:45:19.163572Z","end":"2026-02-06T00:45:19.547717Z","steps":["trace[457488950] 'read index received' (duration: 384.141499ms)","trace[457488950] 'applied index is now lower than readState.Index' (duration: 3.05µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:45:19.547778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.20839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:45:19.547798Z","caller":"traceutil/trace.go:171","msg":"trace[1227364280] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:711; }","duration":"384.25051ms","start":"2026-02-06T00:45:19.163541Z","end":"2026-02-06T00:45:19.547791Z","steps":["trace[1227364280] 'agreement among raft nodes before linearized reading' (duration: 384.216879ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:45:19.547815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:45:19.163528Z","time spent":"384.283911ms","remote":"127.0.0.1:40020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T00:45:19.549155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.553904ms","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-02-06T00:45:19.549201Z","caller":"traceutil/trace.go:171","msg":"trace[906118627] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:712; }","duration":"373.612825ms","start":"2026-02-06T00:45:19.175575Z","end":"2026-02-06T00:45:19.549188Z","steps":["trace[906118627] 'agreement among raft nodes before linearized reading' (duration: 373.459941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:45:19.549224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:45:19.17557Z","time spent":"373.649685ms","remote":"127.0.0.1:40308","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-02-06T00:45:19.549371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.393807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-06T00:45:19.549386Z","caller":"traceutil/trace.go:171","msg":"trace[700420671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:712; }","duration":"353.407948ms","start":"2026-02-06T00:45:19.195972Z","end":"2026-02-06T00:45:19.54938Z","steps":["trace[700420671] 'agreement among raft nodes before linearized reading' (duration: 353.366947ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:45:19.549398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:45:19.195938Z","time spent":"353.457649ms","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-06T00:45:19.549513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.669411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-06T00:45:19.54954Z","caller":"traceutil/trace.go:171","msg":"trace[995280574] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:712; }","duration":"371.745193ms","start":"2026-02-06T00:45:19.177787Z","end":"2026-02-06T00:45:19.549532Z","steps":["trace[995280574] 'agreement among raft nodes before linearized reading' (duration: 371.60912ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:45:19.549558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:45:19.177768Z","time spent":"371.786384ms","remote":"127.0.0.1:40308","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-02-06T00:45:28.332901Z","caller":"traceutil/trace.go:171","msg":"trace[240354556] linearizableReadLoop","detail":"{readStateIndex:772; appliedIndex:771; }","duration":"168.449456ms","start":"2026-02-06T00:45:28.164436Z","end":"2026-02-06T00:45:28.332885Z","steps":["trace[240354556] 'read index received' (duration: 168.352414ms)","trace[240354556] 'applied index is now lower than readState.Index' (duration: 96.322µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:45:28.33298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.527848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:45:28.332997Z","caller":"traceutil/trace.go:171","msg":"trace[652923886] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:744; }","duration":"168.585769ms","start":"2026-02-06T00:45:28.164405Z","end":"2026-02-06T00:45:28.332991Z","steps":["trace[652923886] 'agreement among raft nodes before linearized reading' (duration: 168.542708ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:45:28.333051Z","caller":"traceutil/trace.go:171","msg":"trace[1021230562] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"262.074609ms","start":"2026-02-06T00:45:28.070958Z","end":"2026-02-06T00:45:28.333033Z","steps":["trace[1021230562] 'process raft request' (duration: 261.840393ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:45:31.867916Z","caller":"traceutil/trace.go:171","msg":"trace[1926526338] linearizableReadLoop","detail":"{readStateIndex:781; appliedIndex:780; }","duration":"173.626751ms","start":"2026-02-06T00:45:31.694271Z","end":"2026-02-06T00:45:31.867898Z","steps":["trace[1926526338] 'read index received' (duration: 173.434297ms)","trace[1926526338] 'applied index is now lower than readState.Index' (duration: 191.744µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:45:31.868142Z","caller":"traceutil/trace.go:171","msg":"trace[250301295] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"218.840582ms","start":"2026-02-06T00:45:31.649293Z","end":"2026-02-06T00:45:31.868134Z","steps":["trace[250301295] 'process raft request' (duration: 218.465403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:45:31.868372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.086911ms","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-02-06T00:45:31.8684Z","caller":"traceutil/trace.go:171","msg":"trace[1865395985] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:752; }","duration":"174.126612ms","start":"2026-02-06T00:45:31.694264Z","end":"2026-02-06T00:45:31.86839Z","steps":["trace[1865395985] 'agreement among raft nodes before linearized reading' (duration: 174.02506ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:45:31.868821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.443949ms","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-02-06T00:45:31.868844Z","caller":"traceutil/trace.go:171","msg":"trace[1571728283] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:752; }","duration":"169.48008ms","start":"2026-02-06T00:45:31.699358Z","end":"2026-02-06T00:45:31.868838Z","steps":["trace[1571728283] 'agreement among raft nodes before linearized reading' (duration: 169.424009ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:45:31.869003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.662865ms","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-02-06T00:45:31.869021Z","caller":"traceutil/trace.go:171","msg":"trace[382534084] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:752; }","duration":"170.713027ms","start":"2026-02-06T00:45:31.698303Z","end":"2026-02-06T00:45:31.869016Z","steps":["trace[382534084] 'agreement among raft nodes before linearized reading' (duration: 170.230256ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:45:32.300798Z","caller":"traceutil/trace.go:171","msg":"trace[487792184] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"428.203864ms","start":"2026-02-06T00:45:31.872576Z","end":"2026-02-06T00:45:32.30078Z","steps":["trace[487792184] 'process raft request' (duration: 427.054799ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:45:32.3009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:45:31.872559Z","time spent":"428.283625ms","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:45:32.301251Z","caller":"traceutil/trace.go:171","msg":"trace[485926662] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"426.921854ms","start":"2026-02-06T00:45:31.87431Z","end":"2026-02-06T00:45:32.301232Z","steps":["trace[485926662] 'process raft request' (duration: 426.887274ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:45:32.301367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:45:31.874302Z","time spent":"426.998986ms","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:45:32.301435Z","caller":"traceutil/trace.go:171","msg":"trace[1627281147] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"428.731395ms","start":"2026-02-06T00:45:31.872677Z","end":"2026-02-06T00:45:32.301408Z","steps":["trace[1627281147] 'process raft request' (duration: 428.435989ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:45:32.301479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:45:31.872645Z","time spent":"428.810907ms","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:45:42.500172Z","caller":"traceutil/trace.go:171","msg":"trace[1120768910] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"159.123196ms","start":"2026-02-06T00:45:42.341027Z","end":"2026-02-06T00:45:42.50015Z","steps":["trace[1120768910] 'process raft request' (duration: 159.007843ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:45:43.034394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.389888ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:45:43.034485Z","caller":"traceutil/trace.go:171","msg":"trace[2133162108] linearizableReadLoop","detail":"{readStateIndex:814; appliedIndex:813; }","duration":"692.065144ms","start":"2026-02-06T00:45:42.342406Z","end":"2026-02-06T00:45:43.034471Z","steps":["trace[2133162108] 'read index received' (duration: 157.862198ms)","trace[2133162108] 'applied index is now lower than readState.Index' (duration: 534.201756ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:45:43.034803Z","caller":"traceutil/trace.go:171","msg":"trace[61901753] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"693.69319ms","start":"2026-02-06T00:45:42.341085Z","end":"2026-02-06T00:45:43.034778Z","steps":["trace[61901753] 'process raft request' (duration: 292.847382ms)","trace[61901753] 'compare' (duration: 398.344903ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:45:43.034873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:45:42.341078Z","time spent":"693.761721ms","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:45:43.035099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.687898ms","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-02-06T00:45:43.035134Z","caller":"traceutil/trace.go:171","msg":"trace[1163276908] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:783; }","duration":"692.731938ms","start":"2026-02-06T00:45:42.342393Z","end":"2026-02-06T00:45:43.035125Z","steps":["trace[1163276908] 'agreement among raft nodes before linearized reading' (duration: 692.624516ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:45:43.035161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:45:42.342386Z","time spent":"692.768269ms","remote":"127.0.0.1:40308","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-02-06T00:45:43.035647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.337489ms","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-02-06T00:45:43.035689Z","caller":"traceutil/trace.go:171","msg":"trace[1101110476] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:783; }","duration":"640.433212ms","start":"2026-02-06T00:45:42.395247Z","end":"2026-02-06T00:45:43.03568Z","steps":["trace[1101110476] 'agreement among raft nodes before linearized reading' (duration: 640.32732ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:45:43.035719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:45:42.395236Z","time spent":"640.476033ms","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-06T00:45:43.190271Z","caller":"traceutil/trace.go:171","msg":"trace[1799002621] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"149.092265ms","start":"2026-02-06T00:45:43.041154Z","end":"2026-02-06T00:45:43.190246Z","steps":["trace[1799002621] 'process raft request' (duration: 133.660975ms)","trace[1799002621] 'compare' (duration: 15.331188ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:45:43.192298Z","caller":"traceutil/trace.go:171","msg":"trace[655950172] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"150.311212ms","start":"2026-02-06T00:45:43.041976Z","end":"2026-02-06T00:45:43.192287Z","steps":["trace[655950172] 'process raft request' (duration: 149.573346ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:45:43.191739Z","caller":"traceutil/trace.go:171","msg":"trace[2102604384] linearizableReadLoop","detail":"{readStateIndex:816; appliedIndex:814; }","duration":"137.973999ms","start":"2026-02-06T00:45:43.053718Z","end":"2026-02-06T00:45:43.191692Z","steps":["trace[2102604384] 'read index received' (duration: 121.104947ms)","trace[2102604384] 'applied index is now lower than readState.Index' (duration: 16.868312ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:45:43.193593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.856811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:45:43.193627Z","caller":"traceutil/trace.go:171","msg":"trace[770552937] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:786; }","duration":"139.968303ms","start":"2026-02-06T00:45:43.053647Z","end":"2026-02-06T00:45:43.193616Z","steps":["trace[770552937] 'agreement among raft nodes before linearized reading' (duration: 139.892772ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:45:44.052423Z","caller":"traceutil/trace.go:171","msg":"trace[100869725] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"125.589315ms","start":"2026-02-06T00:45:43.926806Z","end":"2026-02-06T00:45:44.052396Z","steps":["trace[100869725] 'process raft request' (duration: 125.446482ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:45:45.44776Z","caller":"traceutil/trace.go:171","msg":"trace[1352172287] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"207.215379ms","start":"2026-02-06T00:45:45.240524Z","end":"2026-02-06T00:45:45.447739Z","steps":["trace[1352172287] 'process raft request' (duration: 207.008325ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:45:45.654357Z","caller":"traceutil/trace.go:171","msg":"trace[1743137607] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"410.368648ms","start":"2026-02-06T00:45:45.243969Z","end":"2026-02-06T00:45:45.654338Z","steps":["trace[1743137607] 'process raft request' (duration: 410.269356ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:45:45.654481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:45:45.243961Z","time spent":"410.45263ms","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:45:57.839921Z","caller":"traceutil/trace.go:171","msg":"trace[1837021854] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"109.014399ms","start":"2026-02-06T00:45:57.730891Z","end":"2026-02-06T00:45:57.839906Z","steps":["trace[1837021854] 'process raft request' (duration: 108.925417ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:45:59.658326Z","caller":"traceutil/trace.go:171","msg":"trace[716475860] linearizableReadLoop","detail":"{readStateIndex:919; appliedIndex:918; }","duration":"142.189412ms","start":"2026-02-06T00:45:59.516122Z","end":"2026-02-06T00:45:59.658312Z","steps":["trace[716475860] 'read index received' (duration: 142.09188ms)","trace[716475860] 'applied index is now lower than readState.Index' (duration: 96.872µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:45:59.658477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.335735ms","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-02-06T00:45:59.658499Z","caller":"traceutil/trace.go:171","msg":"trace[1842714019] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:882; }","duration":"142.387226ms","start":"2026-02-06T00:45:59.516105Z","end":"2026-02-06T00:45:59.658492Z","steps":["trace[1842714019] 'agreement among raft nodes before linearized reading' (duration: 142.278804ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:46:01.069829Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15036002037651422584,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-06T00:46:01.424517Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.20245166s","expected-duration":"1s"} {"level":"info","ts":"2026-02-06T00:46:01.424782Z","caller":"traceutil/trace.go:171","msg":"trace[1347429390] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"1.202754117s","start":"2026-02-06T00:46:00.22201Z","end":"2026-02-06T00:46:01.424764Z","steps":["trace[1347429390] 'process raft request' (duration: 1.202661775s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:46:01.424866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:46:00.222001Z","time spent":"1.202812738s","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:46:02.257765Z","caller":"traceutil/trace.go:171","msg":"trace[1283502774] linearizableReadLoop","detail":"{readStateIndex:924; appliedIndex:923; }","duration":"1.688186063s","start":"2026-02-06T00:46:00.569558Z","end":"2026-02-06T00:46:02.257744Z","steps":["trace[1283502774] 'read index received' (duration: 855.235677ms)","trace[1283502774] 'applied index is now lower than readState.Index' (duration: 832.949496ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:46:02.258004Z","caller":"traceutil/trace.go:171","msg":"trace[540893849] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"1.729494126s","start":"2026-02-06T00:46:00.528499Z","end":"2026-02-06T00:46:02.257993Z","steps":["trace[540893849] 'process raft request' (duration: 1.729137468s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:46:02.258097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:46:00.528482Z","time spent":"1.729550207s","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:46:02.258363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.688798627s","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-02-06T00:46:02.258395Z","caller":"traceutil/trace.go:171","msg":"trace[1377604841] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:887; }","duration":"1.688852948s","start":"2026-02-06T00:46:00.569533Z","end":"2026-02-06T00:46:02.258386Z","steps":["trace[1377604841] 'agreement among raft nodes before linearized reading' (duration: 1.688716806s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:46:02.258423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:46:00.569519Z","time spent":"1.688897979s","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-06T00:46:02.258631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.153653082s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-06T00:46:02.258665Z","caller":"traceutil/trace.go:171","msg":"trace[252074289] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:887; }","duration":"1.153718233s","start":"2026-02-06T00:46:01.104939Z","end":"2026-02-06T00:46:02.258657Z","steps":["trace[252074289] 'agreement among raft nodes before linearized reading' (duration: 1.153604751s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:46:02.259659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:46:01.104925Z","time spent":"1.154718087s","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-06T00:46:02.260182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.247707ms","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-02-06T00:46:02.260248Z","caller":"traceutil/trace.go:171","msg":"trace[1305157589] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:887; }","duration":"406.351119ms","start":"2026-02-06T00:46:01.853882Z","end":"2026-02-06T00:46:02.260233Z","steps":["trace[1305157589] 'agreement among raft nodes before linearized reading' (duration: 406.159535ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:46:02.260276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:46:01.853865Z","time spent":"406.406621ms","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-06T00:46:02.26041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.914147ms","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-02-06T00:46:02.260423Z","caller":"traceutil/trace.go:171","msg":"trace[21589629] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:887; }","duration":"594.949027ms","start":"2026-02-06T00:46:01.66547Z","end":"2026-02-06T00:46:02.260419Z","steps":["trace[21589629] 'agreement among raft nodes before linearized reading' (duration: 594.910296ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:46:02.260435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:46:01.665457Z","time spent":"594.975518ms","remote":"127.0.0.1:40308","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-02-06T00:46:02.260518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.065612398s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:46:02.260547Z","caller":"traceutil/trace.go:171","msg":"trace[2072748254] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:887; }","duration":"1.065665408s","start":"2026-02-06T00:46:01.194873Z","end":"2026-02-06T00:46:02.260539Z","steps":["trace[2072748254] 'agreement among raft nodes before linearized reading' (duration: 1.065615787s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:46:02.260568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:46:01.194862Z","time spent":"1.065701069s","remote":"127.0.0.1:40554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-06T00:46:02.260618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.099470645s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:46:02.260639Z","caller":"traceutil/trace.go:171","msg":"trace[495695338] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:887; }","duration":"1.099506256s","start":"2026-02-06T00:46:01.161125Z","end":"2026-02-06T00:46:02.260632Z","steps":["trace[495695338] 'agreement among raft nodes before linearized reading' (duration: 1.099478356s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:46:02.260654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:46:01.161114Z","time spent":"1.099537797s","remote":"127.0.0.1:40012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-06T00:46:02.626841Z","caller":"traceutil/trace.go:171","msg":"trace[870456400] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"362.812867ms","start":"2026-02-06T00:46:02.264007Z","end":"2026-02-06T00:46:02.62682Z","steps":["trace[870456400] 'process raft request' (duration: 357.120341ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:46:02.626944Z","caller":"traceutil/trace.go:171","msg":"trace[2139053267] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"355.70701ms","start":"2026-02-06T00:46:02.271203Z","end":"2026-02-06T00:46:02.62691Z","steps":["trace[2139053267] 'process raft request' (duration: 355.517166ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:46:02.626972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:46:02.263998Z","time spent":"362.90561ms","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:46:02.627057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:46:02.271196Z","time spent":"355.795752ms","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:46:02.627138Z","caller":"traceutil/trace.go:171","msg":"trace[609958587] linearizableReadLoop","detail":"{readStateIndex:927; appliedIndex:924; }","duration":"357.565492ms","start":"2026-02-06T00:46:02.269561Z","end":"2026-02-06T00:46:02.627127Z","steps":["trace[609958587] 'read index received' (duration: 351.57997ms)","trace[609958587] 'applied index is now lower than readState.Index' (duration: 5.984462ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:46:02.627192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.625623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:46:02.627217Z","caller":"traceutil/trace.go:171","msg":"trace[2124452562] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"359.624208ms","start":"2026-02-06T00:46:02.267588Z","end":"2026-02-06T00:46:02.627212Z","steps":["trace[2124452562] 'process raft request' (duration: 359.096676ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:46:02.627222Z","caller":"traceutil/trace.go:171","msg":"trace[1792751141] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:891; }","duration":"357.668794ms","start":"2026-02-06T00:46:02.269544Z","end":"2026-02-06T00:46:02.627213Z","steps":["trace[1792751141] 'agreement among raft nodes before linearized reading' (duration: 357.620233ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:46:02.627253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:46:02.269538Z","time spent":"357.709165ms","remote":"127.0.0.1:40012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T00:46:02.627257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:46:02.267584Z","time spent":"359.649288ms","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:46:02.627196Z","caller":"traceutil/trace.go:171","msg":"trace[862077565] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"359.654838ms","start":"2026-02-06T00:46:02.267534Z","end":"2026-02-06T00:46:02.627189Z","steps":["trace[862077565] 'process raft request' (duration: 359.094746ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:46:02.627466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:46:02.267518Z","time spent":"359.924684ms","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:46:04.867441Z","caller":"traceutil/trace.go:171","msg":"trace[1580472946] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"233.928448ms","start":"2026-02-06T00:46:04.633497Z","end":"2026-02-06T00:46:04.867425Z","steps":["trace[1580472946] 'process raft request' (duration: 233.808926ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:46:05.22605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.81352ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:46:05.226327Z","caller":"traceutil/trace.go:171","msg":"trace[923169117] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"591.495571ms","start":"2026-02-06T00:46:04.634817Z","end":"2026-02-06T00:46:05.226312Z","steps":["trace[923169117] 'process raft request' (duration: 308.367623ms)","trace[923169117] 'compare' (duration: 282.674437ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:46:05.226348Z","caller":"traceutil/trace.go:171","msg":"trace[1175419519] linearizableReadLoop","detail":"{readStateIndex:932; appliedIndex:930; }","duration":"588.280339ms","start":"2026-02-06T00:46:04.638048Z","end":"2026-02-06T00:46:05.226328Z","steps":["trace[1175419519] 'read index received' (duration: 229.316076ms)","trace[1175419519] 'applied index is now lower than readState.Index' (duration: 358.962773ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:46:05.226372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:46:04.63481Z","time spent":"591.540092ms","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:46:05.550715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.599373ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-06T00:46:05.550824Z","caller":"traceutil/trace.go:171","msg":"trace[1116567139] linearizableReadLoop","detail":"{readStateIndex:933; appliedIndex:932; }","duration":"324.416398ms","start":"2026-02-06T00:46:05.226384Z","end":"2026-02-06T00:46:05.5508Z","steps":["trace[1116567139] 'read index received' (duration: 43.871µs)","trace[1116567139] 'applied index is now lower than readState.Index' (duration: 324.371297ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:46:05.550973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.164993ms","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-02-06T00:46:05.550989Z","caller":"traceutil/trace.go:171","msg":"trace[1632941675] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:895; }","duration":"911.206385ms","start":"2026-02-06T00:46:04.639777Z","end":"2026-02-06T00:46:05.550983Z","steps":["trace[1632941675] 'agreement among raft nodes before linearized reading' (duration: 911.099833ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:46:05.551008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:46:04.639772Z","time spent":"911.231715ms","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-06T00:46:05.551027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"912.969883ms","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-02-06T00:46:05.551065Z","caller":"traceutil/trace.go:171","msg":"trace[1589001479] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:895; }","duration":"913.027045ms","start":"2026-02-06T00:46:04.638025Z","end":"2026-02-06T00:46:05.551053Z","steps":["trace[1589001479] 'agreement among raft nodes before linearized reading' (duration: 588.389182ms)","trace[1589001479] 'range keys from in-memory index tree' (duration: 324.540561ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:46:05.551087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:46:04.638019Z","time spent":"913.063935ms","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-06T00:46:05.551168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.866877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:46:05.551182Z","caller":"traceutil/trace.go:171","msg":"trace[888010904] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:895; }","duration":"204.902557ms","start":"2026-02-06T00:46:05.346276Z","end":"2026-02-06T00:46:05.551178Z","steps":["trace[888010904] 'agreement among raft nodes before linearized reading' (duration: 204.878377ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:46:05.551327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.537775ms","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-02-06T00:46:05.551346Z","caller":"traceutil/trace.go:171","msg":"trace[490093139] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:895; }","duration":"116.572967ms","start":"2026-02-06T00:46:05.434767Z","end":"2026-02-06T00:46:05.55134Z","steps":["trace[490093139] 'agreement among raft nodes before linearized reading' (duration: 116.525196ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:46:07.085833Z","caller":"traceutil/trace.go:171","msg":"trace[1823087055] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"213.472787ms","start":"2026-02-06T00:46:06.872345Z","end":"2026-02-06T00:46:07.085818Z","steps":["trace[1823087055] 'process raft request' (duration: 213.363185ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:46:07.605129Z","caller":"traceutil/trace.go:171","msg":"trace[1880720523] linearizableReadLoop","detail":"{readStateIndex:938; appliedIndex:937; }","duration":"291.324818ms","start":"2026-02-06T00:46:07.313793Z","end":"2026-02-06T00:46:07.605118Z","steps":["trace[1880720523] 'read index received' (duration: 291.221205ms)","trace[1880720523] 'applied index is now lower than readState.Index' (duration: 103.093µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:46:07.605266Z","caller":"traceutil/trace.go:171","msg":"trace[1433954090] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"343.313367ms","start":"2026-02-06T00:46:07.261947Z","end":"2026-02-06T00:46:07.605261Z","steps":["trace[1433954090] 'process raft request' (duration: 343.098772ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:46:07.605327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:46:07.261933Z","time spent":"343.345857ms","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:46:07.605449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.664495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:46:07.605466Z","caller":"traceutil/trace.go:171","msg":"trace[1526192151] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:900; }","duration":"291.711776ms","start":"2026-02-06T00:46:07.313749Z","end":"2026-02-06T00:46:07.605461Z","steps":["trace[1526192151] 'agreement among raft nodes before linearized reading' (duration: 291.683545ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:46:07.954593Z","caller":"traceutil/trace.go:171","msg":"trace[1700897681] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"343.740515ms","start":"2026-02-06T00:46:07.610836Z","end":"2026-02-06T00:46:07.954577Z","steps":["trace[1700897681] 'process raft request' (duration: 343.706224ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:46:07.954716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:46:07.610831Z","time spent":"343.808936ms","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:46:07.954901Z","caller":"traceutil/trace.go:171","msg":"trace[218430168] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"346.819133ms","start":"2026-02-06T00:46:07.608074Z","end":"2026-02-06T00:46:07.954893Z","steps":["trace[218430168] 'process raft request' (duration: 249.683337ms)","trace[218430168] 'compare' (duration: 96.523112ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:46:07.954935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:46:07.608065Z","time spent":"346.851214ms","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:46:07.955009Z","caller":"traceutil/trace.go:171","msg":"trace[1490383636] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"346.132499ms","start":"2026-02-06T00:46:07.608871Z","end":"2026-02-06T00:46:07.955004Z","steps":["trace[1490383636] 'process raft request' (duration: 345.622187ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:46:07.955037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:46:07.608866Z","time spent":"346.156059ms","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:46:09.703752Z","caller":"traceutil/trace.go:171","msg":"trace[560419125] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"141.436446ms","start":"2026-02-06T00:46:09.562299Z","end":"2026-02-06T00:46:09.703736Z","steps":["trace[560419125] 'process raft request' (duration: 141.14223ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:46:15.500174Z","caller":"traceutil/trace.go:171","msg":"trace[322914670] linearizableReadLoop","detail":"{readStateIndex:961; appliedIndex:960; }","duration":"296.195175ms","start":"2026-02-06T00:46:15.203964Z","end":"2026-02-06T00:46:15.500159Z","steps":["trace[322914670] 'read index received' (duration: 294.051998ms)","trace[322914670] 'applied index is now lower than readState.Index' (duration: 2.142247ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:46:15.500305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.325588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-06T00:46:15.500322Z","caller":"traceutil/trace.go:171","msg":"trace[1575594563] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:921; }","duration":"296.37768ms","start":"2026-02-06T00:46:15.203939Z","end":"2026-02-06T00:46:15.500317Z","steps":["trace[1575594563] 'agreement among raft nodes before linearized reading' (duration: 296.284818ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:46:16.11926Z","caller":"traceutil/trace.go:171","msg":"trace[482797348] linearizableReadLoop","detail":"{readStateIndex:964; appliedIndex:963; }","duration":"121.205838ms","start":"2026-02-06T00:46:15.998034Z","end":"2026-02-06T00:46:16.11924Z","steps":["trace[482797348] 'read index received' (duration: 121.045485ms)","trace[482797348] 'applied index is now lower than readState.Index' (duration: 159.373µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:46:16.11954Z","caller":"traceutil/trace.go:171","msg":"trace[381813969] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"139.659756ms","start":"2026-02-06T00:46:15.97987Z","end":"2026-02-06T00:46:16.119529Z","steps":["trace[381813969] 'process raft request' (duration: 139.261008ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:46:16.119776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.737869ms","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-02-06T00:46:16.119809Z","caller":"traceutil/trace.go:171","msg":"trace[812502825] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:924; }","duration":"121.816971ms","start":"2026-02-06T00:46:15.997983Z","end":"2026-02-06T00:46:16.1198Z","steps":["trace[812502825] 'agreement among raft nodes before linearized reading' (duration: 121.705059ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:46:16.119974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.364528ms","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-02-06T00:46:16.119999Z","caller":"traceutil/trace.go:171","msg":"trace[2123032520] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:924; }","duration":"105.401048ms","start":"2026-02-06T00:46:16.014591Z","end":"2026-02-06T00:46:16.119992Z","steps":["trace[2123032520] 'agreement among raft nodes before linearized reading' (duration: 105.329447ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:46:17.953967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.985238ms","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-02-06T00:46:17.954013Z","caller":"traceutil/trace.go:171","msg":"trace[718746788] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:927; }","duration":"152.05987ms","start":"2026-02-06T00:46:17.801942Z","end":"2026-02-06T00:46:17.954002Z","steps":["trace[718746788] 'range keys from in-memory index tree' (duration: 151.886736ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:46:18.759541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.83607ms","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-02-06T00:46:18.759643Z","caller":"traceutil/trace.go:171","msg":"trace[559612953] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:928; }","duration":"635.977063ms","start":"2026-02-06T00:46:18.123644Z","end":"2026-02-06T00:46:18.759621Z","steps":["trace[559612953] 'range keys from in-memory index tree' (duration: 635.679436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:46:18.759698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:46:18.123631Z","time spent":"636.054775ms","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-06T00:46:18.760109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.291303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:46:18.76015Z","caller":"traceutil/trace.go:171","msg":"trace[516256563] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:928; }","duration":"599.341934ms","start":"2026-02-06T00:46:18.160799Z","end":"2026-02-06T00:46:18.760141Z","steps":["trace[516256563] 'range keys from in-memory index tree' (duration: 599.267062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:46:18.760184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:46:18.160794Z","time spent":"599.383355ms","remote":"127.0.0.1:40012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T00:46:18.76054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.369167ms","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-02-06T00:46:18.760578Z","caller":"traceutil/trace.go:171","msg":"trace[410362190] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:928; }","duration":"576.406527ms","start":"2026-02-06T00:46:18.184163Z","end":"2026-02-06T00:46:18.76057Z","steps":["trace[410362190] 'range keys from in-memory index tree' (duration: 576.287035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:46:18.760611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:46:18.184143Z","time spent":"576.461778ms","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-06T00:46:18.760762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.015466ms","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-02-06T00:46:18.760787Z","caller":"traceutil/trace.go:171","msg":"trace[249890927] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:928; }","duration":"575.046827ms","start":"2026-02-06T00:46:18.185733Z","end":"2026-02-06T00:46:18.760779Z","steps":["trace[249890927] 'range keys from in-memory index tree' (duration: 574.926704ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:46:18.760811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:46:18.185728Z","time spent":"575.079027ms","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-06T00:46:18.761137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.246958ms","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-02-06T00:46:18.77529Z","caller":"traceutil/trace.go:171","msg":"trace[1203579241] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:928; }","duration":"249.429032ms","start":"2026-02-06T00:46:18.525841Z","end":"2026-02-06T00:46:18.775271Z","steps":["trace[1203579241] 'count revisions from in-memory index tree' (duration: 235.161446ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:46:18.998932Z","caller":"traceutil/trace.go:171","msg":"trace[209219008] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"223.794385ms","start":"2026-02-06T00:46:18.775108Z","end":"2026-02-06T00:46:18.998902Z","steps":["trace[209219008] 'process raft request' (duration: 223.628352ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:46:18.999521Z","caller":"traceutil/trace.go:171","msg":"trace[1414882268] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:969; }","duration":"223.904768ms","start":"2026-02-06T00:46:18.775608Z","end":"2026-02-06T00:46:18.999513Z","steps":["trace[1414882268] 'read index received' (duration: 223.901148ms)","trace[1414882268] 'applied index is now lower than readState.Index' (duration: 3.12µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:46:18.999583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.951579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:46:18.999602Z","caller":"traceutil/trace.go:171","msg":"trace[1571293805] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:929; }","duration":"224.014921ms","start":"2026-02-06T00:46:18.775581Z","end":"2026-02-06T00:46:18.999596Z","steps":["trace[1571293805] 'agreement among raft nodes before linearized reading' (duration: 223.956289ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:46:19.014887Z","caller":"traceutil/trace.go:171","msg":"trace[87077645] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"236.520077ms","start":"2026-02-06T00:46:18.778353Z","end":"2026-02-06T00:46:19.014873Z","steps":["trace[87077645] 'process raft request' (duration: 236.113417ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:46:19.018281Z","caller":"traceutil/trace.go:171","msg":"trace[294694585] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"219.99886ms","start":"2026-02-06T00:46:18.794629Z","end":"2026-02-06T00:46:19.014628Z","steps":["trace[294694585] 'process raft request' (duration: 219.961679ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:46:19.749233Z","caller":"traceutil/trace.go:171","msg":"trace[296668294] linearizableReadLoop","detail":"{readStateIndex:972; appliedIndex:972; }","duration":"135.238499ms","start":"2026-02-06T00:46:19.61398Z","end":"2026-02-06T00:46:19.749219Z","steps":["trace[296668294] 'read index received' (duration: 135.234459ms)","trace[296668294] 'applied index is now lower than readState.Index' (duration: 3.2µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:46:19.749337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.026822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T00:46:19.749353Z","caller":"traceutil/trace.go:171","msg":"trace[887902136] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:931; }","duration":"110.082173ms","start":"2026-02-06T00:46:19.639266Z","end":"2026-02-06T00:46:19.749348Z","steps":["trace[887902136] 'agreement among raft nodes before linearized reading' (duration: 110.023742ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:46:19.749489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.509125ms","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-02-06T00:46:19.749502Z","caller":"traceutil/trace.go:171","msg":"trace[1775260366] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:931; }","duration":"135.547755ms","start":"2026-02-06T00:46:19.61395Z","end":"2026-02-06T00:46:19.749498Z","steps":["trace[1775260366] 'agreement among raft nodes before linearized reading' (duration: 135.483894ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:46:19.968141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.435035ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:46:19.968357Z","caller":"traceutil/trace.go:171","msg":"trace[1516114197] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"215.033642ms","start":"2026-02-06T00:46:19.753306Z","end":"2026-02-06T00:46:19.96834Z","steps":["trace[1516114197] 'process raft request' (duration: 214.95754ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:46:19.96861Z","caller":"traceutil/trace.go:171","msg":"trace[1251287152] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"217.852744ms","start":"2026-02-06T00:46:19.750745Z","end":"2026-02-06T00:46:19.968598Z","steps":["trace[1251287152] 'process raft request' (duration: 104.875507ms)","trace[1251287152] 'compare' (duration: 112.304072ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:46:21.558713Z","caller":"traceutil/trace.go:171","msg":"trace[63679639] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"139.533354ms","start":"2026-02-06T00:46:21.419158Z","end":"2026-02-06T00:46:21.558691Z","steps":["trace[63679639] 'process raft request' (duration: 139.388421ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:46:34.401609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.46328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-06T00:46:34.401655Z","caller":"traceutil/trace.go:171","msg":"trace[797635549] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1032; }","duration":"324.558382ms","start":"2026-02-06T00:46:34.077089Z","end":"2026-02-06T00:46:34.401647Z","steps":["trace[797635549] 'agreement among raft nodes before linearized reading' (duration: 324.4402ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:46:34.401679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:46:34.077072Z","time spent":"324.601533ms","remote":"127.0.0.1:40308","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-02-06T00:46:34.40219Z","caller":"traceutil/trace.go:171","msg":"trace[1534483072] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"547.683221ms","start":"2026-02-06T00:46:33.853774Z","end":"2026-02-06T00:46:34.401457Z","steps":["trace[1534483072] 'process raft request' (duration: 547.14639ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:46:34.402256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:46:33.853754Z","time spent":"548.459239ms","remote":"127.0.0.1:40222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5769,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:46:34.401492Z","caller":"traceutil/trace.go:171","msg":"trace[283918937] linearizableReadLoop","detail":"{readStateIndex:1075; appliedIndex:1074; }","duration":"324.067622ms","start":"2026-02-06T00:46:34.077132Z","end":"2026-02-06T00:46:34.4012Z","steps":["trace[283918937] 'read index received' (duration: 323.9742ms)","trace[283918937] 'applied index is now lower than readState.Index' (duration: 92.582µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:46:34.90272Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15036002037651422915,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-06T00:46:35.403784Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15036002037651422915,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-06T00:46:35.904798Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15036002037651422915,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-06T00:46:36.180095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.017055691s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-06T00:46:36.232656Z","caller":"traceutil/trace.go:171","msg":"trace[312072134] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.069651533s","start":"2026-02-06T00:46:34.16298Z","end":"2026-02-06T00:46:36.232631Z","steps":["trace[312072134] 'agreement among raft nodes before linearized reading' (duration: 2.017075021s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:46:36.240439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:46:34.162971Z","time spent":"2.077430105s","remote":"127.0.0.1:40012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T00:46:36.310187Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.909382171s","expected-duration":"1s"} 2026/02/06 00:46:36 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-06T00:46:36.978556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.228171ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:46:36.990805Z","caller":"traceutil/trace.go:171","msg":"trace[1317166517] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"2.877449863s","start":"2026-02-06T00:46:34.113339Z","end":"2026-02-06T00:46:36.990789Z","steps":["trace[1317166517] 'process raft request' (duration: 2.20891241s)","trace[1317166517] 'compare' (duration: 654.816321ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:46:36.990976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:46:34.113315Z","time spent":"2.877630637s","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:46:36.99122Z","caller":"traceutil/trace.go:171","msg":"trace[259752684] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"2.585242137s","start":"2026-02-06T00:46:34.405968Z","end":"2026-02-06T00:46:36.99121Z","steps":["trace[259752684] 'process raft request' (duration: 2.572977025s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:46:36.991354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:46:34.40595Z","time spent":"2.585357089s","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:46:36.977352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.136916139s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:46:36.991841Z","caller":"traceutil/trace.go:171","msg":"trace[912190527] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1032; }","duration":"2.15141863s","start":"2026-02-06T00:46:34.840411Z","end":"2026-02-06T00:46:36.99183Z","steps":["trace[912190527] 'range keys from in-memory index tree' (duration: 2.136902619s)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:46:37.076093Z","caller":"traceutil/trace.go:171","msg":"trace[294687875] linearizableReadLoop","detail":"{readStateIndex:1078; appliedIndex:1075; }","duration":"2.673627351s","start":"2026-02-06T00:46:34.402431Z","end":"2026-02-06T00:46:37.076058Z","steps":["trace[294687875] 'read index received' (duration: 1.919764972s)","trace[294687875] 'applied index is now lower than readState.Index' (duration: 753.861179ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:46:37.076488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.997578108s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-06T00:46:37.076531Z","caller":"traceutil/trace.go:171","msg":"trace[604878369] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1034; }","duration":"2.99764471s","start":"2026-02-06T00:46:34.07887Z","end":"2026-02-06T00:46:37.076514Z","steps":["trace[604878369] 'agreement among raft nodes before linearized reading' (duration: 2.997479866s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:46:37.076567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:46:34.07886Z","time spent":"2.99769811s","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-06T00:46:37.076602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.205040505s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-06T00:46:37.076665Z","caller":"traceutil/trace.go:171","msg":"trace[198897168] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1034; }","duration":"2.205142177s","start":"2026-02-06T00:46:34.871505Z","end":"2026-02-06T00:46:37.076647Z","steps":["trace[198897168] 'agreement among raft nodes before linearized reading' (duration: 2.204966624s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:46:37.076706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:46:34.871487Z","time spent":"2.205208308s","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-06T00:46:37.076824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.521387568s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:46:37.076859Z","caller":"traceutil/trace.go:171","msg":"trace[1091763668] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1034; }","duration":"1.52145263s","start":"2026-02-06T00:46:35.555395Z","end":"2026-02-06T00:46:37.076847Z","steps":["trace[1091763668] 'agreement among raft nodes before linearized reading' (duration: 1.521403579s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:46:37.076883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:46:35.555374Z","time spent":"1.52150398s","remote":"127.0.0.1:40012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T00:46:37.076934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.698864339s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-02-06T00:46:37.07697Z","caller":"traceutil/trace.go:171","msg":"trace[1756901055] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1034; }","duration":"1.698962502s","start":"2026-02-06T00:46:35.377997Z","end":"2026-02-06T00:46:37.076959Z","steps":["trace[1756901055] 'agreement among raft nodes before linearized reading' (duration: 1.69887698s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:46:37.076997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:46:35.377967Z","time spent":"1.699023733s","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":560,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-06T00:46:37.07729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.856730389s","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-02-06T00:46:37.077324Z","caller":"traceutil/trace.go:171","msg":"trace[125825907] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1034; }","duration":"1.85679985s","start":"2026-02-06T00:46:35.220514Z","end":"2026-02-06T00:46:37.077314Z","steps":["trace[125825907] 'agreement among raft nodes before linearized reading' (duration: 1.856590375s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:46:37.077354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:46:35.220494Z","time spent":"1.856851112s","remote":"127.0.0.1:40308","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-02-06T00:46:37.077559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.859947329s","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-02-06T00:46:37.077586Z","caller":"traceutil/trace.go:171","msg":"trace[1478947009] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1034; }","duration":"1.860000871s","start":"2026-02-06T00:46:35.217577Z","end":"2026-02-06T00:46:37.077578Z","steps":["trace[1478947009] 'agreement among raft nodes before linearized reading' (duration: 1.859906029s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:46:37.077611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:46:35.217562Z","time spent":"1.860043601s","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-06T00:46:37.077927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.877410286s","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-02-06T00:46:37.078086Z","caller":"traceutil/trace.go:171","msg":"trace[625888103] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1034; }","duration":"1.877487848s","start":"2026-02-06T00:46:35.200465Z","end":"2026-02-06T00:46:37.077953Z","steps":["trace[625888103] 'agreement among raft nodes before linearized reading' (duration: 1.877248582s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:46:37.078126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:46:35.200448Z","time spent":"1.877669272s","remote":"127.0.0.1:40308","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-02-06T00:46:37.079281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.63395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:46:37.079452Z","caller":"traceutil/trace.go:171","msg":"trace[764285935] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1034; }","duration":"756.905175ms","start":"2026-02-06T00:46:36.322535Z","end":"2026-02-06T00:46:37.07944Z","steps":["trace[764285935] 'agreement among raft nodes before linearized reading' (duration: 756.642259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:46:37.079602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:46:36.322521Z","time spent":"757.069299ms","remote":"127.0.0.1:40020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T00:46:37.079993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.986156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T00:46:37.080062Z","caller":"traceutil/trace.go:171","msg":"trace[735462768] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1034; }","duration":"470.094718ms","start":"2026-02-06T00:46:36.609957Z","end":"2026-02-06T00:46:37.080051Z","steps":["trace[735462768] 'agreement among raft nodes before linearized reading' (duration: 469.996786ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:46:37.080294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:46:36.609935Z","time spent":"470.343463ms","remote":"127.0.0.1:40424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":31,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-06T00:46:37.081217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.150438081s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-06T00:46:37.081284Z","caller":"traceutil/trace.go:171","msg":"trace[414626684] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1034; }","duration":"1.150508643s","start":"2026-02-06T00:46:35.930739Z","end":"2026-02-06T00:46:37.081248Z","steps":["trace[414626684] 'agreement among raft nodes before linearized reading' (duration: 1.150380541s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:46:37.081323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:46:35.930724Z","time spent":"1.150589945s","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":498,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-06T00:46:37.209537Z","caller":"traceutil/trace.go:171","msg":"trace[1850417997] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"100.675324ms","start":"2026-02-06T00:46:37.108842Z","end":"2026-02-06T00:46:37.209517Z","steps":["trace[1850417997] 'process raft request' (duration: 100.646943ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:46:37.209792Z","caller":"traceutil/trace.go:171","msg":"trace[668026224] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"103.126939ms","start":"2026-02-06T00:46:37.106651Z","end":"2026-02-06T00:46:37.209778Z","steps":["trace[668026224] 'process raft request' (duration: 102.743409ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:46:37.210473Z","caller":"traceutil/trace.go:171","msg":"trace[1502497963] linearizableReadLoop","detail":"{readStateIndex:1082; appliedIndex:1078; }","duration":"105.207036ms","start":"2026-02-06T00:46:37.105251Z","end":"2026-02-06T00:46:37.210458Z","steps":["trace[1502497963] 'read index received' (duration: 1.953674ms)","trace[1502497963] 'applied index is now lower than readState.Index' (duration: 103.251251ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:46:37.210646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.378139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:46:37.210722Z","caller":"traceutil/trace.go:171","msg":"trace[1886404550] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1042; }","duration":"105.493731ms","start":"2026-02-06T00:46:37.105217Z","end":"2026-02-06T00:46:37.210711Z","steps":["trace[1886404550] 'agreement among raft nodes before linearized reading' (duration: 105.310337ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:46:37.211118Z","caller":"traceutil/trace.go:171","msg":"trace[1247141067] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"104.351066ms","start":"2026-02-06T00:46:37.106752Z","end":"2026-02-06T00:46:37.211103Z","steps":["trace[1247141067] 'process raft request' (duration: 102.678838ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:46:37.211296Z","caller":"traceutil/trace.go:171","msg":"trace[1761644120] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"102.501195ms","start":"2026-02-06T00:46:37.108785Z","end":"2026-02-06T00:46:37.211286Z","steps":["trace[1761644120] 'process raft request' (duration: 100.676514ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:46:37.211655Z","caller":"traceutil/trace.go:171","msg":"trace[1715435061] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"118.59759ms","start":"2026-02-06T00:46:37.093044Z","end":"2026-02-06T00:46:37.211642Z","steps":["trace[1715435061] 'process raft request' (duration: 101.334209ms)","trace[1715435061] 'compare' (duration: 14.50267ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:46:37.211832Z","caller":"traceutil/trace.go:171","msg":"trace[1527161584] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"118.700903ms","start":"2026-02-06T00:46:37.09312Z","end":"2026-02-06T00:46:37.211821Z","steps":["trace[1527161584] 'process raft request' (duration: 115.911101ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:46:37.212069Z","caller":"traceutil/trace.go:171","msg":"trace[1693322512] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"118.906017ms","start":"2026-02-06T00:46:37.093151Z","end":"2026-02-06T00:46:37.212057Z","steps":["trace[1693322512] 'process raft request' (duration: 116.192437ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:47:17.6574Z","caller":"traceutil/trace.go:171","msg":"trace[659930218] linearizableReadLoop","detail":"{readStateIndex:1258; appliedIndex:1257; }","duration":"104.17009ms","start":"2026-02-06T00:47:17.553194Z","end":"2026-02-06T00:47:17.657364Z","steps":["trace[659930218] 'read index received' (duration: 13.079751ms)","trace[659930218] 'applied index is now lower than readState.Index' (duration: 91.089209ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:47:17.660512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.283709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-06T00:47:17.660609Z","caller":"traceutil/trace.go:171","msg":"trace[1441554668] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1206; }","duration":"107.398131ms","start":"2026-02-06T00:47:17.553168Z","end":"2026-02-06T00:47:17.660566Z","steps":["trace[1441554668] 'agreement among raft nodes before linearized reading' (duration: 104.305122ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:47:17.66232Z","caller":"traceutil/trace.go:171","msg":"trace[934353466] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"104.62974ms","start":"2026-02-06T00:47:17.553097Z","end":"2026-02-06T00:47:17.657727Z","steps":["trace[934353466] 'process raft request' (duration: 30.420445ms)","trace[934353466] 'compare' (duration: 73.692823ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:47:21.890258Z","caller":"traceutil/trace.go:171","msg":"trace[126456252] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"159.078708ms","start":"2026-02-06T00:47:21.73116Z","end":"2026-02-06T00:47:21.890239Z","steps":["trace[126456252] 'process raft request' (duration: 143.883971ms)","trace[126456252] 'compare' (duration: 14.88705ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:47:22.100942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.074163ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:47:22.101089Z","caller":"traceutil/trace.go:171","msg":"trace[1321367836] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"205.939926ms","start":"2026-02-06T00:47:21.895128Z","end":"2026-02-06T00:47:22.101068Z","steps":["trace[1321367836] 'process raft request' (duration: 99.662109ms)","trace[1321367836] 'compare' (duration: 105.852428ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:47:22.345983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.143476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-02-06T00:47:22.346276Z","caller":"traceutil/trace.go:171","msg":"trace[1804466752] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1222; }","duration":"121.500325ms","start":"2026-02-06T00:47:22.224757Z","end":"2026-02-06T00:47:22.346257Z","steps":["trace[1804466752] 'range keys from in-memory index tree' (duration: 120.926561ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:47:28.731347Z","caller":"traceutil/trace.go:171","msg":"trace[350256520] linearizableReadLoop","detail":"{readStateIndex:1303; appliedIndex:1302; }","duration":"497.113182ms","start":"2026-02-06T00:47:28.234212Z","end":"2026-02-06T00:47:28.731325Z","steps":["trace[350256520] 'read index received' (duration: 496.849806ms)","trace[350256520] 'applied index is now lower than readState.Index' (duration: 262.116µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:47:28.73177Z","caller":"traceutil/trace.go:171","msg":"trace[1169914232] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"515.556011ms","start":"2026-02-06T00:47:28.216187Z","end":"2026-02-06T00:47:28.731743Z","steps":["trace[1169914232] 'process raft request' (duration: 514.941128ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:47:28.731971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:47:28.21617Z","time spent":"515.666524ms","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:47:28.731959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.719855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-06T00:47:28.732153Z","caller":"traceutil/trace.go:171","msg":"trace[1210468196] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1249; }","duration":"497.94184ms","start":"2026-02-06T00:47:28.234177Z","end":"2026-02-06T00:47:28.732119Z","steps":["trace[1210468196] 'agreement among raft nodes before linearized reading' (duration: 497.48991ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:47:28.732299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:47:28.234164Z","time spent":"498.119464ms","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-06T00:47:29.650722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.88968ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-02-06T00:47:30.118155Z","caller":"traceutil/trace.go:171","msg":"trace[746507162] linearizableReadLoop","detail":"{readStateIndex:1309; appliedIndex:1308; }","duration":"129.199835ms","start":"2026-02-06T00:47:29.988928Z","end":"2026-02-06T00:47:30.118128Z","steps":["trace[746507162] 'read index received' (duration: 128.888248ms)","trace[746507162] 'applied index is now lower than readState.Index' (duration: 310.687µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:47:30.118637Z","caller":"traceutil/trace.go:171","msg":"trace[1116348188] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"296.425142ms","start":"2026-02-06T00:47:29.822198Z","end":"2026-02-06T00:47:30.118623Z","steps":["trace[1116348188] 'process raft request' (duration: 295.712176ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:47:30.118962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.008262ms","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-02-06T00:47:30.118996Z","caller":"traceutil/trace.go:171","msg":"trace[1615889690] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1254; }","duration":"130.119826ms","start":"2026-02-06T00:47:29.988866Z","end":"2026-02-06T00:47:30.118986Z","steps":["trace[1615889690] 'agreement among raft nodes before linearized reading' (duration: 129.962622ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:47:32.338622Z","caller":"traceutil/trace.go:171","msg":"trace[1762217282] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"110.432399ms","start":"2026-02-06T00:47:32.228154Z","end":"2026-02-06T00:47:32.338587Z","steps":["trace[1762217282] 'process raft request' (duration: 57.047195ms)","trace[1762217282] 'compare' (duration: 53.179249ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:47:32.635665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.083636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-06T00:47:32.635718Z","caller":"traceutil/trace.go:171","msg":"trace[485800327] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1262; }","duration":"186.172949ms","start":"2026-02-06T00:47:32.449532Z","end":"2026-02-06T00:47:32.635705Z","steps":["trace[485800327] 'range keys from in-memory index tree' (duration: 185.945303ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:47:32.635942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.704001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-02-06T00:47:32.635963Z","caller":"traceutil/trace.go:171","msg":"trace[907025323] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1262; }","duration":"145.749482ms","start":"2026-02-06T00:47:32.490206Z","end":"2026-02-06T00:47:32.635956Z","steps":["trace[907025323] 'range keys from in-memory index tree' (duration: 145.582148ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:47:33.254801Z","caller":"traceutil/trace.go:171","msg":"trace[768470241] linearizableReadLoop","detail":"{readStateIndex:1321; appliedIndex:1320; }","duration":"250.619767ms","start":"2026-02-06T00:47:33.004161Z","end":"2026-02-06T00:47:33.25478Z","steps":["trace[768470241] 'read index received' (duration: 250.055284ms)","trace[768470241] 'applied index is now lower than readState.Index' (duration: 563.523µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:47:33.254955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.779331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:47:33.254987Z","caller":"traceutil/trace.go:171","msg":"trace[986918067] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1266; }","duration":"250.858292ms","start":"2026-02-06T00:47:33.004119Z","end":"2026-02-06T00:47:33.254977Z","steps":["trace[986918067] 'agreement among raft nodes before linearized reading' (duration: 250.75148ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:47:33.25525Z","caller":"traceutil/trace.go:171","msg":"trace[53181619] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"284.862646ms","start":"2026-02-06T00:47:32.970376Z","end":"2026-02-06T00:47:33.255239Z","steps":["trace[53181619] 'process raft request' (duration: 283.896335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:47:33.959326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.745751ms","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-02-06T00:47:33.959408Z","caller":"traceutil/trace.go:171","msg":"trace[1262966681] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1267; }","duration":"136.842623ms","start":"2026-02-06T00:47:33.822547Z","end":"2026-02-06T00:47:33.95939Z","steps":["trace[1262966681] 'range keys from in-memory index tree' (duration: 136.598878ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:47:33.959717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.510873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-02-06T00:47:33.959799Z","caller":"traceutil/trace.go:171","msg":"trace[794608766] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1267; }","duration":"222.633017ms","start":"2026-02-06T00:47:33.737141Z","end":"2026-02-06T00:47:33.959774Z","steps":["trace[794608766] 'range keys from in-memory index tree' (duration: 222.233457ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:47:34.493003Z","caller":"traceutil/trace.go:171","msg":"trace[1314663978] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"145.431545ms","start":"2026-02-06T00:47:34.347535Z","end":"2026-02-06T00:47:34.492967Z","steps":["trace[1314663978] 'process raft request' (duration: 145.295072ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:47:37.274627Z","caller":"traceutil/trace.go:171","msg":"trace[757215011] linearizableReadLoop","detail":"{readStateIndex:1336; appliedIndex:1335; }","duration":"109.692822ms","start":"2026-02-06T00:47:37.164912Z","end":"2026-02-06T00:47:37.274605Z","steps":["trace[757215011] 'read index received' (duration: 109.515568ms)","trace[757215011] 'applied index is now lower than readState.Index' (duration: 176.504µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:47:37.274994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.0649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:47:37.275164Z","caller":"traceutil/trace.go:171","msg":"trace[1651538619] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1280; }","duration":"110.271255ms","start":"2026-02-06T00:47:37.164881Z","end":"2026-02-06T00:47:37.275152Z","steps":["trace[1651538619] 'agreement among raft nodes before linearized reading' (duration: 110.069491ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:47:37.275404Z","caller":"traceutil/trace.go:171","msg":"trace[1671792265] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"286.462331ms","start":"2026-02-06T00:47:36.988928Z","end":"2026-02-06T00:47:37.275391Z","steps":["trace[1671792265] 'process raft request' (duration: 285.558511ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:47:37.799473Z","caller":"traceutil/trace.go:171","msg":"trace[267433581] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"107.447542ms","start":"2026-02-06T00:47:37.691999Z","end":"2026-02-06T00:47:37.799447Z","steps":["trace[267433581] 'process raft request' (duration: 73.904048ms)","trace[267433581] 'compare' (duration: 33.37387ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:47:38.497444Z","caller":"traceutil/trace.go:171","msg":"trace[166267656] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"183.952488ms","start":"2026-02-06T00:47:38.313279Z","end":"2026-02-06T00:47:38.497231Z","steps":["trace[166267656] 'process raft request' (duration: 167.213747ms)","trace[166267656] 'compare' (duration: 16.23908ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:49:51.403596Z","caller":"traceutil/trace.go:171","msg":"trace[70332164] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"105.210981ms","start":"2026-02-06T00:49:51.298352Z","end":"2026-02-06T00:49:51.403563Z","steps":["trace[70332164] 'process raft request' (duration: 105.082158ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:50:00.502657Z","caller":"traceutil/trace.go:171","msg":"trace[529199570] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"157.36169ms","start":"2026-02-06T00:50:00.345256Z","end":"2026-02-06T00:50:00.502617Z","steps":["trace[529199570] 'process raft request' (duration: 157.147428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:01.044892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.410805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-06T00:50:01.045007Z","caller":"traceutil/trace.go:171","msg":"trace[587097147] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1897; }","duration":"264.545236ms","start":"2026-02-06T00:50:00.780433Z","end":"2026-02-06T00:50:01.044978Z","steps":["trace[587097147] 'range keys from in-memory index tree' (duration: 264.153691ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:50:02.050733Z","caller":"traceutil/trace.go:171","msg":"trace[255692326] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"311.267486ms","start":"2026-02-06T00:50:01.73943Z","end":"2026-02-06T00:50:02.050698Z","steps":["trace[255692326] 'process raft request' (duration: 311.040583ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:02.050957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:50:01.739409Z","time spent":"311.422458ms","remote":"127.0.0.1:40230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3006,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:50:02.290667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.8052ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:3032"} {"level":"info","ts":"2026-02-06T00:50:02.290777Z","caller":"traceutil/trace.go:171","msg":"trace[141888495] linearizableReadLoop","detail":"{readStateIndex:1992; appliedIndex:1991; }","duration":"121.215054ms","start":"2026-02-06T00:50:02.169542Z","end":"2026-02-06T00:50:02.290757Z","steps":["trace[141888495] 'read index received' (duration: 27.71µs)","trace[141888495] 'applied index is now lower than readState.Index' (duration: 121.185974ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:50:02.290911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.365626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-06T00:50:02.29094Z","caller":"traceutil/trace.go:171","msg":"trace[414737841] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1905; }","duration":"121.433007ms","start":"2026-02-06T00:50:02.169494Z","end":"2026-02-06T00:50:02.290927Z","steps":["trace[414737841] 'agreement among raft nodes before linearized reading' (duration: 121.310795ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:50:02.29127Z","caller":"traceutil/trace.go:171","msg":"trace[626961795] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1905; }","duration":"235.519554ms","start":"2026-02-06T00:50:02.055731Z","end":"2026-02-06T00:50:02.291251Z","steps":["trace[626961795] 'process raft request' (duration: 84.035673ms)","trace[626961795] 'compare' (duration: 149.711518ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:50:02.668467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.857859ms","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-02-06T00:50:02.668544Z","caller":"traceutil/trace.go:171","msg":"trace[1552458368] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1907; }","duration":"160.99647ms","start":"2026-02-06T00:50:02.50753Z","end":"2026-02-06T00:50:02.668526Z","steps":["trace[1552458368] 'range keys from in-memory index tree' (duration: 160.634006ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:50:03.1788Z","caller":"traceutil/trace.go:171","msg":"trace[484088597] transaction","detail":"{read_only:false; response_revision:1909; number_of_response:1; }","duration":"101.446127ms","start":"2026-02-06T00:50:03.077334Z","end":"2026-02-06T00:50:03.17878Z","steps":["trace[484088597] 'process raft request' (duration: 101.112133ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:03.423159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.846871ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:50:03.423293Z","caller":"traceutil/trace.go:171","msg":"trace[1866430052] transaction","detail":"{read_only:false; response_revision:1913; number_of_response:1; }","duration":"232.422013ms","start":"2026-02-06T00:50:03.19086Z","end":"2026-02-06T00:50:03.423282Z","steps":["trace[1866430052] 'process raft request' (duration: 232.402903ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:50:03.423476Z","caller":"traceutil/trace.go:171","msg":"trace[613316307] transaction","detail":"{read_only:false; response_revision:1910; number_of_response:1; }","duration":"235.688278ms","start":"2026-02-06T00:50:03.187781Z","end":"2026-02-06T00:50:03.42347Z","steps":["trace[613316307] 'process raft request' (duration: 125.477532ms)","trace[613316307] 'compare' (duration: 109.717919ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:50:03.423572Z","caller":"traceutil/trace.go:171","msg":"trace[1856874606] transaction","detail":"{read_only:false; response_revision:1912; number_of_response:1; }","duration":"232.740327ms","start":"2026-02-06T00:50:03.19078Z","end":"2026-02-06T00:50:03.423521Z","steps":["trace[1856874606] 'process raft request' (duration: 232.466684ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:50:03.423717Z","caller":"traceutil/trace.go:171","msg":"trace[880025678] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"232.987111ms","start":"2026-02-06T00:50:03.190724Z","end":"2026-02-06T00:50:03.423711Z","steps":["trace[880025678] 'process raft request' (duration: 232.498234ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:50:04.502148Z","caller":"traceutil/trace.go:171","msg":"trace[1021721151] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"114.529673ms","start":"2026-02-06T00:50:04.387577Z","end":"2026-02-06T00:50:04.502107Z","steps":["trace[1021721151] 'process raft request' (duration: 55.037732ms)","trace[1021721151] 'compare' (duration: 59.237608ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:50:05.638668Z","caller":"traceutil/trace.go:171","msg":"trace[1330364573] transaction","detail":"{read_only:false; response_revision:1920; number_of_response:1; }","duration":"203.95271ms","start":"2026-02-06T00:50:05.434645Z","end":"2026-02-06T00:50:05.638598Z","steps":["trace[1330364573] 'process raft request' (duration: 201.619409ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:50:05.638721Z","caller":"traceutil/trace.go:171","msg":"trace[512401158] linearizableReadLoop","detail":"{readStateIndex:2008; appliedIndex:2006; }","duration":"201.921942ms","start":"2026-02-06T00:50:05.435586Z","end":"2026-02-06T00:50:05.637508Z","steps":["trace[512401158] 'read index received' (duration: 49.343694ms)","trace[512401158] 'applied index is now lower than readState.Index' (duration: 152.577218ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:50:05.638994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.382201ms","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-02-06T00:50:05.639037Z","caller":"traceutil/trace.go:171","msg":"trace[1642765576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1920; }","duration":"203.449903ms","start":"2026-02-06T00:50:05.435571Z","end":"2026-02-06T00:50:05.639021Z","steps":["trace[1642765576] 'agreement among raft nodes before linearized reading' (duration: 203.27903ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:05.639197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.00101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-06T00:50:05.639213Z","caller":"traceutil/trace.go:171","msg":"trace[1590863821] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1920; }","duration":"135.04549ms","start":"2026-02-06T00:50:05.504163Z","end":"2026-02-06T00:50:05.639208Z","steps":["trace[1590863821] 'agreement among raft nodes before linearized reading' (duration: 134.9983ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:05.639351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.348878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/default/\" range_end:\"/registry/secrets/default0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:50:05.63937Z","caller":"traceutil/trace.go:171","msg":"trace[1850261731] range","detail":"{range_begin:/registry/secrets/default/; range_end:/registry/secrets/default0; response_count:0; response_revision:1920; }","duration":"110.413688ms","start":"2026-02-06T00:50:05.528951Z","end":"2026-02-06T00:50:05.639365Z","steps":["trace[1850261731] 'agreement among raft nodes before linearized reading' (duration: 110.313767ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:05.882684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.715158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-public/\" range_end:\"/registry/secrets/kube-public0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:50:05.883136Z","caller":"traceutil/trace.go:171","msg":"trace[1127992878] range","detail":"{range_begin:/registry/secrets/kube-public/; range_end:/registry/secrets/kube-public0; response_count:0; response_revision:1922; }","duration":"124.209225ms","start":"2026-02-06T00:50:05.758874Z","end":"2026-02-06T00:50:05.883083Z","steps":["trace[1127992878] 'range keys from in-memory index tree' (duration: 123.563626ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:50:21.853831Z","caller":"traceutil/trace.go:171","msg":"trace[998190668] transaction","detail":"{read_only:false; response_revision:1990; number_of_response:1; }","duration":"112.935262ms","start":"2026-02-06T00:50:21.740874Z","end":"2026-02-06T00:50:21.85381Z","steps":["trace[998190668] 'process raft request' (duration: 85.760546ms)","trace[998190668] 'compare' (duration: 26.74686ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:50:21.853933Z","caller":"traceutil/trace.go:171","msg":"trace[980264998] linearizableReadLoop","detail":"{readStateIndex:2082; appliedIndex:2080; }","duration":"103.8668ms","start":"2026-02-06T00:50:21.750056Z","end":"2026-02-06T00:50:21.853923Z","steps":["trace[980264998] 'read index received' (duration: 76.545663ms)","trace[980264998] 'applied index is now lower than readState.Index' (duration: 27.320357ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:50:21.855076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.975736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/kube-node-lease/\" range_end:\"/registry/jobs/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:50:21.855125Z","caller":"traceutil/trace.go:171","msg":"trace[1677881177] range","detail":"{range_begin:/registry/jobs/kube-node-lease/; range_end:/registry/jobs/kube-node-lease0; response_count:0; response_revision:1991; }","duration":"105.091147ms","start":"2026-02-06T00:50:21.750022Z","end":"2026-02-06T00:50:21.855113Z","steps":["trace[1677881177] 'agreement among raft nodes before linearized reading' (duration: 104.975205ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:50:21.854629Z","caller":"traceutil/trace.go:171","msg":"trace[1861022076] transaction","detail":"{read_only:false; response_revision:1991; number_of_response:1; }","duration":"112.890782ms","start":"2026-02-06T00:50:21.741723Z","end":"2026-02-06T00:50:21.854614Z","steps":["trace[1861022076] 'process raft request' (duration: 111.777617ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:50:22.638985Z","caller":"traceutil/trace.go:171","msg":"trace[172706898] linearizableReadLoop","detail":"{readStateIndex:2083; appliedIndex:2082; }","duration":"186.733708ms","start":"2026-02-06T00:50:22.452199Z","end":"2026-02-06T00:50:22.638932Z","steps":["trace[172706898] 'read index received' (duration: 186.551646ms)","trace[172706898] 'applied index is now lower than readState.Index' (duration: 181.052µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:50:22.639268Z","caller":"traceutil/trace.go:171","msg":"trace[1728537699] transaction","detail":"{read_only:false; response_revision:1992; number_of_response:1; }","duration":"188.551403ms","start":"2026-02-06T00:50:22.450705Z","end":"2026-02-06T00:50:22.639256Z","steps":["trace[1728537699] 'process raft request' (duration: 188.091667ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:22.639622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.404707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/\" range_end:\"/registry/events/default0\" limit:500 ","response":"range_response_count:24 size:16571"} {"level":"info","ts":"2026-02-06T00:50:22.639654Z","caller":"traceutil/trace.go:171","msg":"trace[298653250] range","detail":"{range_begin:/registry/events/default/; range_end:/registry/events/default0; response_count:24; response_revision:1992; }","duration":"187.477818ms","start":"2026-02-06T00:50:22.452166Z","end":"2026-02-06T00:50:22.639644Z","steps":["trace[298653250] 'agreement among raft nodes before linearized reading' (duration: 187.250605ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:22.639817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.013448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/default/\" range_end:\"/registry/secrets/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:50:22.639842Z","caller":"traceutil/trace.go:171","msg":"trace[893030684] range","detail":"{range_begin:/registry/secrets/default/; range_end:/registry/secrets/default0; response_count:0; response_revision:1992; }","duration":"166.057188ms","start":"2026-02-06T00:50:22.473778Z","end":"2026-02-06T00:50:22.639835Z","steps":["trace[893030684] 'agreement among raft nodes before linearized reading' (duration: 166.011708ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:22.640046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.369757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/default/\" range_end:\"/registry/roles/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:50:22.640073Z","caller":"traceutil/trace.go:171","msg":"trace[1435628411] range","detail":"{range_begin:/registry/roles/default/; range_end:/registry/roles/default0; response_count:0; response_revision:1992; }","duration":"164.411227ms","start":"2026-02-06T00:50:22.475654Z","end":"2026-02-06T00:50:22.640065Z","steps":["trace[1435628411] 'agreement among raft nodes before linearized reading' (duration: 164.367317ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:22.837087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.981245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-02-06T00:50:22.837153Z","caller":"traceutil/trace.go:171","msg":"trace[48539962] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1992; }","duration":"131.081816ms","start":"2026-02-06T00:50:22.706055Z","end":"2026-02-06T00:50:22.837136Z","steps":["trace[48539962] 'count revisions from in-memory index tree' (duration: 130.916074ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:50:23.689355Z","caller":"traceutil/trace.go:171","msg":"trace[220500622] transaction","detail":"{read_only:false; response_revision:1996; number_of_response:1; }","duration":"117.464283ms","start":"2026-02-06T00:50:23.571875Z","end":"2026-02-06T00:50:23.689339Z","steps":["trace[220500622] 'process raft request' (duration: 117.117478ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:50:23.99873Z","caller":"traceutil/trace.go:171","msg":"trace[228391365] transaction","detail":"{read_only:false; response_revision:1998; number_of_response:1; }","duration":"136.117185ms","start":"2026-02-06T00:50:23.8626Z","end":"2026-02-06T00:50:23.998717Z","steps":["trace[228391365] 'process raft request' (duration: 63.016319ms)","trace[228391365] 'compare' (duration: 72.985674ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:50:23.998821Z","caller":"traceutil/trace.go:171","msg":"trace[1550894758] linearizableReadLoop","detail":"{readStateIndex:2090; appliedIndex:2088; }","duration":"113.267216ms","start":"2026-02-06T00:50:23.885537Z","end":"2026-02-06T00:50:23.998804Z","steps":["trace[1550894758] 'read index received' (duration: 40.08662ms)","trace[1550894758] 'applied index is now lower than readState.Index' (duration: 73.179796ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:50:23.998977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.428268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-02-06T00:50:23.99901Z","caller":"traceutil/trace.go:171","msg":"trace[1826881281] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1999; }","duration":"113.484889ms","start":"2026-02-06T00:50:23.885508Z","end":"2026-02-06T00:50:23.998992Z","steps":["trace[1826881281] 'agreement among raft nodes before linearized reading' (duration: 113.399977ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:50:23.999096Z","caller":"traceutil/trace.go:171","msg":"trace[1418365326] transaction","detail":"{read_only:false; response_revision:1999; number_of_response:1; }","duration":"136.159245ms","start":"2026-02-06T00:50:23.862931Z","end":"2026-02-06T00:50:23.99909Z","steps":["trace[1418365326] 'process raft request' (duration: 135.75524ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:24.277668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.936135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/ceph-config\" ","response":"range_response_count:1 size:899"} {"level":"info","ts":"2026-02-06T00:50:24.277757Z","caller":"traceutil/trace.go:171","msg":"trace[1018910564] range","detail":"{range_begin:/registry/configmaps/kube-system/ceph-config; range_end:; response_count:1; response_revision:2000; }","duration":"131.057007ms","start":"2026-02-06T00:50:24.146685Z","end":"2026-02-06T00:50:24.277742Z","steps":["trace[1018910564] 'range keys from in-memory index tree' (duration: 130.814543ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:24.277993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.06073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/\" range_end:\"/registry/daemonsets/kube-system0\" limit:500 ","response":"range_response_count:3 size:26532"} {"level":"info","ts":"2026-02-06T00:50:24.278022Z","caller":"traceutil/trace.go:171","msg":"trace[1402812245] range","detail":"{range_begin:/registry/daemonsets/kube-system/; range_end:/registry/daemonsets/kube-system0; response_count:3; response_revision:2000; }","duration":"129.09259ms","start":"2026-02-06T00:50:24.14892Z","end":"2026-02-06T00:50:24.278013Z","steps":["trace[1402812245] 'range keys from in-memory index tree' (duration: 128.954658ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:24.917757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.003305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/coredns\" ","response":"range_response_count:1 size:612"} {"level":"info","ts":"2026-02-06T00:50:24.918701Z","caller":"traceutil/trace.go:171","msg":"trace[991724930] range","detail":"{range_begin:/registry/configmaps/kube-system/coredns; range_end:; response_count:1; response_revision:2002; }","duration":"148.988538ms","start":"2026-02-06T00:50:24.769693Z","end":"2026-02-06T00:50:24.918682Z","steps":["trace[991724930] 'range keys from in-memory index tree' (duration: 147.935604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:24.917812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.50216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:1 size:6534"} {"level":"info","ts":"2026-02-06T00:50:24.919301Z","caller":"traceutil/trace.go:171","msg":"trace[1901880092] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:1; response_revision:2002; }","duration":"141.010402ms","start":"2026-02-06T00:50:24.778276Z","end":"2026-02-06T00:50:24.919287Z","steps":["trace[1901880092] 'range keys from in-memory index tree' (duration: 139.45635ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:50:25.069202Z","caller":"traceutil/trace.go:171","msg":"trace[84043399] transaction","detail":"{read_only:false; response_revision:2003; number_of_response:1; }","duration":"147.772792ms","start":"2026-02-06T00:50:24.921406Z","end":"2026-02-06T00:50:25.069179Z","steps":["trace[84043399] 'process raft request' (duration: 147.66531ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:50:25.069213Z","caller":"traceutil/trace.go:171","msg":"trace[1891025813] linearizableReadLoop","detail":"{readStateIndex:2094; appliedIndex:2093; }","duration":"187.348766ms","start":"2026-02-06T00:50:24.881841Z","end":"2026-02-06T00:50:25.069189Z","steps":["trace[1891025813] 'read index received' (duration: 125.175228ms)","trace[1891025813] 'applied index is now lower than readState.Index' (duration: 62.171118ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:50:25.069382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.524328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/kube-system/\" range_end:\"/registry/ingress/kube-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:50:25.069415Z","caller":"traceutil/trace.go:171","msg":"trace[1319268166] range","detail":"{range_begin:/registry/ingress/kube-system/; range_end:/registry/ingress/kube-system0; response_count:0; response_revision:2003; }","duration":"187.603489ms","start":"2026-02-06T00:50:24.881801Z","end":"2026-02-06T00:50:25.069404Z","steps":["trace[1319268166] 'agreement among raft nodes before linearized reading' (duration: 187.501157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:25.069684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.897558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:1 size:6534"} {"level":"info","ts":"2026-02-06T00:50:25.069714Z","caller":"traceutil/trace.go:171","msg":"trace[3694670] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:1; response_revision:2003; }","duration":"134.927419ms","start":"2026-02-06T00:50:24.934777Z","end":"2026-02-06T00:50:25.069705Z","steps":["trace[3694670] 'agreement among raft nodes before linearized reading' (duration: 134.860938ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:28.009944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.036374ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:50:28.010055Z","caller":"traceutil/trace.go:171","msg":"trace[1031583653] linearizableReadLoop","detail":"{readStateIndex:2103; appliedIndex:2102; }","duration":"121.789561ms","start":"2026-02-06T00:50:27.888251Z","end":"2026-02-06T00:50:28.01004Z","steps":["trace[1031583653] 'read index received' (duration: 22.32µs)","trace[1031583653] 'applied index is now lower than readState.Index' (duration: 121.766141ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:50:28.010267Z","caller":"traceutil/trace.go:171","msg":"trace[1094740492] transaction","detail":"{read_only:false; response_revision:2011; number_of_response:1; }","duration":"182.293337ms","start":"2026-02-06T00:50:27.82796Z","end":"2026-02-06T00:50:28.010253Z","steps":["trace[1094740492] 'process raft request' (duration: 45.880858ms)","trace[1094740492] 'compare' (duration: 135.821411ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:50:28.010501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.245257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-snv2r\" ","response":"range_response_count:1 size:18058"} {"level":"info","ts":"2026-02-06T00:50:28.010538Z","caller":"traceutil/trace.go:171","msg":"trace[740504895] range","detail":"{range_begin:/registry/pods/kube-system/cilium-snv2r; range_end:; response_count:1; response_revision:2011; }","duration":"122.325969ms","start":"2026-02-06T00:50:27.888203Z","end":"2026-02-06T00:50:28.010529Z","steps":["trace[740504895] 'agreement among raft nodes before linearized reading' (duration: 122.212307ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:28.010747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.682308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-4xbzc\" ","response":"range_response_count:1 size:4847"} {"level":"info","ts":"2026-02-06T00:50:28.010783Z","caller":"traceutil/trace.go:171","msg":"trace[954512056] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-4xbzc; range_end:; response_count:1; response_revision:2011; }","duration":"103.745448ms","start":"2026-02-06T00:50:27.907029Z","end":"2026-02-06T00:50:28.010775Z","steps":["trace[954512056] 'agreement among raft nodes before linearized reading' (duration: 103.642077ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:50:28.160441Z","caller":"traceutil/trace.go:171","msg":"trace[213134644] transaction","detail":"{read_only:false; response_revision:2012; number_of_response:1; }","duration":"133.311048ms","start":"2026-02-06T00:50:28.027108Z","end":"2026-02-06T00:50:28.160419Z","steps":["trace[213134644] 'process raft request' (duration: 85.137388ms)","trace[213134644] 'compare' (duration: 47.887026ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:50:28.211628Z","caller":"traceutil/trace.go:171","msg":"trace[65280544] linearizableReadLoop","detail":"{readStateIndex:2105; appliedIndex:2103; }","duration":"179.779902ms","start":"2026-02-06T00:50:28.031832Z","end":"2026-02-06T00:50:28.211611Z","steps":["trace[65280544] 'read index received' (duration: 80.421703ms)","trace[65280544] 'applied index is now lower than readState.Index' (duration: 99.357389ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:50:28.211912Z","caller":"traceutil/trace.go:171","msg":"trace[1502993615] transaction","detail":"{read_only:false; response_revision:2013; number_of_response:1; }","duration":"184.090422ms","start":"2026-02-06T00:50:28.027808Z","end":"2026-02-06T00:50:28.211898Z","steps":["trace[1502993615] 'process raft request' (duration: 183.731518ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:28.21284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.159782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-4xbzc\" ","response":"range_response_count:1 size:4847"} {"level":"info","ts":"2026-02-06T00:50:28.212901Z","caller":"traceutil/trace.go:171","msg":"trace[121717465] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-4xbzc; range_end:; response_count:1; response_revision:2013; }","duration":"118.250513ms","start":"2026-02-06T00:50:28.094634Z","end":"2026-02-06T00:50:28.212884Z","steps":["trace[121717465] 'agreement among raft nodes before linearized reading' (duration: 117.325521ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:28.213277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.878948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:50:28.213309Z","caller":"traceutil/trace.go:171","msg":"trace[658116140] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:2013; }","duration":"111.932349ms","start":"2026-02-06T00:50:28.101367Z","end":"2026-02-06T00:50:28.213299Z","steps":["trace[658116140] 'agreement among raft nodes before linearized reading' (duration: 111.883348ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:28.213916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.059384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:138 size:105743"} {"level":"info","ts":"2026-02-06T00:50:28.214115Z","caller":"traceutil/trace.go:171","msg":"trace[2100505561] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:138; response_revision:2013; }","duration":"182.281787ms","start":"2026-02-06T00:50:28.031819Z","end":"2026-02-06T00:50:28.2141Z","steps":["trace[2100505561] 'agreement among raft nodes before linearized reading' (duration: 180.765586ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:28.379331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.906376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-02-06T00:50:28.37976Z","caller":"traceutil/trace.go:171","msg":"trace[282538486] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2014; }","duration":"117.344382ms","start":"2026-02-06T00:50:28.262366Z","end":"2026-02-06T00:50:28.37971Z","steps":["trace[282538486] 'range keys from in-memory index tree' (duration: 116.797725ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:50:28.675764Z","caller":"traceutil/trace.go:171","msg":"trace[440998299] linearizableReadLoop","detail":"{readStateIndex:2108; appliedIndex:2107; }","duration":"188.781865ms","start":"2026-02-06T00:50:28.486963Z","end":"2026-02-06T00:50:28.675745Z","steps":["trace[440998299] 'read index received' (duration: 141.843532ms)","trace[440998299] 'applied index is now lower than readState.Index' (duration: 46.937463ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:50:28.676047Z","caller":"traceutil/trace.go:171","msg":"trace[1851304965] transaction","detail":"{read_only:false; response_revision:2016; number_of_response:1; }","duration":"196.63531ms","start":"2026-02-06T00:50:28.479398Z","end":"2026-02-06T00:50:28.676033Z","steps":["trace[1851304965] 'process raft request' (duration: 149.476274ms)","trace[1851304965] 'compare' (duration: 46.749841ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:50:28.676284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.779553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7036"} {"level":"warn","ts":"2026-02-06T00:50:28.676462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.461534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-87hxt\" ","response":"range_response_count:1 size:4844"} {"level":"info","ts":"2026-02-06T00:50:28.677797Z","caller":"traceutil/trace.go:171","msg":"trace[264613944] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-87hxt; range_end:; response_count:1; response_revision:2016; }","duration":"190.866593ms","start":"2026-02-06T00:50:28.486917Z","end":"2026-02-06T00:50:28.677783Z","steps":["trace[264613944] 'agreement among raft nodes before linearized reading' (duration: 189.427764ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:50:28.677704Z","caller":"traceutil/trace.go:171","msg":"trace[401302319] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:2016; }","duration":"132.221332ms","start":"2026-02-06T00:50:28.545459Z","end":"2026-02-06T00:50:28.67768Z","steps":["trace[401302319] 'agreement among raft nodes before linearized reading' (duration: 130.718962ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:28.939672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.187927ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:50:28.939863Z","caller":"traceutil/trace.go:171","msg":"trace[1783662050] linearizableReadLoop","detail":"{readStateIndex:2109; appliedIndex:2108; }","duration":"109.903671ms","start":"2026-02-06T00:50:28.829911Z","end":"2026-02-06T00:50:28.939815Z","steps":["trace[1783662050] 'read index received' (duration: 19.34µs)","trace[1783662050] 'applied index is now lower than readState.Index' (duration: 109.882891ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:50:28.940182Z","caller":"traceutil/trace.go:171","msg":"trace[1455914092] transaction","detail":"{read_only:false; response_revision:2017; number_of_response:1; }","duration":"203.23519ms","start":"2026-02-06T00:50:28.73693Z","end":"2026-02-06T00:50:28.940165Z","steps":["trace[1455914092] 'process raft request' (duration: 83.411654ms)","trace[1455914092] 'compare' (duration: 119.070966ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:50:28.940524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.60528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7036"} {"level":"info","ts":"2026-02-06T00:50:28.940628Z","caller":"traceutil/trace.go:171","msg":"trace[862766123] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:2017; }","duration":"110.735552ms","start":"2026-02-06T00:50:28.82988Z","end":"2026-02-06T00:50:28.940616Z","steps":["trace[862766123] 'agreement among raft nodes before linearized reading' (duration: 110.594321ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:28.940922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.749242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6684"} {"level":"info","ts":"2026-02-06T00:50:28.940995Z","caller":"traceutil/trace.go:171","msg":"trace[1463329036] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:2017; }","duration":"110.841643ms","start":"2026-02-06T00:50:28.830137Z","end":"2026-02-06T00:50:28.940979Z","steps":["trace[1463329036] 'agreement among raft nodes before linearized reading' (duration: 110.673071ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:29.165932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.350711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-pm4hw\" ","response":"range_response_count:1 size:4411"} {"level":"info","ts":"2026-02-06T00:50:29.166096Z","caller":"traceutil/trace.go:171","msg":"trace[1413863327] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-pm4hw; range_end:; response_count:1; response_revision:2017; }","duration":"108.521813ms","start":"2026-02-06T00:50:29.057559Z","end":"2026-02-06T00:50:29.166081Z","steps":["trace[1413863327] 'range keys from in-memory index tree' (duration: 108.29636ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:29.165473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.682285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:138 size:105743"} {"level":"info","ts":"2026-02-06T00:50:29.166479Z","caller":"traceutil/trace.go:171","msg":"trace[2083010368] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:138; response_revision:2017; }","duration":"115.716839ms","start":"2026-02-06T00:50:29.050751Z","end":"2026-02-06T00:50:29.166468Z","steps":["trace[2083010368] 'range keys from in-memory index tree' (duration: 114.171998ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:50:29.319905Z","caller":"traceutil/trace.go:171","msg":"trace[557055625] transaction","detail":"{read_only:false; response_revision:2018; number_of_response:1; }","duration":"144.120604ms","start":"2026-02-06T00:50:29.175771Z","end":"2026-02-06T00:50:29.319891Z","steps":["trace[557055625] 'process raft request' (duration: 144.043883ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:50:29.528785Z","caller":"traceutil/trace.go:171","msg":"trace[51202299] linearizableReadLoop","detail":"{readStateIndex:2111; appliedIndex:2110; }","duration":"219.933004ms","start":"2026-02-06T00:50:29.308833Z","end":"2026-02-06T00:50:29.528766Z","steps":["trace[51202299] 'read index received' (duration: 11.305392ms)","trace[51202299] 'applied index is now lower than readState.Index' (duration: 208.626712ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:50:29.528978Z","caller":"traceutil/trace.go:171","msg":"trace[655488775] transaction","detail":"{read_only:false; response_revision:2019; number_of_response:1; }","duration":"352.841077ms","start":"2026-02-06T00:50:29.176119Z","end":"2026-02-06T00:50:29.52896Z","steps":["trace[655488775] 'process raft request' (duration: 352.528012ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:29.529079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:50:29.176114Z","time spent":"352.911367ms","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:50:29.529134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.188757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4064"} {"level":"info","ts":"2026-02-06T00:50:29.529195Z","caller":"traceutil/trace.go:171","msg":"trace[269174540] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:2019; }","duration":"220.38027ms","start":"2026-02-06T00:50:29.308802Z","end":"2026-02-06T00:50:29.529182Z","steps":["trace[269174540] 'agreement among raft nodes before linearized reading' (duration: 220.178608ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:29.529311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.005492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-pm4hw\" ","response":"range_response_count:1 size:4411"} {"level":"info","ts":"2026-02-06T00:50:29.529343Z","caller":"traceutil/trace.go:171","msg":"trace[1996034385] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-pm4hw; range_end:; response_count:1; response_revision:2019; }","duration":"196.054692ms","start":"2026-02-06T00:50:29.33328Z","end":"2026-02-06T00:50:29.529334Z","steps":["trace[1996034385] 'agreement among raft nodes before linearized reading' (duration: 195.996652ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:29.784745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.039774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:138 size:105743"} {"level":"info","ts":"2026-02-06T00:50:29.786474Z","caller":"traceutil/trace.go:171","msg":"trace[229292380] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:138; response_revision:2021; }","duration":"157.797897ms","start":"2026-02-06T00:50:29.628659Z","end":"2026-02-06T00:50:29.786457Z","steps":["trace[229292380] 'agreement among raft nodes before linearized reading' (duration: 155.308073ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:50:29.785801Z","caller":"traceutil/trace.go:171","msg":"trace[1385053851] transaction","detail":"{read_only:false; response_revision:2021; number_of_response:1; }","duration":"158.973203ms","start":"2026-02-06T00:50:29.626807Z","end":"2026-02-06T00:50:29.785781Z","steps":["trace[1385053851] 'process raft request' (duration: 127.770332ms)","trace[1385053851] 'compare' (duration: 29.246604ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:50:30.412733Z","caller":"traceutil/trace.go:171","msg":"trace[1028783735] linearizableReadLoop","detail":"{readStateIndex:2117; appliedIndex:2116; }","duration":"224.2544ms","start":"2026-02-06T00:50:30.188457Z","end":"2026-02-06T00:50:30.412711Z","steps":["trace[1028783735] 'read index received' (duration: 152.930491ms)","trace[1028783735] 'applied index is now lower than readState.Index' (duration: 71.322719ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:50:30.41321Z","caller":"traceutil/trace.go:171","msg":"trace[1007580714] transaction","detail":"{read_only:false; response_revision:2024; number_of_response:1; }","duration":"246.089681ms","start":"2026-02-06T00:50:30.167071Z","end":"2026-02-06T00:50:30.413161Z","steps":["trace[1007580714] 'process raft request' (duration: 174.377634ms)","trace[1007580714] 'compare' (duration: 71.163725ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:50:30.413419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.941895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-public/system:controller:bootstrap-signer\" ","response":"range_response_count:1 size:709"} {"level":"info","ts":"2026-02-06T00:50:30.413899Z","caller":"traceutil/trace.go:171","msg":"trace[1468949099] range","detail":"{range_begin:/registry/roles/kube-public/system:controller:bootstrap-signer; range_end:; response_count:1; response_revision:2024; }","duration":"225.453607ms","start":"2026-02-06T00:50:30.188432Z","end":"2026-02-06T00:50:30.413885Z","steps":["trace[1468949099] 'agreement among raft nodes before linearized reading' (duration: 224.925655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:30.414479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.885753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-public/default\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-02-06T00:50:30.414601Z","caller":"traceutil/trace.go:171","msg":"trace[1568165859] range","detail":"{range_begin:/registry/serviceaccounts/kube-public/default; range_end:; response_count:1; response_revision:2024; }","duration":"199.022846ms","start":"2026-02-06T00:50:30.215545Z","end":"2026-02-06T00:50:30.414568Z","steps":["trace[1568165859] 'agreement among raft nodes before linearized reading' (duration: 198.865173ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:30.414927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.998003ms","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-02-06T00:50:30.415754Z","caller":"traceutil/trace.go:171","msg":"trace[120799310] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2024; }","duration":"198.810311ms","start":"2026-02-06T00:50:30.216904Z","end":"2026-02-06T00:50:30.415714Z","steps":["trace[120799310] 'agreement among raft nodes before linearized reading' (duration: 197.921291ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:50:30.61558Z","caller":"traceutil/trace.go:171","msg":"trace[1664077680] linearizableReadLoop","detail":"{readStateIndex:2118; appliedIndex:2117; }","duration":"115.890077ms","start":"2026-02-06T00:50:30.499669Z","end":"2026-02-06T00:50:30.615559Z","steps":["trace[1664077680] 'read index received' (duration: 75.458099ms)","trace[1664077680] 'applied index is now lower than readState.Index' (duration: 40.431018ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:50:30.616083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.388618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-public/default\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-02-06T00:50:30.617004Z","caller":"traceutil/trace.go:171","msg":"trace[1578107996] range","detail":"{range_begin:/registry/serviceaccounts/kube-public/default; range_end:; response_count:1; response_revision:2025; }","duration":"117.34534ms","start":"2026-02-06T00:50:30.499643Z","end":"2026-02-06T00:50:30.616988Z","steps":["trace[1578107996] 'agreement among raft nodes before linearized reading' (duration: 116.313207ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:50:30.61645Z","caller":"traceutil/trace.go:171","msg":"trace[1006469597] transaction","detail":"{read_only:false; response_revision:2025; number_of_response:1; }","duration":"188.086505ms","start":"2026-02-06T00:50:30.428308Z","end":"2026-02-06T00:50:30.616394Z","steps":["trace[1006469597] 'process raft request' (duration: 146.87145ms)","trace[1006469597] 'compare' (duration: 40.259335ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:50:30.616869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.614206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/ceph-csi-rbd-provisioner\" ","response":"range_response_count:1 size:951"} {"level":"info","ts":"2026-02-06T00:50:30.618153Z","caller":"traceutil/trace.go:171","msg":"trace[1991543342] range","detail":"{range_begin:/registry/rolebindings/kube-system/ceph-csi-rbd-provisioner; range_end:; response_count:1; response_revision:2025; }","duration":"103.925635ms","start":"2026-02-06T00:50:30.514211Z","end":"2026-02-06T00:50:30.618136Z","steps":["trace[1991543342] 'agreement among raft nodes before linearized reading' (duration: 102.512554ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:32.232418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.682279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-06T00:50:32.232496Z","caller":"traceutil/trace.go:171","msg":"trace[653441870] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2028; }","duration":"365.767861ms","start":"2026-02-06T00:50:31.86671Z","end":"2026-02-06T00:50:32.232478Z","steps":["trace[653441870] 'range keys from in-memory index tree' (duration: 365.515816ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:32.232531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:50:31.866673Z","time spent":"365.850253ms","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-06T00:50:32.232594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.294324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/ceph-csi-rbd-provisioner\" ","response":"range_response_count:1 size:930"} {"level":"info","ts":"2026-02-06T00:50:32.232663Z","caller":"traceutil/trace.go:171","msg":"trace[320623245] range","detail":"{range_begin:/registry/roles/kube-system/ceph-csi-rbd-provisioner; range_end:; response_count:1; response_revision:2028; }","duration":"306.398377ms","start":"2026-02-06T00:50:31.926246Z","end":"2026-02-06T00:50:32.232644Z","steps":["trace[320623245] 'range keys from in-memory index tree' (duration: 306.10477ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:32.232705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:50:31.926218Z","time spent":"306.478168ms","remote":"127.0.0.1:40384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":954,"request content":"key:\"/registry/roles/kube-system/ceph-csi-rbd-provisioner\" "} {"level":"warn","ts":"2026-02-06T00:50:32.232765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.770331ms","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-02-06T00:50:32.232797Z","caller":"traceutil/trace.go:171","msg":"trace[677968836] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2028; }","duration":"118.801802ms","start":"2026-02-06T00:50:32.113986Z","end":"2026-02-06T00:50:32.232788Z","steps":["trace[677968836] 'range keys from in-memory index tree' (duration: 118.651178ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:32.232923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.289056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system:controller:token-cleaner\" ","response":"range_response_count:1 size:729"} {"level":"info","ts":"2026-02-06T00:50:32.23296Z","caller":"traceutil/trace.go:171","msg":"trace[334088166] range","detail":"{range_begin:/registry/rolebindings/kube-system/system:controller:token-cleaner; range_end:; response_count:1; response_revision:2028; }","duration":"342.341297ms","start":"2026-02-06T00:50:31.890604Z","end":"2026-02-06T00:50:32.232945Z","steps":["trace[334088166] 'range keys from in-memory index tree' (duration: 342.244814ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:32.233002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:50:31.890594Z","time spent":"342.401308ms","remote":"127.0.0.1:40400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":1,"response size":753,"request content":"key:\"/registry/rolebindings/kube-system/system:controller:token-cleaner\" "} {"level":"info","ts":"2026-02-06T00:50:32.720189Z","caller":"traceutil/trace.go:171","msg":"trace[1511858550] transaction","detail":"{read_only:false; response_revision:2029; number_of_response:1; }","duration":"478.706105ms","start":"2026-02-06T00:50:32.241446Z","end":"2026-02-06T00:50:32.720152Z","steps":["trace[1511858550] 'process raft request' (duration: 478.48479ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:50:32.720408Z","caller":"traceutil/trace.go:171","msg":"trace[473094359] linearizableReadLoop","detail":"{readStateIndex:2122; appliedIndex:2122; }","duration":"478.232744ms","start":"2026-02-06T00:50:32.242148Z","end":"2026-02-06T00:50:32.720381Z","steps":["trace[473094359] 'read index received' (duration: 478.221614ms)","trace[473094359] 'applied index is now lower than readState.Index' (duration: 9.06µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:50:32.720499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:50:32.241431Z","time spent":"478.892579ms","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:50:32.720591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.402499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:50:32.720641Z","caller":"traceutil/trace.go:171","msg":"trace[532523160] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2029; }","duration":"478.48503ms","start":"2026-02-06T00:50:32.242145Z","end":"2026-02-06T00:50:32.72063Z","steps":["trace[532523160] 'agreement among raft nodes before linearized reading' (duration: 478.335006ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:32.720672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:50:32.242114Z","time spent":"478.547671ms","remote":"127.0.0.1:40020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T00:50:32.729678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.028219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/ceph-csi-rbd-provisioner\" ","response":"range_response_count:1 size:930"} {"level":"info","ts":"2026-02-06T00:50:32.729727Z","caller":"traceutil/trace.go:171","msg":"trace[771927796] range","detail":"{range_begin:/registry/roles/kube-system/ceph-csi-rbd-provisioner; range_end:; response_count:1; response_revision:2030; }","duration":"417.105251ms","start":"2026-02-06T00:50:32.312608Z","end":"2026-02-06T00:50:32.729713Z","steps":["trace[771927796] 'agreement among raft nodes before linearized reading' (duration: 416.932877ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:32.729754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:50:32.312595Z","time spent":"417.151772ms","remote":"127.0.0.1:40384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":954,"request content":"key:\"/registry/roles/kube-system/ceph-csi-rbd-provisioner\" "} {"level":"info","ts":"2026-02-06T00:50:32.729987Z","caller":"traceutil/trace.go:171","msg":"trace[2047101926] transaction","detail":"{read_only:false; response_revision:2030; number_of_response:1; }","duration":"485.456323ms","start":"2026-02-06T00:50:32.244522Z","end":"2026-02-06T00:50:32.729978Z","steps":["trace[2047101926] 'process raft request' (duration: 484.908331ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:32.730069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:50:32.244503Z","time spent":"485.499794ms","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:50:32.730202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.336938ms","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-02-06T00:50:32.730217Z","caller":"traceutil/trace.go:171","msg":"trace[1811435163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2030; }","duration":"106.41313ms","start":"2026-02-06T00:50:32.623799Z","end":"2026-02-06T00:50:32.730212Z","steps":["trace[1811435163] 'agreement among raft nodes before linearized reading' (duration: 106.360598ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:32.730312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.780043ms","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-02-06T00:50:32.730327Z","caller":"traceutil/trace.go:171","msg":"trace[400238923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2030; }","duration":"310.852474ms","start":"2026-02-06T00:50:32.41947Z","end":"2026-02-06T00:50:32.730322Z","steps":["trace[400238923] 'agreement among raft nodes before linearized reading' (duration: 310.806183ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:32.730353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:50:32.419439Z","time spent":"310.910106ms","remote":"127.0.0.1:40308","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-02-06T00:50:32.730449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.135648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T00:50:32.730465Z","caller":"traceutil/trace.go:171","msg":"trace[2031671880] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:2030; }","duration":"365.173248ms","start":"2026-02-06T00:50:32.365286Z","end":"2026-02-06T00:50:32.730459Z","steps":["trace[2031671880] 'agreement among raft nodes before linearized reading' (duration: 365.142047ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:32.730481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:50:32.365275Z","time spent":"365.198959ms","remote":"127.0.0.1:40238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":4,"response size":31,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-02-06T00:50:32.730612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.047863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/cilium-config-agent\" ","response":"range_response_count:1 size:695"} {"level":"info","ts":"2026-02-06T00:50:32.730628Z","caller":"traceutil/trace.go:171","msg":"trace[1698970119] range","detail":"{range_begin:/registry/roles/kube-system/cilium-config-agent; range_end:; response_count:1; response_revision:2030; }","duration":"389.084144ms","start":"2026-02-06T00:50:32.341538Z","end":"2026-02-06T00:50:32.730623Z","steps":["trace[1698970119] 'agreement among raft nodes before linearized reading' (duration: 389.047004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:32.730641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:50:32.341527Z","time spent":"389.111025ms","remote":"127.0.0.1:40384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":719,"request content":"key:\"/registry/roles/kube-system/cilium-config-agent\" "} {"level":"info","ts":"2026-02-06T00:50:39.617009Z","caller":"traceutil/trace.go:171","msg":"trace[1114366412] transaction","detail":"{read_only:false; response_revision:2060; number_of_response:1; }","duration":"101.575512ms","start":"2026-02-06T00:50:39.515412Z","end":"2026-02-06T00:50:39.616988Z","steps":["trace[1114366412] 'process raft request' (duration: 39.59035ms)","trace[1114366412] 'compare' (duration: 61.820289ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:50:39.874544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.857763ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:50:39.875525Z","caller":"traceutil/trace.go:171","msg":"trace[797678246] linearizableReadLoop","detail":"{readStateIndex:2156; appliedIndex:2155; }","duration":"162.523353ms","start":"2026-02-06T00:50:39.712981Z","end":"2026-02-06T00:50:39.875504Z","steps":["trace[797678246] 'read index received' (duration: 42.562035ms)","trace[797678246] 'applied index is now lower than readState.Index' (duration: 119.959578ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:50:39.875962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.150026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-06T00:50:39.876129Z","caller":"traceutil/trace.go:171","msg":"trace[1571221209] transaction","detail":"{read_only:false; response_revision:2061; number_of_response:1; }","duration":"246.683533ms","start":"2026-02-06T00:50:39.629343Z","end":"2026-02-06T00:50:39.876027Z","steps":["trace[1571221209] 'process raft request' (duration: 126.248365ms)","trace[1571221209] 'compare' (duration: 118.497545ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:50:39.876274Z","caller":"traceutil/trace.go:171","msg":"trace[509221040] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:2061; }","duration":"147.398852ms","start":"2026-02-06T00:50:39.728776Z","end":"2026-02-06T00:50:39.876175Z","steps":["trace[509221040] 'agreement among raft nodes before linearized reading' (duration: 147.118415ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:39.875962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.951172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-06T00:50:39.876578Z","caller":"traceutil/trace.go:171","msg":"trace[2125183957] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:2061; }","duration":"163.635437ms","start":"2026-02-06T00:50:39.712926Z","end":"2026-02-06T00:50:39.876562Z","steps":["trace[2125183957] 'agreement among raft nodes before linearized reading' (duration: 162.945482ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:40.465155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.58014ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:50:40.466008Z","caller":"traceutil/trace.go:171","msg":"trace[1309204375] linearizableReadLoop","detail":"{readStateIndex:2157; appliedIndex:2156; }","duration":"537.957897ms","start":"2026-02-06T00:50:39.927515Z","end":"2026-02-06T00:50:40.465473Z","steps":["trace[1309204375] 'read index received' (duration: 119.904436ms)","trace[1309204375] 'applied index is now lower than readState.Index' (duration: 418.051791ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:50:40.466612Z","caller":"traceutil/trace.go:171","msg":"trace[1288697949] transaction","detail":"{read_only:false; response_revision:2062; number_of_response:1; }","duration":"551.546496ms","start":"2026-02-06T00:50:39.915009Z","end":"2026-02-06T00:50:40.466555Z","steps":["trace[1288697949] 'process raft request' (duration: 132.491073ms)","trace[1288697949] 'compare' (duration: 417.286314ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:50:40.467215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:50:39.914988Z","time spent":"552.154649ms","remote":"127.0.0.1:40308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:50:40.467781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.376759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:50:40.46885Z","caller":"traceutil/trace.go:171","msg":"trace[384515677] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2062; }","duration":"304.437902ms","start":"2026-02-06T00:50:40.164394Z","end":"2026-02-06T00:50:40.468832Z","steps":["trace[384515677] 'agreement among raft nodes before linearized reading' (duration: 303.357628ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:40.469043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:50:40.16431Z","time spent":"304.644506ms","remote":"127.0.0.1:40012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T00:50:40.467809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.256567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:138 size:105743"} {"level":"info","ts":"2026-02-06T00:50:40.469386Z","caller":"traceutil/trace.go:171","msg":"trace[1209696905] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:138; response_revision:2062; }","duration":"541.891364ms","start":"2026-02-06T00:50:39.927481Z","end":"2026-02-06T00:50:40.469372Z","steps":["trace[1209696905] 'agreement among raft nodes before linearized reading' (duration: 539.274147ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:40.46952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:50:39.927471Z","time spent":"542.028807ms","remote":"127.0.0.1:40094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":138,"response size":105767,"request content":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 "} {"level":"warn","ts":"2026-02-06T00:50:40.468754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.506259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:138 size:105743"} {"level":"info","ts":"2026-02-06T00:50:40.470496Z","caller":"traceutil/trace.go:171","msg":"trace[1614065517] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:138; response_revision:2062; }","duration":"540.257337ms","start":"2026-02-06T00:50:39.930224Z","end":"2026-02-06T00:50:40.470481Z","steps":["trace[1614065517] 'agreement among raft nodes before linearized reading' (duration: 537.238981ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:40.470709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:50:39.930218Z","time spent":"540.411872ms","remote":"127.0.0.1:40094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":138,"response size":105767,"request content":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 "} {"level":"warn","ts":"2026-02-06T00:50:40.747048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.14595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-controller\" ","response":"range_response_count:1 size:201"} {"level":"info","ts":"2026-02-06T00:50:40.747164Z","caller":"traceutil/trace.go:171","msg":"trace[2063803166] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-controller; range_end:; response_count:1; response_revision:2062; }","duration":"144.342654ms","start":"2026-02-06T00:50:40.602795Z","end":"2026-02-06T00:50:40.747138Z","steps":["trace[2063803166] 'range keys from in-memory index tree' (duration: 143.989517ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:40.747471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.57545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-02-06T00:50:40.74756Z","caller":"traceutil/trace.go:171","msg":"trace[892246612] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:2062; }","duration":"175.733904ms","start":"2026-02-06T00:50:40.571803Z","end":"2026-02-06T00:50:40.747537Z","steps":["trace[892246612] 'range keys from in-memory index tree' (duration: 175.460438ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:50:41.072122Z","caller":"traceutil/trace.go:171","msg":"trace[1451115922] transaction","detail":"{read_only:false; response_revision:2065; number_of_response:1; }","duration":"160.08368ms","start":"2026-02-06T00:50:40.912006Z","end":"2026-02-06T00:50:41.07209Z","steps":["trace[1451115922] 'process raft request' (duration: 159.874485ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:50:41.072945Z","caller":"traceutil/trace.go:171","msg":"trace[1183254659] linearizableReadLoop","detail":"{readStateIndex:2160; appliedIndex:2160; }","duration":"160.734414ms","start":"2026-02-06T00:50:40.912192Z","end":"2026-02-06T00:50:41.072926Z","steps":["trace[1183254659] 'read index received' (duration: 160.723944ms)","trace[1183254659] 'applied index is now lower than readState.Index' (duration: 7.34µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:50:41.073415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.186704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" limit:500 ","response":"range_response_count:6 size:363642"} {"level":"info","ts":"2026-02-06T00:50:41.073463Z","caller":"traceutil/trace.go:171","msg":"trace[1396962073] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:2065; }","duration":"161.316546ms","start":"2026-02-06T00:50:40.912135Z","end":"2026-02-06T00:50:41.073451Z","steps":["trace[1396962073] 'agreement among raft nodes before linearized reading' (duration: 160.873587ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:50:41.08131Z","caller":"traceutil/trace.go:171","msg":"trace[1766980922] transaction","detail":"{read_only:false; response_revision:2066; number_of_response:1; }","duration":"168.874423ms","start":"2026-02-06T00:50:40.912404Z","end":"2026-02-06T00:50:41.081278Z","steps":["trace[1766980922] 'process raft request' (duration: 168.704249ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:50:41.081423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.062787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-controller\" ","response":"range_response_count:1 size:201"} {"level":"info","ts":"2026-02-06T00:50:41.081583Z","caller":"traceutil/trace.go:171","msg":"trace[608749867] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-controller; range_end:; response_count:1; response_revision:2066; }","duration":"169.370464ms","start":"2026-02-06T00:50:40.912193Z","end":"2026-02-06T00:50:41.081564Z","steps":["trace[608749867] 'agreement among raft nodes before linearized reading' (duration: 169.057377ms)"],"step_count":1}