{"level":"warn","ts":"2026-02-13T14:22:10.500438Z","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-13T14:22:10.500591Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.12: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://162.253.55.12:2380","--initial-cluster=instance=https://162.253.55.12:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.12:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.12: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-13T14:22:10.500698Z","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-13T14:22:10.500726Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.12:2380"]} {"level":"info","ts":"2026-02-13T14:22:10.500768Z","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-13T14:22:10.501546Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.12:2379"]} {"level":"info","ts":"2026-02-13T14:22:10.501651Z","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://162.253.55.12:2380"],"listen-peer-urls":["https://162.253.55.12:2380"],"advertise-client-urls":["https://162.253.55.12:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.12:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.12: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-13T14:22:10.512944Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"10.96954ms"} {"level":"info","ts":"2026-02-13T14:22:10.53473Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b4c6dc24a78438ca","cluster-id":"587227f9a3f73e98"} {"level":"info","ts":"2026-02-13T14:22:10.534792Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca switched to configuration voters=()"} {"level":"info","ts":"2026-02-13T14:22:10.53482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became follower at term 0"} {"level":"info","ts":"2026-02-13T14:22:10.534836Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b4c6dc24a78438ca [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-13T14:22:10.534853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became follower at term 1"} {"level":"info","ts":"2026-02-13T14:22:10.534889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca switched to configuration voters=(13026341022203132106)"} {"level":"warn","ts":"2026-02-13T14:22:10.555002Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-13T14:22:10.562997Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-13T14:22:10.573913Z","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-13T14:22:10.583081Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b4c6dc24a78438ca","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-13T14:22:10.594474Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b4c6dc24a78438ca","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-13T14:22:10.594673Z","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-13T14:22:10.594709Z","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-13T14:22:10.594792Z","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-13T14:22:10.5951Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca switched to configuration voters=(13026341022203132106)"} {"level":"info","ts":"2026-02-13T14:22:10.595444Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"587227f9a3f73e98","local-member-id":"b4c6dc24a78438ca","added-peer-id":"b4c6dc24a78438ca","added-peer-peer-urls":["https://162.253.55.12:2380"]} {"level":"info","ts":"2026-02-13T14:22:10.604918Z","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-13T14:22:10.605161Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b4c6dc24a78438ca","initial-advertise-peer-urls":["https://162.253.55.12:2380"],"listen-peer-urls":["https://162.253.55.12:2380"],"advertise-client-urls":["https://162.253.55.12:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.12:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-13T14:22:10.605186Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-13T14:22:10.605828Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.12:2380"} {"level":"info","ts":"2026-02-13T14:22:10.605842Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.12:2380"} {"level":"info","ts":"2026-02-13T14:22:10.735743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca is starting a new election at term 1"} {"level":"info","ts":"2026-02-13T14:22:10.73578Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became pre-candidate at term 1"} {"level":"info","ts":"2026-02-13T14:22:10.735917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca received MsgPreVoteResp from b4c6dc24a78438ca at term 1"} {"level":"info","ts":"2026-02-13T14:22:10.735995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became candidate at term 2"} {"level":"info","ts":"2026-02-13T14:22:10.736008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca received MsgVoteResp from b4c6dc24a78438ca at term 2"} {"level":"info","ts":"2026-02-13T14:22:10.73607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became leader at term 2"} {"level":"info","ts":"2026-02-13T14:22:10.736083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b4c6dc24a78438ca elected leader b4c6dc24a78438ca at term 2"} {"level":"info","ts":"2026-02-13T14:22:10.737457Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b4c6dc24a78438ca","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.12:2379]}","request-path":"/0/members/b4c6dc24a78438ca/attributes","cluster-id":"587227f9a3f73e98","publish-timeout":"7s"} {"level":"info","ts":"2026-02-13T14:22:10.737554Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-13T14:22:10.73793Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-13T14:22:10.740299Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-13T14:22:10.741818Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.12:2379"} {"level":"info","ts":"2026-02-13T14:22:10.741902Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"587227f9a3f73e98","local-member-id":"b4c6dc24a78438ca","cluster-version":"3.5"} {"level":"info","ts":"2026-02-13T14:22:10.741941Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-13T14:22:10.741955Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-13T14:22:10.74226Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-13T14:22:10.742285Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-13T14:22:10.746723Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-13T14:22:25.499788Z","caller":"traceutil/trace.go:171","msg":"trace[130301710] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"141.077114ms","start":"2026-02-13T14:22:25.358693Z","end":"2026-02-13T14:22:25.49977Z","steps":["trace[130301710] 'process raft request' (duration: 106.857447ms)","trace[130301710] 'compare' (duration: 34.131875ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:22:31.759089Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.129061668s","expected-duration":"1s"} {"level":"info","ts":"2026-02-13T14:22:31.75934Z","caller":"traceutil/trace.go:171","msg":"trace[2093748910] linearizableReadLoop","detail":"{readStateIndex:426; appliedIndex:425; }","duration":"383.113973ms","start":"2026-02-13T14:22:31.376209Z","end":"2026-02-13T14:22:31.759323Z","steps":["trace[2093748910] 'read index received' (duration: 382.991731ms)","trace[2093748910] 'applied index is now lower than readState.Index' (duration: 121.572µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:22:31.75946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.237996ms","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-13T14:22:31.75949Z","caller":"traceutil/trace.go:171","msg":"trace[1945280006] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:412; }","duration":"383.283037ms","start":"2026-02-13T14:22:31.376199Z","end":"2026-02-13T14:22:31.759482Z","steps":["trace[1945280006] 'agreement among raft nodes before linearized reading' (duration: 383.167404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:22:31.759517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:22:31.376143Z","time spent":"383.368699ms","remote":"127.0.0.1:38052","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-13T14:22:31.759751Z","caller":"traceutil/trace.go:171","msg":"trace[1572872336] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"1.129780224s","start":"2026-02-13T14:22:30.629963Z","end":"2026-02-13T14:22:31.759744Z","steps":["trace[1572872336] 'process raft request' (duration: 1.129273722s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:22:31.760069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:22:30.629952Z","time spent":"1.129820126s","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:22:32.169813Z","caller":"traceutil/trace.go:171","msg":"trace[1952985341] linearizableReadLoop","detail":"{readStateIndex:427; appliedIndex:426; }","duration":"138.604748ms","start":"2026-02-13T14:22:32.031196Z","end":"2026-02-13T14:22:32.1698Z","steps":["trace[1952985341] 'read index received' (duration: 115.605835ms)","trace[1952985341] 'applied index is now lower than readState.Index' (duration: 22.998443ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:22:32.169945Z","caller":"traceutil/trace.go:171","msg":"trace[1685157387] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"404.453907ms","start":"2026-02-13T14:22:31.765484Z","end":"2026-02-13T14:22:32.169938Z","steps":["trace[1685157387] 'process raft request' (duration: 381.329282ms)","trace[1685157387] 'compare' (duration: 22.825648ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:22:32.169999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:22:31.765468Z","time spent":"404.490987ms","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T14:22:32.17009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.899444ms","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-13T14:22:32.170104Z","caller":"traceutil/trace.go:171","msg":"trace[1853758448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:413; }","duration":"138.967145ms","start":"2026-02-13T14:22:32.031133Z","end":"2026-02-13T14:22:32.1701Z","steps":["trace[1853758448] 'agreement among raft nodes before linearized reading' (duration: 138.938135ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:22:40.656421Z","caller":"traceutil/trace.go:171","msg":"trace[453958306] linearizableReadLoop","detail":"{readStateIndex:482; appliedIndex:481; }","duration":"419.05244ms","start":"2026-02-13T14:22:40.237349Z","end":"2026-02-13T14:22:40.656401Z","steps":["trace[453958306] 'read index received' (duration: 418.904657ms)","trace[453958306] 'applied index is now lower than readState.Index' (duration: 147.193µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:22:40.656599Z","caller":"traceutil/trace.go:171","msg":"trace[1044912537] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"438.276506ms","start":"2026-02-13T14:22:40.218317Z","end":"2026-02-13T14:22:40.656593Z","steps":["trace[1044912537] 'process raft request' (duration: 437.98809ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:22:40.656664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:22:40.218301Z","time spent":"438.313587ms","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T14:22:40.656804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.454899ms","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-13T14:22:40.656823Z","caller":"traceutil/trace.go:171","msg":"trace[1282445825] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:466; }","duration":"419.492199ms","start":"2026-02-13T14:22:40.237323Z","end":"2026-02-13T14:22:40.656816Z","steps":["trace[1282445825] 'agreement among raft nodes before linearized reading' (duration: 419.424968ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:22:40.656836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:22:40.237311Z","time spent":"419.52252ms","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-13T14:22:40.656901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.14943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-13T14:22:40.656912Z","caller":"traceutil/trace.go:171","msg":"trace[871775360] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:466; }","duration":"389.189911ms","start":"2026-02-13T14:22:40.267719Z","end":"2026-02-13T14:22:40.656908Z","steps":["trace[871775360] 'agreement among raft nodes before linearized reading' (duration: 389.168101ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:22:40.656923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:22:40.267703Z","time spent":"389.218002ms","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-13T14:22:42.262809Z","caller":"traceutil/trace.go:171","msg":"trace[399078751] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"406.938224ms","start":"2026-02-13T14:22:41.855848Z","end":"2026-02-13T14:22:42.262786Z","steps":["trace[399078751] 'process raft request' (duration: 406.588566ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:22:42.262931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:22:41.855834Z","time spent":"407.035517ms","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:22:42.841655Z","caller":"traceutil/trace.go:171","msg":"trace[1977658401] linearizableReadLoop","detail":"{readStateIndex:487; appliedIndex:486; }","duration":"167.442314ms","start":"2026-02-13T14:22:42.674197Z","end":"2026-02-13T14:22:42.841639Z","steps":["trace[1977658401] 'read index received' (duration: 167.29427ms)","trace[1977658401] 'applied index is now lower than readState.Index' (duration: 146.614µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:22:42.841726Z","caller":"traceutil/trace.go:171","msg":"trace[1050879310] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"173.224995ms","start":"2026-02-13T14:22:42.668496Z","end":"2026-02-13T14:22:42.841721Z","steps":["trace[1050879310] 'process raft request' (duration: 173.048811ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:22:42.841847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.638688ms","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-13T14:22:42.841863Z","caller":"traceutil/trace.go:171","msg":"trace[1161113982] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:471; }","duration":"167.69001ms","start":"2026-02-13T14:22:42.674168Z","end":"2026-02-13T14:22:42.841858Z","steps":["trace[1161113982] 'agreement among raft nodes before linearized reading' (duration: 167.645959ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:22:42.84193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.565541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-13T14:22:42.841942Z","caller":"traceutil/trace.go:171","msg":"trace[1061658298] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:471; }","duration":"165.596611ms","start":"2026-02-13T14:22:42.676342Z","end":"2026-02-13T14:22:42.841938Z","steps":["trace[1061658298] 'agreement among raft nodes before linearized reading' (duration: 165.576561ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:22:44.370367Z","caller":"traceutil/trace.go:171","msg":"trace[645030111] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"100.82314ms","start":"2026-02-13T14:22:44.269523Z","end":"2026-02-13T14:22:44.370346Z","steps":["trace[645030111] 'process raft request' (duration: 100.702947ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:22:44.980044Z","caller":"traceutil/trace.go:171","msg":"trace[322604216] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"127.970387ms","start":"2026-02-13T14:22:44.852042Z","end":"2026-02-13T14:22:44.980012Z","steps":["trace[322604216] 'process raft request' (duration: 127.440475ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:22:49.621579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"994.464671ms","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-13T14:22:49.621658Z","caller":"traceutil/trace.go:171","msg":"trace[1892888104] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:488; }","duration":"994.549523ms","start":"2026-02-13T14:22:48.627094Z","end":"2026-02-13T14:22:49.621643Z","steps":["trace[1892888104] 'range keys from in-memory index tree' (duration: 994.45434ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:22:49.621952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.81562ms","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-13T14:22:49.621968Z","caller":"traceutil/trace.go:171","msg":"trace[1842215373] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:488; }","duration":"630.867022ms","start":"2026-02-13T14:22:48.991096Z","end":"2026-02-13T14:22:49.621963Z","steps":["trace[1842215373] 'range keys from in-memory index tree' (duration: 630.717138ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:22:49.621984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:22:48.991079Z","time spent":"630.901152ms","remote":"127.0.0.1:38052","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-13T14:22:49.622074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.681954ms","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-13T14:22:49.622085Z","caller":"traceutil/trace.go:171","msg":"trace[517217360] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:488; }","duration":"614.713084ms","start":"2026-02-13T14:22:49.007368Z","end":"2026-02-13T14:22:49.622081Z","steps":["trace[517217360] 'range keys from in-memory index tree' (duration: 614.636473ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:22:49.622097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:22:49.007359Z","time spent":"614.734835ms","remote":"127.0.0.1:38052","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-13T14:22:49.622158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.66846ms","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-13T14:22:49.62217Z","caller":"traceutil/trace.go:171","msg":"trace[2140992583] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:488; }","duration":"609.69265ms","start":"2026-02-13T14:22:49.012473Z","end":"2026-02-13T14:22:49.622166Z","steps":["trace[2140992583] 'range keys from in-memory index tree' (duration: 609.636019ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:22:49.622194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:22:49.012467Z","time spent":"609.712051ms","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-13T14:22:49.622295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.84785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:22:49.62231Z","caller":"traceutil/trace.go:171","msg":"trace[1117710824] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:488; }","duration":"453.900882ms","start":"2026-02-13T14:22:49.168405Z","end":"2026-02-13T14:22:49.622306Z","steps":["trace[1117710824] 'range keys from in-memory index tree' (duration: 453.791369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:22:49.622324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:22:49.168388Z","time spent":"453.932182ms","remote":"127.0.0.1:37770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-13T14:22:49.622387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.558599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-jjhk6\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-13T14:22:49.622399Z","caller":"traceutil/trace.go:171","msg":"trace[2121752818] range","detail":"{range_begin:/registry/pods/kube-system/cilium-jjhk6; range_end:; response_count:1; response_revision:488; }","duration":"385.60196ms","start":"2026-02-13T14:22:49.236794Z","end":"2026-02-13T14:22:49.622396Z","steps":["trace[2121752818] 'range keys from in-memory index tree' (duration: 385.465147ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:22:49.62241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:22:49.236777Z","time spent":"385.630441ms","remote":"127.0.0.1:37974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16675,"request content":"key:\"/registry/pods/kube-system/cilium-jjhk6\" "} {"level":"warn","ts":"2026-02-13T14:22:49.995946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.194851ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-13T14:22:49.996152Z","caller":"traceutil/trace.go:171","msg":"trace[1333815334] linearizableReadLoop","detail":"{readStateIndex:506; appliedIndex:505; }","duration":"364.258055ms","start":"2026-02-13T14:22:49.631879Z","end":"2026-02-13T14:22:49.996137Z","steps":["trace[1333815334] 'read index received' (duration: 81.684026ms)","trace[1333815334] 'applied index is now lower than readState.Index' (duration: 282.573139ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:22:49.996214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.324046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:22:49.996241Z","caller":"traceutil/trace.go:171","msg":"trace[1896813708] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:488; }","duration":"364.356527ms","start":"2026-02-13T14:22:49.631875Z","end":"2026-02-13T14:22:49.996231Z","steps":["trace[1896813708] 'agreement among raft nodes before linearized reading' (duration: 364.307946ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:22:49.996476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:22:49.631849Z","time spent":"364.615473ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-13T14:22:49.996819Z","caller":"traceutil/trace.go:171","msg":"trace[445889840] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"362.933505ms","start":"2026-02-13T14:22:49.633795Z","end":"2026-02-13T14:22:49.996729Z","steps":["trace[445889840] 'process raft request' (duration: 362.243169ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:22:49.996939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:22:49.633622Z","time spent":"363.228462ms","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:22:49.997182Z","caller":"traceutil/trace.go:171","msg":"trace[842260702] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"360.353226ms","start":"2026-02-13T14:22:49.636818Z","end":"2026-02-13T14:22:49.997171Z","steps":["trace[842260702] 'process raft request' (duration: 359.684141ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:22:50.000785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:22:49.636813Z","time spent":"363.886026ms","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:22:50.001714Z","caller":"traceutil/trace.go:171","msg":"trace[622849325] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"345.524259ms","start":"2026-02-13T14:22:49.656173Z","end":"2026-02-13T14:22:50.001697Z","steps":["trace[622849325] 'process raft request' (duration: 340.387482ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:22:50.001789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:22:49.656152Z","time spent":"345.598591ms","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:22:50.010229Z","caller":"traceutil/trace.go:171","msg":"trace[581384580] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"352.761683ms","start":"2026-02-13T14:22:49.657451Z","end":"2026-02-13T14:22:50.010213Z","steps":["trace[581384580] 'process raft request' (duration: 339.176185ms)","trace[581384580] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/pods/kube-system/cilium-jjhk6; req_size:16795; } (duration: 13.396114ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:22:50.010305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:22:49.657435Z","time spent":"352.827304ms","remote":"127.0.0.1:37974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T14:22:54.648848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.703124ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T14:22:54.649005Z","caller":"traceutil/trace.go:171","msg":"trace[1548813767] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"123.72102ms","start":"2026-02-13T14:22:54.525273Z","end":"2026-02-13T14:22:54.648994Z","steps":["trace[1548813767] 'process raft request' (duration: 123.657609ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:22:54.649292Z","caller":"traceutil/trace.go:171","msg":"trace[69985552] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"233.432673ms","start":"2026-02-13T14:22:54.415851Z","end":"2026-02-13T14:22:54.649284Z","steps":["trace[69985552] 'process raft request' (duration: 104.215717ms)","trace[69985552] 'compare' (duration: 128.614762ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:22:54.649374Z","caller":"traceutil/trace.go:171","msg":"trace[1004942719] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:572; }","duration":"208.524037ms","start":"2026-02-13T14:22:54.440844Z","end":"2026-02-13T14:22:54.649368Z","steps":["trace[1004942719] 'read index received' (duration: 79.22575ms)","trace[1004942719] 'applied index is now lower than readState.Index' (duration: 129.297707ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:22:54.649476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.63654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-02-13T14:22:54.649511Z","caller":"traceutil/trace.go:171","msg":"trace[164270783] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:555; }","duration":"208.696152ms","start":"2026-02-13T14:22:54.440792Z","end":"2026-02-13T14:22:54.649488Z","steps":["trace[164270783] 'agreement among raft nodes before linearized reading' (duration: 208.61278ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:22:54.649802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.32915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.12\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-13T14:22:54.64987Z","caller":"traceutil/trace.go:171","msg":"trace[165161390] range","detail":"{range_begin:/registry/masterleases/162.253.55.12; range_end:; response_count:1; response_revision:555; }","duration":"115.463893ms","start":"2026-02-13T14:22:54.53439Z","end":"2026-02-13T14:22:54.649853Z","steps":["trace[165161390] 'agreement among raft nodes before linearized reading' (duration: 115.33485ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:23:02.380281Z","caller":"traceutil/trace.go:171","msg":"trace[242211124] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:644; }","duration":"109.255386ms","start":"2026-02-13T14:23:02.271001Z","end":"2026-02-13T14:23:02.380257Z","steps":["trace[242211124] 'read index received' (duration: 50.637516ms)","trace[242211124] 'applied index is now lower than readState.Index' (duration: 58.6171ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:23:02.380374Z","caller":"traceutil/trace.go:171","msg":"trace[110957668] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"129.485657ms","start":"2026-02-13T14:23:02.250873Z","end":"2026-02-13T14:23:02.380359Z","steps":["trace[110957668] 'process raft request' (duration: 70.811235ms)","trace[110957668] 'compare' (duration: 58.443206ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:23:02.380477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.464221ms","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-13T14:23:02.380499Z","caller":"traceutil/trace.go:171","msg":"trace[1286535560] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:623; }","duration":"109.518263ms","start":"2026-02-13T14:23:02.270973Z","end":"2026-02-13T14:23:02.380491Z","steps":["trace[1286535560] 'agreement among raft nodes before linearized reading' (duration: 109.41094ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:23:02.550084Z","caller":"traceutil/trace.go:171","msg":"trace[1769164364] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:646; }","duration":"134.478212ms","start":"2026-02-13T14:23:02.415592Z","end":"2026-02-13T14:23:02.550071Z","steps":["trace[1769164364] 'read index received' (duration: 24.210162ms)","trace[1769164364] 'applied index is now lower than readState.Index' (duration: 110.26748ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:23:02.550226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.619255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-zbbdz\" ","response":"range_response_count:1 size:4732"} {"level":"info","ts":"2026-02-13T14:23:02.550265Z","caller":"traceutil/trace.go:171","msg":"trace[1826769735] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-zbbdz; range_end:; response_count:1; response_revision:626; }","duration":"134.692557ms","start":"2026-02-13T14:23:02.415567Z","end":"2026-02-13T14:23:02.55026Z","steps":["trace[1826769735] 'agreement among raft nodes before linearized reading' (duration: 134.567704ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:23:02.550401Z","caller":"traceutil/trace.go:171","msg":"trace[245491594] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"165.41915ms","start":"2026-02-13T14:23:02.384976Z","end":"2026-02-13T14:23:02.550395Z","steps":["trace[245491594] 'process raft request' (duration: 136.993441ms)","trace[245491594] 'compare' (duration: 28.00734ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:23:02.550465Z","caller":"traceutil/trace.go:171","msg":"trace[1278813349] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"140.619375ms","start":"2026-02-13T14:23:02.409841Z","end":"2026-02-13T14:23:02.55046Z","steps":["trace[1278813349] 'process raft request' (duration: 140.204915ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:06.893224Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255110530624669,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-13T14:23:07.205159Z","caller":"traceutil/trace.go:171","msg":"trace[984666733] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"909.191458ms","start":"2026-02-13T14:23:06.295947Z","end":"2026-02-13T14:23:07.205138Z","steps":["trace[984666733] 'process raft request' (duration: 908.998654ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:07.205293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:06.295938Z","time spent":"909.28692ms","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:23:07.305418Z","caller":"traceutil/trace.go:171","msg":"trace[2000780550] linearizableReadLoop","detail":"{readStateIndex:697; appliedIndex:695; }","duration":"912.499225ms","start":"2026-02-13T14:23:06.392903Z","end":"2026-02-13T14:23:07.305403Z","steps":["trace[2000780550] 'read index received' (duration: 812.79256ms)","trace[2000780550] 'applied index is now lower than readState.Index' (duration: 99.706115ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:23:07.305503Z","caller":"traceutil/trace.go:171","msg":"trace[836726401] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"653.151495ms","start":"2026-02-13T14:23:06.652345Z","end":"2026-02-13T14:23:07.305497Z","steps":["trace[836726401] 'process raft request' (duration: 636.999469ms)","trace[836726401] 'compare' (duration: 15.89811ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:23:07.305566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:06.65233Z","time spent":"653.193785ms","remote":"127.0.0.1:37832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":817,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-13T14:23:07.305868Z","caller":"traceutil/trace.go:171","msg":"trace[1763591710] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"491.839749ms","start":"2026-02-13T14:23:06.814022Z","end":"2026-02-13T14:23:07.305862Z","steps":["trace[1763591710] 'process raft request' (duration: 491.333977ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:07.305932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:06.814008Z","time spent":"491.89905ms","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T14:23:07.306047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.14039ms","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-13T14:23:07.306066Z","caller":"traceutil/trace.go:171","msg":"trace[749204264] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:674; }","duration":"913.184712ms","start":"2026-02-13T14:23:06.392876Z","end":"2026-02-13T14:23:07.306061Z","steps":["trace[749204264] 'agreement among raft nodes before linearized reading' (duration: 913.11755ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:07.306081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:06.392861Z","time spent":"913.217322ms","remote":"127.0.0.1:38052","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-13T14:23:07.306176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.864343ms","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-13T14:23:07.306192Z","caller":"traceutil/trace.go:171","msg":"trace[1417273240] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:674; }","duration":"856.902225ms","start":"2026-02-13T14:23:06.449285Z","end":"2026-02-13T14:23:07.306188Z","steps":["trace[1417273240] 'agreement among raft nodes before linearized reading' (duration: 856.862954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:07.306209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:06.449269Z","time spent":"856.937065ms","remote":"127.0.0.1:38052","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-13T14:23:07.306323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.325362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:23:07.306344Z","caller":"traceutil/trace.go:171","msg":"trace[174942989] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:674; }","duration":"138.386323ms","start":"2026-02-13T14:23:07.16795Z","end":"2026-02-13T14:23:07.306336Z","steps":["trace[174942989] 'agreement among raft nodes before linearized reading' (duration: 138.361652ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:07.306425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.923692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-13T14:23:07.306441Z","caller":"traceutil/trace.go:171","msg":"trace[2092477049] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:674; }","duration":"594.963903ms","start":"2026-02-13T14:23:06.711472Z","end":"2026-02-13T14:23:07.306436Z","steps":["trace[2092477049] 'agreement among raft nodes before linearized reading' (duration: 594.926762ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:07.306453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:06.711453Z","time spent":"594.997895ms","remote":"127.0.0.1:38052","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-13T14:23:07.306745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.730117ms","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-13T14:23:07.306766Z","caller":"traceutil/trace.go:171","msg":"trace[1741813511] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:674; }","duration":"737.773029ms","start":"2026-02-13T14:23:06.568989Z","end":"2026-02-13T14:23:07.306762Z","steps":["trace[1741813511] 'agreement among raft nodes before linearized reading' (duration: 737.719858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:07.306783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:06.568972Z","time spent":"737.808679ms","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-13T14:23:07.505855Z","caller":"traceutil/trace.go:171","msg":"trace[1121216590] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"191.116717ms","start":"2026-02-13T14:23:07.314725Z","end":"2026-02-13T14:23:07.505841Z","steps":["trace[1121216590] 'process raft request' (duration: 191.096806ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:23:07.506414Z","caller":"traceutil/trace.go:171","msg":"trace[2138608562] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"193.507313ms","start":"2026-02-13T14:23:07.3129Z","end":"2026-02-13T14:23:07.506407Z","steps":["trace[2138608562] 'process raft request' (duration: 125.868443ms)","trace[2138608562] 'compare' (duration: 66.900313ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:23:07.506545Z","caller":"traceutil/trace.go:171","msg":"trace[1800894895] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"192.268154ms","start":"2026-02-13T14:23:07.314271Z","end":"2026-02-13T14:23:07.506539Z","steps":["trace[1800894895] 'process raft request' (duration: 191.528017ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:23:10.339211Z","caller":"traceutil/trace.go:171","msg":"trace[166253320] linearizableReadLoop","detail":"{readStateIndex:707; appliedIndex:706; }","duration":"171.753838ms","start":"2026-02-13T14:23:10.167439Z","end":"2026-02-13T14:23:10.339193Z","steps":["trace[166253320] 'read index received' (duration: 116.578457ms)","trace[166253320] 'applied index is now lower than readState.Index' (duration: 55.174491ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:23:10.339334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.86903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:23:10.339399Z","caller":"traceutil/trace.go:171","msg":"trace[1971122013] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:683; }","duration":"171.987463ms","start":"2026-02-13T14:23:10.167397Z","end":"2026-02-13T14:23:10.339385Z","steps":["trace[1971122013] 'agreement among raft nodes before linearized reading' (duration: 171.85904ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:11.910537Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255110530624695,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T14:23:12.410787Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255110530624695,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T14:23:12.545073Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.309031901s","expected-duration":"1s"} {"level":"info","ts":"2026-02-13T14:23:12.545338Z","caller":"traceutil/trace.go:171","msg":"trace[467931157] linearizableReadLoop","detail":"{readStateIndex:708; appliedIndex:707; }","duration":"1.135804788s","start":"2026-02-13T14:23:11.409516Z","end":"2026-02-13T14:23:12.545321Z","steps":["trace[467931157] 'read index received' (duration: 1.135662665s)","trace[467931157] 'applied index is now lower than readState.Index' (duration: 141.473µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:23:12.54553Z","caller":"traceutil/trace.go:171","msg":"trace[1657935166] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"1.309531252s","start":"2026-02-13T14:23:11.23599Z","end":"2026-02-13T14:23:12.545521Z","steps":["trace[1657935166] 'process raft request' (duration: 1.309232045s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:12.545614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:11.235979Z","time spent":"1.309582143s","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T14:23:12.545772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.136254529s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-13T14:23:12.545792Z","caller":"traceutil/trace.go:171","msg":"trace[938201535] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:684; }","duration":"1.13629571s","start":"2026-02-13T14:23:11.40949Z","end":"2026-02-13T14:23:12.545786Z","steps":["trace[938201535] 'agreement among raft nodes before linearized reading' (duration: 1.136223909s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:12.545809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:11.409478Z","time spent":"1.136327061s","remote":"127.0.0.1:38052","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-13T14:23:12.545947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.015461136s","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-13T14:23:12.545965Z","caller":"traceutil/trace.go:171","msg":"trace[1784469285] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:684; }","duration":"1.015505366s","start":"2026-02-13T14:23:11.530454Z","end":"2026-02-13T14:23:12.54596Z","steps":["trace[1784469285] 'agreement among raft nodes before linearized reading' (duration: 1.015449915s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:12.54598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:11.530441Z","time spent":"1.015535598s","remote":"127.0.0.1:38052","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-13T14:23:12.54615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.15587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:23:12.546167Z","caller":"traceutil/trace.go:171","msg":"trace[1343874131] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:684; }","duration":"378.20311ms","start":"2026-02-13T14:23:12.167958Z","end":"2026-02-13T14:23:12.546162Z","steps":["trace[1343874131] 'agreement among raft nodes before linearized reading' (duration: 378.170289ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:12.546182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:12.167945Z","time spent":"378.233441ms","remote":"127.0.0.1:37770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-13T14:23:12.546314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.015771442s","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-13T14:23:12.546332Z","caller":"traceutil/trace.go:171","msg":"trace[986005705] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:684; }","duration":"1.015796603s","start":"2026-02-13T14:23:11.53053Z","end":"2026-02-13T14:23:12.546327Z","steps":["trace[986005705] 'agreement among raft nodes before linearized reading' (duration: 1.015748192s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:12.546348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:11.530527Z","time spent":"1.015816634s","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-13T14:23:12.546443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.015915976s","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-13T14:23:12.546462Z","caller":"traceutil/trace.go:171","msg":"trace[1108727390] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:684; }","duration":"1.015944127s","start":"2026-02-13T14:23:11.530514Z","end":"2026-02-13T14:23:12.546458Z","steps":["trace[1108727390] 'agreement among raft nodes before linearized reading' (duration: 1.015897476s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:12.546478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:11.53051Z","time spent":"1.015964217s","remote":"127.0.0.1:38052","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-13T14:23:13.31977Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255110530624699,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T14:23:13.820192Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255110530624699,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T14:23:13.83594Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.276152968s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-13T14:23:14.037475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.415526ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T14:23:14.03754Z","caller":"traceutil/trace.go:171","msg":"trace[1426992626] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"1.486312818s","start":"2026-02-13T14:23:12.551213Z","end":"2026-02-13T14:23:14.037526Z","steps":["trace[1426992626] 'process raft request' (duration: 1.284796699s)","trace[1426992626] 'compare' (duration: 201.228232ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:23:14.037578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:12.551202Z","time spent":"1.486358878s","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:23:14.57001Z","caller":"traceutil/trace.go:171","msg":"trace[412068792] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"2.01837244s","start":"2026-02-13T14:23:12.55162Z","end":"2026-02-13T14:23:14.569992Z","steps":["trace[412068792] 'process raft request' (duration: 2.018195205s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:14.570123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:12.551612Z","time spent":"2.018458811s","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:23:14.570202Z","caller":"traceutil/trace.go:171","msg":"trace[1406366613] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"2.016193269s","start":"2026-02-13T14:23:12.553997Z","end":"2026-02-13T14:23:14.57019Z","steps":["trace[1406366613] 'process raft request' (duration: 2.015968444s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:14.570261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:12.553958Z","time spent":"2.016271541s","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:23:14.570383Z","caller":"traceutil/trace.go:171","msg":"trace[845373067] linearizableReadLoop","detail":"{readStateIndex:712; appliedIndex:708; }","duration":"1.750754986s","start":"2026-02-13T14:23:12.819603Z","end":"2026-02-13T14:23:14.570358Z","steps":["trace[845373067] 'read index received' (duration: 1.016447718s)","trace[845373067] 'applied index is now lower than readState.Index' (duration: 734.306598ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:23:14.570468Z","caller":"traceutil/trace.go:171","msg":"trace[222659357] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"2.014346407s","start":"2026-02-13T14:23:12.556116Z","end":"2026-02-13T14:23:14.570462Z","steps":["trace[222659357] 'process raft request' (duration: 2.014036419s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:14.570502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:12.556097Z","time spent":"2.014383987s","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T14:23:14.570535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.401938868s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:23:14.570565Z","caller":"traceutil/trace.go:171","msg":"trace[634898261] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:688; }","duration":"1.401970049s","start":"2026-02-13T14:23:13.168585Z","end":"2026-02-13T14:23:14.570555Z","steps":["trace[634898261] 'agreement among raft nodes before linearized reading' (duration: 1.401922328s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:14.570593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:13.168528Z","time spent":"1.402058781s","remote":"127.0.0.1:37770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-13T14:23:14.570726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.751123205s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-13T14:23:14.57075Z","caller":"traceutil/trace.go:171","msg":"trace[1522352372] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:688; }","duration":"1.751176916s","start":"2026-02-13T14:23:12.819566Z","end":"2026-02-13T14:23:14.570743Z","steps":["trace[1522352372] 'agreement among raft nodes before linearized reading' (duration: 1.751133515s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:14.570773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:12.819551Z","time spent":"1.751215048s","remote":"127.0.0.1:38068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":2,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-02-13T14:23:14.570897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.299786266s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-13T14:23:14.570922Z","caller":"traceutil/trace.go:171","msg":"trace[882191899] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:688; }","duration":"1.299834828s","start":"2026-02-13T14:23:13.271078Z","end":"2026-02-13T14:23:14.570913Z","steps":["trace[882191899] 'agreement among raft nodes before linearized reading' (duration: 1.299792467s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:14.570944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:13.271068Z","time spent":"1.299869709s","remote":"127.0.0.1:38046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-02-13T14:23:15.639932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"914.631994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-13T14:23:15.640022Z","caller":"traceutil/trace.go:171","msg":"trace[678399162] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:690; }","duration":"914.764637ms","start":"2026-02-13T14:23:14.72524Z","end":"2026-02-13T14:23:15.640005Z","steps":["trace[678399162] 'count revisions from in-memory index tree' (duration: 914.577983ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:15.640089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:14.725226Z","time spent":"914.851849ms","remote":"127.0.0.1:38144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":51,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-13T14:23:15.640454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.694061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:23:15.640493Z","caller":"traceutil/trace.go:171","msg":"trace[1284427642] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:690; }","duration":"608.767733ms","start":"2026-02-13T14:23:15.031716Z","end":"2026-02-13T14:23:15.640484Z","steps":["trace[1284427642] 'count revisions from in-memory index tree' (duration: 608.63313ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:15.640526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:15.031699Z","time spent":"608.819904ms","remote":"127.0.0.1:37842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-02-13T14:23:15.642903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.932754ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T14:23:15.642985Z","caller":"traceutil/trace.go:171","msg":"trace[824638638] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"204.621021ms","start":"2026-02-13T14:23:15.438351Z","end":"2026-02-13T14:23:15.642972Z","steps":["trace[824638638] 'process raft request' (duration: 82.632369ms)","trace[824638638] 'compare' (duration: 118.814189ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:23:16.207858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.79263ms","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-13T14:23:16.207905Z","caller":"traceutil/trace.go:171","msg":"trace[1401963262] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:691; }","duration":"162.875521ms","start":"2026-02-13T14:23:16.045019Z","end":"2026-02-13T14:23:16.207894Z","steps":["trace[1401963262] 'range keys from in-memory index tree' (duration: 162.697887ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:29.204281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.765276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-13T14:23:29.204343Z","caller":"traceutil/trace.go:171","msg":"trace[1960118264] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:736; }","duration":"391.881879ms","start":"2026-02-13T14:23:28.812448Z","end":"2026-02-13T14:23:29.20433Z","steps":["trace[1960118264] 'count revisions from in-memory index tree' (duration: 391.722934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:29.204373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:28.812436Z","time spent":"391.930089ms","remote":"127.0.0.1:37832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":79,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-02-13T14:23:32.929988Z","caller":"traceutil/trace.go:171","msg":"trace[173193009] linearizableReadLoop","detail":"{readStateIndex:773; appliedIndex:772; }","duration":"266.377714ms","start":"2026-02-13T14:23:32.663589Z","end":"2026-02-13T14:23:32.929967Z","steps":["trace[173193009] 'read index received' (duration: 95.836865ms)","trace[173193009] 'applied index is now lower than readState.Index' (duration: 170.539969ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:23:32.930455Z","caller":"traceutil/trace.go:171","msg":"trace[1116749121] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"268.259038ms","start":"2026-02-13T14:23:32.662183Z","end":"2026-02-13T14:23:32.930442Z","steps":["trace[1116749121] 'process raft request' (duration: 267.623644ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:32.931939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.329879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-13T14:23:32.931987Z","caller":"traceutil/trace.go:171","msg":"trace[206792154] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:744; }","duration":"268.400152ms","start":"2026-02-13T14:23:32.663576Z","end":"2026-02-13T14:23:32.931976Z","steps":["trace[206792154] 'agreement among raft nodes before linearized reading' (duration: 268.256328ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:32.932782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.33486ms","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-13T14:23:32.932825Z","caller":"traceutil/trace.go:171","msg":"trace[946135907] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:744; }","duration":"268.392081ms","start":"2026-02-13T14:23:32.664423Z","end":"2026-02-13T14:23:32.932815Z","steps":["trace[946135907] 'agreement among raft nodes before linearized reading' (duration: 268.269248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:33.547521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.379534ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T14:23:33.547634Z","caller":"traceutil/trace.go:171","msg":"trace[149960906] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"609.297655ms","start":"2026-02-13T14:23:32.938324Z","end":"2026-02-13T14:23:33.547622Z","steps":["trace[149960906] 'process raft request' (duration: 609.265254ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:33.547685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:32.938319Z","time spent":"609.336226ms","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:23:33.547856Z","caller":"traceutil/trace.go:171","msg":"trace[576504143] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"609.617482ms","start":"2026-02-13T14:23:32.938231Z","end":"2026-02-13T14:23:33.547849Z","steps":["trace[576504143] 'process raft request' (duration: 252.86074ms)","trace[576504143] 'compare' (duration: 356.310862ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:23:33.547888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:32.938218Z","time spent":"609.653743ms","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T14:23:34.592201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.456213ms","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-13T14:23:34.592262Z","caller":"traceutil/trace.go:171","msg":"trace[1669261666] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:746; }","duration":"657.583276ms","start":"2026-02-13T14:23:33.934665Z","end":"2026-02-13T14:23:34.592248Z","steps":["trace[1669261666] 'count revisions from in-memory index tree' (duration: 657.389962ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:34.592295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:33.934646Z","time spent":"657.639977ms","remote":"127.0.0.1:37922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-02-13T14:23:34.592473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.352577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:23:34.5925Z","caller":"traceutil/trace.go:171","msg":"trace[525374754] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:746; }","duration":"435.411558ms","start":"2026-02-13T14:23:34.15708Z","end":"2026-02-13T14:23:34.592492Z","steps":["trace[525374754] 'count revisions from in-memory index tree' (duration: 435.292925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:34.592525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:34.157063Z","time spent":"435.4559ms","remote":"127.0.0.1:38242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-02-13T14:23:34.592659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.000364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:23:34.592687Z","caller":"traceutil/trace.go:171","msg":"trace[1495947614] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:746; }","duration":"424.055454ms","start":"2026-02-13T14:23:34.168621Z","end":"2026-02-13T14:23:34.592676Z","steps":["trace[1495947614] 'range keys from in-memory index tree' (duration: 423.941382ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:34.592711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:34.168605Z","time spent":"424.101226ms","remote":"127.0.0.1:37770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-13T14:23:34.592981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.521094ms","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-13T14:23:34.593008Z","caller":"traceutil/trace.go:171","msg":"trace[581075732] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:746; }","duration":"268.574665ms","start":"2026-02-13T14:23:34.324425Z","end":"2026-02-13T14:23:34.593Z","steps":["trace[581075732] 'range keys from in-memory index tree' (duration: 268.426812ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:35.062069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.204451ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T14:23:35.062189Z","caller":"traceutil/trace.go:171","msg":"trace[2038562067] linearizableReadLoop","detail":"{readStateIndex:778; appliedIndex:776; }","duration":"299.389231ms","start":"2026-02-13T14:23:34.762787Z","end":"2026-02-13T14:23:35.062177Z","steps":["trace[2038562067] 'read index received' (duration: 92.025636ms)","trace[2038562067] 'applied index is now lower than readState.Index' (duration: 207.361644ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:23:35.062242Z","caller":"traceutil/trace.go:171","msg":"trace[1301980645] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"459.350204ms","start":"2026-02-13T14:23:34.602885Z","end":"2026-02-13T14:23:35.062235Z","steps":["trace[1301980645] 'process raft request' (duration: 251.920808ms)","trace[1301980645] 'compare' (duration: 207.123929ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:23:35.062277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:34.602875Z","time spent":"459.382845ms","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T14:23:35.062369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.565635ms","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-13T14:23:35.062421Z","caller":"traceutil/trace.go:171","msg":"trace[1965589043] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:748; }","duration":"299.648387ms","start":"2026-02-13T14:23:34.762759Z","end":"2026-02-13T14:23:35.062407Z","steps":["trace[1965589043] 'agreement among raft nodes before linearized reading' (duration: 299.490083ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:35.062457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.277329ms","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-13T14:23:35.062477Z","caller":"traceutil/trace.go:171","msg":"trace[1368113740] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:748; }","duration":"125.324409ms","start":"2026-02-13T14:23:34.937147Z","end":"2026-02-13T14:23:35.062471Z","steps":["trace[1368113740] 'agreement among raft nodes before linearized reading' (duration: 125.255488ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:23:35.062534Z","caller":"traceutil/trace.go:171","msg":"trace[1941908068] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"322.424206ms","start":"2026-02-13T14:23:34.740105Z","end":"2026-02-13T14:23:35.062529Z","steps":["trace[1941908068] 'process raft request' (duration: 322.030437ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:35.062567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:34.740089Z","time spent":"322.459206ms","remote":"127.0.0.1:37790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:23:35.439051Z","caller":"traceutil/trace.go:171","msg":"trace[224174563] linearizableReadLoop","detail":"{readStateIndex:780; appliedIndex:778; }","duration":"369.337805ms","start":"2026-02-13T14:23:35.069688Z","end":"2026-02-13T14:23:35.439026Z","steps":["trace[224174563] 'read index received' (duration: 26.758751ms)","trace[224174563] 'applied index is now lower than readState.Index' (duration: 342.578054ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:23:35.439381Z","caller":"traceutil/trace.go:171","msg":"trace[849680399] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"369.693353ms","start":"2026-02-13T14:23:35.069648Z","end":"2026-02-13T14:23:35.439342Z","steps":["trace[849680399] 'process raft request' (duration: 352.828812ms)","trace[849680399] 'compare' (duration: 16.443011ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:23:35.439678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.224283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:23:35.439753Z","caller":"traceutil/trace.go:171","msg":"trace[1158232195] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:750; }","duration":"270.348866ms","start":"2026-02-13T14:23:35.169386Z","end":"2026-02-13T14:23:35.439735Z","steps":["trace[1158232195] 'agreement among raft nodes before linearized reading' (duration: 270.206413ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:35.439964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:35.06964Z","time spent":"369.99806ms","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T14:23:35.440837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.138336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-13T14:23:35.441108Z","caller":"traceutil/trace.go:171","msg":"trace[1170907932] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:750; }","duration":"371.351931ms","start":"2026-02-13T14:23:35.069676Z","end":"2026-02-13T14:23:35.441028Z","steps":["trace[1170907932] 'agreement among raft nodes before linearized reading' (duration: 371.112196ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:35.441253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:35.069671Z","time spent":"371.552126ms","remote":"127.0.0.1:38068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-02-13T14:23:47.207768Z","caller":"traceutil/trace.go:171","msg":"trace[2052136691] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"102.211923ms","start":"2026-02-13T14:23:47.105543Z","end":"2026-02-13T14:23:47.207755Z","steps":["trace[2052136691] 'process raft request' (duration: 101.829454ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:47.715302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.818303ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T14:23:47.715374Z","caller":"traceutil/trace.go:171","msg":"trace[1579241206] linearizableReadLoop","detail":"{readStateIndex:868; appliedIndex:867; }","duration":"172.780582ms","start":"2026-02-13T14:23:47.542581Z","end":"2026-02-13T14:23:47.715362Z","steps":["trace[1579241206] 'read index received' (duration: 28.641µs)","trace[1579241206] 'applied index is now lower than readState.Index' (duration: 172.751201ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:23:47.715513Z","caller":"traceutil/trace.go:171","msg":"trace[881228463] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"504.740867ms","start":"2026-02-13T14:23:47.210763Z","end":"2026-02-13T14:23:47.715504Z","steps":["trace[881228463] 'process raft request' (duration: 228.662488ms)","trace[881228463] 'compare' (duration: 275.68095ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:23:47.715569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:47.210751Z","time spent":"504.780129ms","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T14:23:47.715739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.14875ms","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-13T14:23:47.715754Z","caller":"traceutil/trace.go:171","msg":"trace[278509449] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:834; }","duration":"173.203561ms","start":"2026-02-13T14:23:47.542545Z","end":"2026-02-13T14:23:47.715749Z","steps":["trace[278509449] 'agreement among raft nodes before linearized reading' (duration: 173.124459ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:23:47.823555Z","caller":"traceutil/trace.go:171","msg":"trace[2101329017] linearizableReadLoop","detail":"{readStateIndex:870; appliedIndex:868; }","duration":"101.547488ms","start":"2026-02-13T14:23:47.721993Z","end":"2026-02-13T14:23:47.823541Z","steps":["trace[2101329017] 'read index received' (duration: 93.655444ms)","trace[2101329017] 'applied index is now lower than readState.Index' (duration: 7.891524ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:23:47.823681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.67235ms","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-13T14:23:47.823703Z","caller":"traceutil/trace.go:171","msg":"trace[1360924417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:836; }","duration":"101.717241ms","start":"2026-02-13T14:23:47.721979Z","end":"2026-02-13T14:23:47.823696Z","steps":["trace[1360924417] 'agreement among raft nodes before linearized reading' (duration: 101.615299ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:23:47.823849Z","caller":"traceutil/trace.go:171","msg":"trace[644657505] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"103.408431ms","start":"2026-02-13T14:23:47.720434Z","end":"2026-02-13T14:23:47.823842Z","steps":["trace[644657505] 'process raft request' (duration: 103.018562ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:23:49.623382Z","caller":"traceutil/trace.go:171","msg":"trace[1205668464] linearizableReadLoop","detail":"{readStateIndex:873; appliedIndex:872; }","duration":"276.213462ms","start":"2026-02-13T14:23:49.347146Z","end":"2026-02-13T14:23:49.62336Z","steps":["trace[1205668464] 'read index received' (duration: 276.048329ms)","trace[1205668464] 'applied index is now lower than readState.Index' (duration: 164.263µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:23:49.623575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.401797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-13T14:23:49.623613Z","caller":"traceutil/trace.go:171","msg":"trace[1955607019] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:839; }","duration":"276.500759ms","start":"2026-02-13T14:23:49.347103Z","end":"2026-02-13T14:23:49.623604Z","steps":["trace[1955607019] 'agreement among raft nodes before linearized reading' (duration: 276.340085ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:23:49.623961Z","caller":"traceutil/trace.go:171","msg":"trace[971081553] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"409.347844ms","start":"2026-02-13T14:23:49.214603Z","end":"2026-02-13T14:23:49.62395Z","steps":["trace[971081553] 'process raft request' (duration: 408.648598ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:49.624039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:49.214593Z","time spent":"409.392835ms","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T14:23:50.085253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.770025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-13T14:23:50.085328Z","caller":"traceutil/trace.go:171","msg":"trace[1099168710] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:839; }","duration":"363.882388ms","start":"2026-02-13T14:23:49.721429Z","end":"2026-02-13T14:23:50.085312Z","steps":["trace[1099168710] 'range keys from in-memory index tree' (duration: 363.631962ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:50.085366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:49.721415Z","time spent":"363.943159ms","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-13T14:23:50.085658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.113515ms","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-13T14:23:50.085687Z","caller":"traceutil/trace.go:171","msg":"trace[719866378] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:839; }","duration":"265.173537ms","start":"2026-02-13T14:23:49.820505Z","end":"2026-02-13T14:23:50.085678Z","steps":["trace[719866378] 'range keys from in-memory index tree' (duration: 265.025193ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:50.085846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.005773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-13T14:23:50.085873Z","caller":"traceutil/trace.go:171","msg":"trace[1839647159] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:839; }","duration":"259.043874ms","start":"2026-02-13T14:23:49.826819Z","end":"2026-02-13T14:23:50.085863Z","steps":["trace[1839647159] 'range keys from in-memory index tree' (duration: 258.942912ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:50.086408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.016617ms","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-13T14:23:50.086449Z","caller":"traceutil/trace.go:171","msg":"trace[1635904552] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:839; }","duration":"257.387835ms","start":"2026-02-13T14:23:49.829048Z","end":"2026-02-13T14:23:50.086436Z","steps":["trace[1635904552] 'range keys from in-memory index tree' (duration: 256.941915ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:23:50.321318Z","caller":"traceutil/trace.go:171","msg":"trace[1540303303] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"231.174427ms","start":"2026-02-13T14:23:50.090128Z","end":"2026-02-13T14:23:50.321303Z","steps":["trace[1540303303] 'process raft request' (duration: 231.086825ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:23:50.744602Z","caller":"traceutil/trace.go:171","msg":"trace[2034888976] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"653.85537ms","start":"2026-02-13T14:23:50.090733Z","end":"2026-02-13T14:23:50.744588Z","steps":["trace[2034888976] 'process raft request' (duration: 653.666676ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:50.744686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:50.090726Z","time spent":"653.915462ms","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:23:50.744882Z","caller":"traceutil/trace.go:171","msg":"trace[740100174] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"653.307257ms","start":"2026-02-13T14:23:50.091567Z","end":"2026-02-13T14:23:50.744874Z","steps":["trace[740100174] 'process raft request' (duration: 652.920458ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:50.744909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:50.091562Z","time spent":"653.332228ms","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:23:50.74501Z","caller":"traceutil/trace.go:171","msg":"trace[1976184308] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"652.198201ms","start":"2026-02-13T14:23:50.092808Z","end":"2026-02-13T14:23:50.745006Z","steps":["trace[1976184308] 'process raft request' (duration: 651.69846ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:50.745035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:50.092802Z","time spent":"652.219312ms","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T14:23:50.846784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.238264ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-13T14:23:50.846845Z","caller":"traceutil/trace.go:171","msg":"trace[1997266359] linearizableReadLoop","detail":"{readStateIndex:877; appliedIndex:874; }","duration":"681.201186ms","start":"2026-02-13T14:23:50.165633Z","end":"2026-02-13T14:23:50.846834Z","steps":["trace[1997266359] 'read index received' (duration: 155.980702ms)","trace[1997266359] 'applied index is now lower than readState.Index' (duration: 525.219564ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:23:50.846888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.249457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:23:50.8469Z","caller":"traceutil/trace.go:171","msg":"trace[2034221669] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:843; }","duration":"681.293048ms","start":"2026-02-13T14:23:50.165602Z","end":"2026-02-13T14:23:50.846895Z","steps":["trace[2034221669] 'agreement among raft nodes before linearized reading' (duration: 681.256337ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:50.846917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:50.16559Z","time spent":"681.322828ms","remote":"127.0.0.1:37770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-13T14:23:54.779683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.186746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.12\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-13T14:23:54.779776Z","caller":"traceutil/trace.go:171","msg":"trace[1450146865] range","detail":"{range_begin:/registry/masterleases/162.253.55.12; range_end:; response_count:1; response_revision:850; }","duration":"243.314389ms","start":"2026-02-13T14:23:54.536446Z","end":"2026-02-13T14:23:54.779761Z","steps":["trace[1450146865] 'range keys from in-memory index tree' (duration: 243.073814ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:55.109672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:54.781178Z","time spent":"328.489426ms","remote":"127.0.0.1:37790","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-13T14:23:55.28462Z","caller":"traceutil/trace.go:171","msg":"trace[1670716605] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"173.979389ms","start":"2026-02-13T14:23:55.110626Z","end":"2026-02-13T14:23:55.284606Z","steps":["trace[1670716605] 'process raft request' (duration: 173.949479ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:23:55.284904Z","caller":"traceutil/trace.go:171","msg":"trace[1551725455] linearizableReadLoop","detail":"{readStateIndex:887; appliedIndex:886; }","duration":"455.921275ms","start":"2026-02-13T14:23:54.828961Z","end":"2026-02-13T14:23:55.284883Z","steps":["trace[1551725455] 'read index received' (duration: 280.949473ms)","trace[1551725455] 'applied index is now lower than readState.Index' (duration: 174.969802ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:23:55.284973Z","caller":"traceutil/trace.go:171","msg":"trace[602497454] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"500.189943ms","start":"2026-02-13T14:23:54.784776Z","end":"2026-02-13T14:23:55.284966Z","steps":["trace[602497454] 'process raft request' (duration: 400.206441ms)","trace[602497454] 'compare' (duration: 99.500591ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:23:55.285033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:54.784739Z","time spent":"500.247134ms","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T14:23:55.285161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.198661ms","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-13T14:23:55.285176Z","caller":"traceutil/trace.go:171","msg":"trace[577960143] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:852; }","duration":"456.234472ms","start":"2026-02-13T14:23:54.828936Z","end":"2026-02-13T14:23:55.285171Z","steps":["trace[577960143] 'agreement among raft nodes before linearized reading' (duration: 456.158291ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:55.285191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:54.828925Z","time spent":"456.261143ms","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-13T14:23:55.285302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.308944ms","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-13T14:23:55.285315Z","caller":"traceutil/trace.go:171","msg":"trace[1348394275] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:852; }","duration":"456.328525ms","start":"2026-02-13T14:23:54.828983Z","end":"2026-02-13T14:23:55.285311Z","steps":["trace[1348394275] 'agreement among raft nodes before linearized reading' (duration: 456.291794ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:55.285328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:54.82898Z","time spent":"456.344105ms","remote":"127.0.0.1:38052","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-13T14:23:55.285424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.87589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:23:55.285448Z","caller":"traceutil/trace.go:171","msg":"trace[305055935] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:852; }","duration":"115.935952ms","start":"2026-02-13T14:23:55.169506Z","end":"2026-02-13T14:23:55.285442Z","steps":["trace[305055935] 'agreement among raft nodes before linearized reading' (duration: 115.885481ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:23:57.455405Z","caller":"traceutil/trace.go:171","msg":"trace[1243836655] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"145.129959ms","start":"2026-02-13T14:23:57.310261Z","end":"2026-02-13T14:23:57.455391Z","steps":["trace[1243836655] 'process raft request' (duration: 145.065637ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:24:00.913709Z","caller":"traceutil/trace.go:171","msg":"trace[1741730166] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"274.359499ms","start":"2026-02-13T14:24:00.639331Z","end":"2026-02-13T14:24:00.913691Z","steps":["trace[1741730166] 'process raft request' (duration: 274.231326ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:01.766624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.925778ms","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-13T14:24:01.766677Z","caller":"traceutil/trace.go:171","msg":"trace[476059513] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:868; }","duration":"438.008689ms","start":"2026-02-13T14:24:01.328655Z","end":"2026-02-13T14:24:01.766664Z","steps":["trace[476059513] 'range keys from in-memory index tree' (duration: 437.810965ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:01.76671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:01.328648Z","time spent":"438.0573ms","remote":"127.0.0.1:38052","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-13T14:24:01.766891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.087041ms","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-13T14:24:01.766956Z","caller":"traceutil/trace.go:171","msg":"trace[155386519] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:868; }","duration":"298.184723ms","start":"2026-02-13T14:24:01.468755Z","end":"2026-02-13T14:24:01.76694Z","steps":["trace[155386519] 'range keys from in-memory index tree' (duration: 297.950438ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:01.766972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.187795ms","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-13T14:24:01.766991Z","caller":"traceutil/trace.go:171","msg":"trace[2091170351] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:868; }","duration":"451.229047ms","start":"2026-02-13T14:24:01.315757Z","end":"2026-02-13T14:24:01.766986Z","steps":["trace[2091170351] 'range keys from in-memory index tree' (duration: 451.126564ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:01.767004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:01.315744Z","time spent":"451.256887ms","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-13T14:24:01.902863Z","caller":"traceutil/trace.go:171","msg":"trace[1560308997] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"130.823697ms","start":"2026-02-13T14:24:01.772023Z","end":"2026-02-13T14:24:01.902847Z","steps":["trace[1560308997] 'process raft request' (duration: 130.707004ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:24:02.661957Z","caller":"traceutil/trace.go:171","msg":"trace[1192656814] linearizableReadLoop","detail":"{readStateIndex:909; appliedIndex:906; }","duration":"496.619031ms","start":"2026-02-13T14:24:02.165324Z","end":"2026-02-13T14:24:02.661943Z","steps":["trace[1192656814] 'read index received' (duration: 459.884758ms)","trace[1192656814] 'applied index is now lower than readState.Index' (duration: 36.733743ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:24:02.662092Z","caller":"traceutil/trace.go:171","msg":"trace[1153417463] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"889.662206ms","start":"2026-02-13T14:24:01.772423Z","end":"2026-02-13T14:24:02.662086Z","steps":["trace[1153417463] 'process raft request' (duration: 852.825511ms)","trace[1153417463] 'compare' (duration: 36.246122ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:24:02.662153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:01.772359Z","time spent":"889.752458ms","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:24:02.662248Z","caller":"traceutil/trace.go:171","msg":"trace[557967870] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"889.278797ms","start":"2026-02-13T14:24:01.772965Z","end":"2026-02-13T14:24:02.662243Z","steps":["trace[557967870] 'process raft request' (duration: 888.936759ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:02.662275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:01.772939Z","time spent":"889.321948ms","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:24:02.662333Z","caller":"traceutil/trace.go:171","msg":"trace[1868984829] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"887.68105ms","start":"2026-02-13T14:24:01.774647Z","end":"2026-02-13T14:24:02.662328Z","steps":["trace[1868984829] 'process raft request' (duration: 887.275771ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:02.662359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:01.774639Z","time spent":"887.70775ms","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T14:24:02.662421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.097112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:24:02.662439Z","caller":"traceutil/trace.go:171","msg":"trace[1857322647] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:872; }","duration":"497.138642ms","start":"2026-02-13T14:24:02.165296Z","end":"2026-02-13T14:24:02.662435Z","steps":["trace[1857322647] 'agreement among raft nodes before linearized reading' (duration: 497.110342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:02.662455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:02.165283Z","time spent":"497.169544ms","remote":"127.0.0.1:37770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-13T14:24:02.662532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.469298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:24:02.66255Z","caller":"traceutil/trace.go:171","msg":"trace[1119697003] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:872; }","duration":"229.511349ms","start":"2026-02-13T14:24:02.433034Z","end":"2026-02-13T14:24:02.662545Z","steps":["trace[1119697003] 'agreement among raft nodes before linearized reading' (duration: 229.484818ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:02.662693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.734242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:24:02.662711Z","caller":"traceutil/trace.go:171","msg":"trace[1104579163] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:872; }","duration":"126.776304ms","start":"2026-02-13T14:24:02.535931Z","end":"2026-02-13T14:24:02.662707Z","steps":["trace[1104579163] 'agreement among raft nodes before linearized reading' (duration: 126.748193ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:24:04.058365Z","caller":"traceutil/trace.go:171","msg":"trace[405944127] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"122.225548ms","start":"2026-02-13T14:24:03.936113Z","end":"2026-02-13T14:24:04.058339Z","steps":["trace[405944127] 'process raft request' (duration: 122.057664ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:04.306526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.9488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:24:04.306581Z","caller":"traceutil/trace.go:171","msg":"trace[1698823281] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:874; }","duration":"134.059902ms","start":"2026-02-13T14:24:04.172508Z","end":"2026-02-13T14:24:04.306568Z","steps":["trace[1698823281] 'range keys from in-memory index tree' (duration: 133.809656ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:24:04.850319Z","caller":"traceutil/trace.go:171","msg":"trace[76538146] linearizableReadLoop","detail":"{readStateIndex:913; appliedIndex:912; }","duration":"183.262025ms","start":"2026-02-13T14:24:04.66703Z","end":"2026-02-13T14:24:04.850292Z","steps":["trace[76538146] 'read index received' (duration: 157.063887ms)","trace[76538146] 'applied index is now lower than readState.Index' (duration: 26.196958ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:24:04.850332Z","caller":"traceutil/trace.go:171","msg":"trace[260528779] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"235.441556ms","start":"2026-02-13T14:24:04.61486Z","end":"2026-02-13T14:24:04.850301Z","steps":["trace[260528779] 'process raft request' (duration: 209.279519ms)","trace[260528779] 'compare' (duration: 26.026574ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:24:04.850547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.4951ms","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-13T14:24:04.850579Z","caller":"traceutil/trace.go:171","msg":"trace[1383422293] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:875; }","duration":"183.566192ms","start":"2026-02-13T14:24:04.667006Z","end":"2026-02-13T14:24:04.850572Z","steps":["trace[1383422293] 'agreement among raft nodes before linearized reading' (duration: 183.380668ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:04.850851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.017699ms","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-13T14:24:04.85089Z","caller":"traceutil/trace.go:171","msg":"trace[2061548467] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:875; }","duration":"183.07965ms","start":"2026-02-13T14:24:04.667799Z","end":"2026-02-13T14:24:04.850879Z","steps":["trace[2061548467] 'agreement among raft nodes before linearized reading' (duration: 182.911306ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:04.851013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.941317ms","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-13T14:24:04.851037Z","caller":"traceutil/trace.go:171","msg":"trace[1143692978] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:875; }","duration":"182.971388ms","start":"2026-02-13T14:24:04.668057Z","end":"2026-02-13T14:24:04.851028Z","steps":["trace[1143692978] 'agreement among raft nodes before linearized reading' (duration: 182.910486ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:24:06.351049Z","caller":"traceutil/trace.go:171","msg":"trace[842803090] linearizableReadLoop","detail":"{readStateIndex:918; appliedIndex:917; }","duration":"185.695462ms","start":"2026-02-13T14:24:06.165338Z","end":"2026-02-13T14:24:06.351034Z","steps":["trace[842803090] 'read index received' (duration: 185.559449ms)","trace[842803090] 'applied index is now lower than readState.Index' (duration: 135.523µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:24:06.351147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.791294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:24:06.351167Z","caller":"traceutil/trace.go:171","msg":"trace[910876278] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:880; }","duration":"185.847145ms","start":"2026-02-13T14:24:06.165311Z","end":"2026-02-13T14:24:06.351159Z","steps":["trace[910876278] 'agreement among raft nodes before linearized reading' (duration: 185.781934ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:24:06.351425Z","caller":"traceutil/trace.go:171","msg":"trace[937805969] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"287.106806ms","start":"2026-02-13T14:24:06.064312Z","end":"2026-02-13T14:24:06.351419Z","steps":["trace[937805969] 'process raft request' (duration: 286.628475ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:24:06.805684Z","caller":"traceutil/trace.go:171","msg":"trace[664989461] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"338.347806ms","start":"2026-02-13T14:24:06.467316Z","end":"2026-02-13T14:24:06.805663Z","steps":["trace[664989461] 'process raft request' (duration: 335.259744ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:06.805806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:06.467301Z","time spent":"338.440399ms","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:24:08.816893Z","caller":"traceutil/trace.go:171","msg":"trace[672686217] linearizableReadLoop","detail":"{readStateIndex:924; appliedIndex:923; }","duration":"418.949004ms","start":"2026-02-13T14:24:08.397923Z","end":"2026-02-13T14:24:08.816872Z","steps":["trace[672686217] 'read index received' (duration: 418.814681ms)","trace[672686217] 'applied index is now lower than readState.Index' (duration: 133.583µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:24:08.816988Z","caller":"traceutil/trace.go:171","msg":"trace[1911545031] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"460.281524ms","start":"2026-02-13T14:24:08.356695Z","end":"2026-02-13T14:24:08.816977Z","steps":["trace[1911545031] 'process raft request' (duration: 459.998767ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:08.817094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:08.356684Z","time spent":"460.326486ms","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T14:24:08.817262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.325653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-13T14:24:08.817332Z","caller":"traceutil/trace.go:171","msg":"trace[2089013415] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:886; }","duration":"419.493747ms","start":"2026-02-13T14:24:08.397824Z","end":"2026-02-13T14:24:08.817318Z","steps":["trace[2089013415] 'agreement among raft nodes before linearized reading' (duration: 419.337983ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:08.817366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:08.397806Z","time spent":"419.551189ms","remote":"127.0.0.1:38296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":86,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-02-13T14:24:08.906612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.438682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-13T14:24:08.906881Z","caller":"traceutil/trace.go:171","msg":"trace[1197423185] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:887; }","duration":"203.75706ms","start":"2026-02-13T14:24:08.703106Z","end":"2026-02-13T14:24:08.906863Z","steps":["trace[1197423185] 'agreement among raft nodes before linearized reading' (duration: 203.33622ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:24:08.906942Z","caller":"traceutil/trace.go:171","msg":"trace[1345786130] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"422.64347ms","start":"2026-02-13T14:24:08.484288Z","end":"2026-02-13T14:24:08.906931Z","steps":["trace[1345786130] 'process raft request' (duration: 393.108025ms)","trace[1345786130] 'compare' (duration: 28.85851ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:24:08.907541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:08.484268Z","time spent":"423.177722ms","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T14:24:08.906991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.18349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:24:08.908221Z","caller":"traceutil/trace.go:171","msg":"trace[870247442] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:887; }","duration":"146.47866ms","start":"2026-02-13T14:24:08.761727Z","end":"2026-02-13T14:24:08.908206Z","steps":["trace[870247442] 'agreement among raft nodes before linearized reading' (duration: 145.240121ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:24:09.07827Z","caller":"traceutil/trace.go:171","msg":"trace[1506746578] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"166.103737ms","start":"2026-02-13T14:24:08.912136Z","end":"2026-02-13T14:24:09.07824Z","steps":["trace[1506746578] 'process raft request' (duration: 120.969059ms)","trace[1506746578] 'compare' (duration: 44.976974ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:24:09.078442Z","caller":"traceutil/trace.go:171","msg":"trace[386537939] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"165.966273ms","start":"2026-02-13T14:24:08.912467Z","end":"2026-02-13T14:24:09.078433Z","steps":["trace[386537939] 'process raft request' (duration: 165.703757ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:24:13.326143Z","caller":"traceutil/trace.go:171","msg":"trace[1637655117] linearizableReadLoop","detail":"{readStateIndex:956; appliedIndex:955; }","duration":"368.513035ms","start":"2026-02-13T14:24:12.957614Z","end":"2026-02-13T14:24:13.326127Z","steps":["trace[1637655117] 'read index received' (duration: 368.387512ms)","trace[1637655117] 'applied index is now lower than readState.Index' (duration: 124.733µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:24:13.326372Z","caller":"traceutil/trace.go:171","msg":"trace[1535620286] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"480.720461ms","start":"2026-02-13T14:24:12.845646Z","end":"2026-02-13T14:24:13.326367Z","steps":["trace[1535620286] 'process raft request' (duration: 480.365083ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:13.326436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:12.845635Z","time spent":"480.755951ms","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T14:24:13.326576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.983113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-13T14:24:13.326623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.666158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:24:13.326643Z","caller":"traceutil/trace.go:171","msg":"trace[1931080117] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:917; }","duration":"159.700748ms","start":"2026-02-13T14:24:13.166937Z","end":"2026-02-13T14:24:13.326637Z","steps":["trace[1931080117] 'agreement among raft nodes before linearized reading' (duration: 159.667457ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:24:13.326643Z","caller":"traceutil/trace.go:171","msg":"trace[1400363346] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:917; }","duration":"234.095975ms","start":"2026-02-13T14:24:13.09253Z","end":"2026-02-13T14:24:13.326626Z","steps":["trace[1400363346] 'agreement among raft nodes before linearized reading' (duration: 233.88004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:13.326771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.15826ms","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-13T14:24:13.326785Z","caller":"traceutil/trace.go:171","msg":"trace[1715697432] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:917; }","duration":"369.207821ms","start":"2026-02-13T14:24:12.957574Z","end":"2026-02-13T14:24:13.326781Z","steps":["trace[1715697432] 'agreement among raft nodes before linearized reading' (duration: 369.14103ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:13.326799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:12.957557Z","time spent":"369.239522ms","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-13T14:24:13.326942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.346431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-13T14:24:13.326956Z","caller":"traceutil/trace.go:171","msg":"trace[517784093] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:917; }","duration":"234.372011ms","start":"2026-02-13T14:24:13.092579Z","end":"2026-02-13T14:24:13.326951Z","steps":["trace[517784093] 'agreement among raft nodes before linearized reading' (duration: 234.33077ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:13.326954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.69812ms","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-13T14:24:13.326988Z","caller":"traceutil/trace.go:171","msg":"trace[1734314657] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:917; }","duration":"169.754031ms","start":"2026-02-13T14:24:13.157224Z","end":"2026-02-13T14:24:13.326978Z","steps":["trace[1734314657] 'agreement among raft nodes before linearized reading' (duration: 169.658299ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:15.20065Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255110530625296,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-13T14:24:15.440142Z","caller":"traceutil/trace.go:171","msg":"trace[669223933] linearizableReadLoop","detail":"{readStateIndex:962; appliedIndex:961; }","duration":"740.534204ms","start":"2026-02-13T14:24:14.699578Z","end":"2026-02-13T14:24:15.440112Z","steps":["trace[669223933] 'read index received' (duration: 706.983315ms)","trace[669223933] 'applied index is now lower than readState.Index' (duration: 33.550099ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:24:15.440221Z","caller":"traceutil/trace.go:171","msg":"trace[1134679810] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"755.208404ms","start":"2026-02-13T14:24:14.685006Z","end":"2026-02-13T14:24:15.440214Z","steps":["trace[1134679810] 'process raft request' (duration: 721.591704ms)","trace[1134679810] 'compare' (duration: 33.436256ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:24:15.440285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:14.684988Z","time spent":"755.249856ms","remote":"127.0.0.1:37790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T14:24:15.440481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.901832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:24:15.440503Z","caller":"traceutil/trace.go:171","msg":"trace[483437540] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:922; }","duration":"740.943903ms","start":"2026-02-13T14:24:14.699552Z","end":"2026-02-13T14:24:15.440496Z","steps":["trace[483437540] 'agreement among raft nodes before linearized reading' (duration: 740.845801ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:15.44052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:14.699542Z","time spent":"740.974083ms","remote":"127.0.0.1:37770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-13T14:24:15.440841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.101274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:24:15.440892Z","caller":"traceutil/trace.go:171","msg":"trace[1326029619] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:922; }","duration":"274.205657ms","start":"2026-02-13T14:24:15.16668Z","end":"2026-02-13T14:24:15.440886Z","steps":["trace[1326029619] 'agreement among raft nodes before linearized reading' (duration: 274.141455ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:15.442368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.059542ms","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-13T14:24:15.442397Z","caller":"traceutil/trace.go:171","msg":"trace[163349066] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:922; }","duration":"109.117313ms","start":"2026-02-13T14:24:15.333273Z","end":"2026-02-13T14:24:15.44239Z","steps":["trace[163349066] 'agreement among raft nodes before linearized reading' (duration: 109.047461ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:24:15.885385Z","caller":"traceutil/trace.go:171","msg":"trace[516941624] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"432.746397ms","start":"2026-02-13T14:24:15.452618Z","end":"2026-02-13T14:24:15.885364Z","steps":["trace[516941624] 'process raft request' (duration: 432.575203ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:15.885522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:15.452607Z","time spent":"432.85506ms","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:24:15.912743Z","caller":"traceutil/trace.go:171","msg":"trace[888015629] linearizableReadLoop","detail":"{readStateIndex:967; appliedIndex:963; }","duration":"453.906079ms","start":"2026-02-13T14:24:15.458824Z","end":"2026-02-13T14:24:15.91273Z","steps":["trace[888015629] 'read index received' (duration: 427.198379ms)","trace[888015629] 'applied index is now lower than readState.Index' (duration: 26.70711ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:24:15.912957Z","caller":"traceutil/trace.go:171","msg":"trace[155997383] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"460.186494ms","start":"2026-02-13T14:24:15.452759Z","end":"2026-02-13T14:24:15.912945Z","steps":["trace[155997383] 'process raft request' (duration: 459.813975ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:15.913024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:15.452753Z","time spent":"460.230215ms","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:24:15.913168Z","caller":"traceutil/trace.go:171","msg":"trace[1086533303] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"460.312827ms","start":"2026-02-13T14:24:15.452846Z","end":"2026-02-13T14:24:15.913159Z","steps":["trace[1086533303] 'process raft request' (duration: 459.793875ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:15.913993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:15.45284Z","time spent":"460.350208ms","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:24:15.914456Z","caller":"traceutil/trace.go:171","msg":"trace[358216050] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"461.513685ms","start":"2026-02-13T14:24:15.452928Z","end":"2026-02-13T14:24:15.914442Z","steps":["trace[358216050] 'process raft request' (duration: 459.746834ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:15.914661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:15.452923Z","time spent":"461.713849ms","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:24:15.915069Z","caller":"traceutil/trace.go:171","msg":"trace[1164357460] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"462.051947ms","start":"2026-02-13T14:24:15.453009Z","end":"2026-02-13T14:24:15.915061Z","steps":["trace[1164357460] 'process raft request' (duration: 459.694352ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:15.915211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:15.453003Z","time spent":"462.124159ms","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T14:24:15.915519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.689493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-13T14:24:15.915605Z","caller":"traceutil/trace.go:171","msg":"trace[1826503910] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:927; }","duration":"456.727584ms","start":"2026-02-13T14:24:15.458812Z","end":"2026-02-13T14:24:15.91554Z","steps":["trace[1826503910] 'agreement among raft nodes before linearized reading' (duration: 456.594431ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:15.915635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:15.458808Z","time spent":"456.820426ms","remote":"127.0.0.1:38068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-02-13T14:24:26.159006Z","caller":"traceutil/trace.go:171","msg":"trace[166702706] linearizableReadLoop","detail":"{readStateIndex:1017; appliedIndex:1016; }","duration":"164.758825ms","start":"2026-02-13T14:24:25.994219Z","end":"2026-02-13T14:24:26.158978Z","steps":["trace[166702706] 'read index received' (duration: 164.54161ms)","trace[166702706] 'applied index is now lower than readState.Index' (duration: 216.115µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:24:26.160733Z","caller":"traceutil/trace.go:171","msg":"trace[650792929] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"211.53405ms","start":"2026-02-13T14:24:25.949155Z","end":"2026-02-13T14:24:26.160689Z","steps":["trace[650792929] 'process raft request' (duration: 209.678777ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:26.16186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.618001ms","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-13T14:24:26.162279Z","caller":"traceutil/trace.go:171","msg":"trace[726123788] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:975; }","duration":"168.069652ms","start":"2026-02-13T14:24:25.994181Z","end":"2026-02-13T14:24:26.162251Z","steps":["trace[726123788] 'agreement among raft nodes before linearized reading' (duration: 167.527039ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:26.162903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.567583ms","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-13T14:24:26.162952Z","caller":"traceutil/trace.go:171","msg":"trace[1939369319] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:975; }","duration":"168.669186ms","start":"2026-02-13T14:24:25.99427Z","end":"2026-02-13T14:24:26.162939Z","steps":["trace[1939369319] 'agreement among raft nodes before linearized reading' (duration: 168.549873ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:26.163794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.757678ms","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-13T14:24:26.163858Z","caller":"traceutil/trace.go:171","msg":"trace[1005520972] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:975; }","duration":"131.887332ms","start":"2026-02-13T14:24:26.031958Z","end":"2026-02-13T14:24:26.163846Z","steps":["trace[1005520972] 'agreement among raft nodes before linearized reading' (duration: 131.725018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:26.164886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.059164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-13T14:24:26.165022Z","caller":"traceutil/trace.go:171","msg":"trace[330169395] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:975; }","duration":"141.125126ms","start":"2026-02-13T14:24:26.02379Z","end":"2026-02-13T14:24:26.164915Z","steps":["trace[330169395] 'agreement among raft nodes before linearized reading' (duration: 140.763037ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:24:26.41247Z","caller":"traceutil/trace.go:171","msg":"trace[1505045903] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"242.136262ms","start":"2026-02-13T14:24:26.17031Z","end":"2026-02-13T14:24:26.412446Z","steps":["trace[1505045903] 'process raft request' (duration: 210.237551ms)","trace[1505045903] 'compare' (duration: 31.524643ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:24:26.749727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.136118ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T14:24:26.750033Z","caller":"traceutil/trace.go:171","msg":"trace[1311836945] linearizableReadLoop","detail":"{readStateIndex:1021; appliedIndex:1017; }","duration":"443.23737ms","start":"2026-02-13T14:24:26.306777Z","end":"2026-02-13T14:24:26.750014Z","steps":["trace[1311836945] 'read index received' (duration: 73.858255ms)","trace[1311836945] 'applied index is now lower than readState.Index' (duration: 369.378015ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:24:26.75003Z","caller":"traceutil/trace.go:171","msg":"trace[893581667] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"578.88291ms","start":"2026-02-13T14:24:26.171123Z","end":"2026-02-13T14:24:26.750006Z","steps":["trace[893581667] 'process raft request' (duration: 349.353612ms)","trace[893581667] 'compare' (duration: 228.877233ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:24:26.750171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:26.171115Z","time spent":"578.968711ms","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:24:26.750251Z","caller":"traceutil/trace.go:171","msg":"trace[133084838] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"578.113511ms","start":"2026-02-13T14:24:26.172129Z","end":"2026-02-13T14:24:26.750243Z","steps":["trace[133084838] 'process raft request' (duration: 577.711522ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:26.750305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:26.172117Z","time spent":"578.161082ms","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T14:24:26.750394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.881125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:24:26.750425Z","caller":"traceutil/trace.go:171","msg":"trace[1169470102] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:979; }","duration":"207.947297ms","start":"2026-02-13T14:24:26.542467Z","end":"2026-02-13T14:24:26.750415Z","steps":["trace[1169470102] 'agreement among raft nodes before linearized reading' (duration: 207.898326ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:24:26.750515Z","caller":"traceutil/trace.go:171","msg":"trace[1717472085] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"577.20409ms","start":"2026-02-13T14:24:26.173306Z","end":"2026-02-13T14:24:26.75051Z","steps":["trace[1717472085] 'process raft request' (duration: 576.672658ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:26.750547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:26.1733Z","time spent":"577.228691ms","remote":"127.0.0.1:38052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T14:24:26.750613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.847294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:24:26.750626Z","caller":"traceutil/trace.go:171","msg":"trace[1437549928] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:979; }","duration":"443.936046ms","start":"2026-02-13T14:24:26.306684Z","end":"2026-02-13T14:24:26.75062Z","steps":["trace[1437549928] 'agreement among raft nodes before linearized reading' (duration: 443.914835ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:26.750637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:26.30666Z","time spent":"443.975036ms","remote":"127.0.0.1:37770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-13T14:24:27.048401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.444834ms","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-13T14:24:27.048483Z","caller":"traceutil/trace.go:171","msg":"trace[1246717406] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:979; }","duration":"169.67256ms","start":"2026-02-13T14:24:26.878787Z","end":"2026-02-13T14:24:27.048459Z","steps":["trace[1246717406] 'count revisions from in-memory index tree' (duration: 169.336352ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:25:24.850606Z","caller":"traceutil/trace.go:171","msg":"trace[1567590279] linearizableReadLoop","detail":"{readStateIndex:1283; appliedIndex:1282; }","duration":"301.822245ms","start":"2026-02-13T14:25:24.548764Z","end":"2026-02-13T14:25:24.850587Z","steps":["trace[1567590279] 'read index received' (duration: 301.419006ms)","trace[1567590279] 'applied index is now lower than readState.Index' (duration: 402.449µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:25:24.850617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:25:24.540992Z","time spent":"309.616472ms","remote":"127.0.0.1:37790","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-13T14:25:24.85099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.193304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-13T14:25:24.851101Z","caller":"traceutil/trace.go:171","msg":"trace[1731892743] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1229; }","duration":"302.278065ms","start":"2026-02-13T14:25:24.548754Z","end":"2026-02-13T14:25:24.851032Z","steps":["trace[1731892743] 'agreement among raft nodes before linearized reading' (duration: 302.075671ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:25:24.851344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:25:24.54875Z","time spent":"302.441689ms","remote":"127.0.0.1:38178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-02-13T14:25:24.851429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.049593ms","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-13T14:25:24.851512Z","caller":"traceutil/trace.go:171","msg":"trace[1095629023] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1229; }","duration":"130.250818ms","start":"2026-02-13T14:25:24.721252Z","end":"2026-02-13T14:25:24.851503Z","steps":["trace[1095629023] 'agreement among raft nodes before linearized reading' (duration: 130.003352ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:25:25.577919Z","caller":"traceutil/trace.go:171","msg":"trace[1116342831] linearizableReadLoop","detail":"{readStateIndex:1288; appliedIndex:1287; }","duration":"122.864441ms","start":"2026-02-13T14:25:25.455032Z","end":"2026-02-13T14:25:25.577897Z","steps":["trace[1116342831] 'read index received' (duration: 122.652896ms)","trace[1116342831] 'applied index is now lower than readState.Index' (duration: 188.775µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:25:25.578045Z","caller":"traceutil/trace.go:171","msg":"trace[1658114954] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"212.414753ms","start":"2026-02-13T14:25:25.36562Z","end":"2026-02-13T14:25:25.578035Z","steps":["trace[1658114954] 'process raft request' (duration: 212.128067ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:25:25.578273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.254969ms","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-13T14:25:25.57831Z","caller":"traceutil/trace.go:171","msg":"trace[802833841] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1234; }","duration":"123.344761ms","start":"2026-02-13T14:25:25.454956Z","end":"2026-02-13T14:25:25.578301Z","steps":["trace[802833841] 'agreement among raft nodes before linearized reading' (duration: 123.201538ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:25:25.578546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.897206ms","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-13T14:25:25.578611Z","caller":"traceutil/trace.go:171","msg":"trace[1557260970] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1234; }","duration":"110.009339ms","start":"2026-02-13T14:25:25.468587Z","end":"2026-02-13T14:25:25.578596Z","steps":["trace[1557260970] 'agreement among raft nodes before linearized reading' (duration: 109.765483ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:25:31.573184Z","caller":"traceutil/trace.go:171","msg":"trace[280327933] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"154.260144ms","start":"2026-02-13T14:25:31.418899Z","end":"2026-02-13T14:25:31.573159Z","steps":["trace[280327933] 'process raft request' (duration: 154.119411ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:27:34.946292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.573396ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T14:27:34.946383Z","caller":"traceutil/trace.go:171","msg":"trace[666822467] linearizableReadLoop","detail":"{readStateIndex:1869; appliedIndex:1868; }","duration":"247.941608ms","start":"2026-02-13T14:27:34.698427Z","end":"2026-02-13T14:27:34.946369Z","steps":["trace[666822467] 'read index received' (duration: 47.691µs)","trace[666822467] 'applied index is now lower than readState.Index' (duration: 247.892887ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:27:34.946473Z","caller":"traceutil/trace.go:171","msg":"trace[1232601691] transaction","detail":"{read_only:false; response_revision:1788; number_of_response:1; }","duration":"360.601705ms","start":"2026-02-13T14:27:34.585861Z","end":"2026-02-13T14:27:34.946462Z","steps":["trace[1232601691] 'process raft request' (duration: 94.786003ms)","trace[1232601691] 'compare' (duration: 265.214138ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:27:34.946546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:27:34.585835Z","time spent":"360.682467ms","remote":"127.0.0.1:37790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T14:27:34.946778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.348037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:27:34.946807Z","caller":"traceutil/trace.go:171","msg":"trace[1398175776] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1788; }","duration":"248.409209ms","start":"2026-02-13T14:27:34.698388Z","end":"2026-02-13T14:27:34.946798Z","steps":["trace[1398175776] 'agreement among raft nodes before linearized reading' (duration: 248.334357ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:27:34.946951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.988573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:27:34.94698Z","caller":"traceutil/trace.go:171","msg":"trace[855514886] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1788; }","duration":"103.038165ms","start":"2026-02-13T14:27:34.843932Z","end":"2026-02-13T14:27:34.94697Z","steps":["trace[855514886] 'agreement among raft nodes before linearized reading' (duration: 102.996094ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:27:41.448308Z","caller":"traceutil/trace.go:171","msg":"trace[1949286442] transaction","detail":"{read_only:false; response_revision:1816; number_of_response:1; }","duration":"150.975634ms","start":"2026-02-13T14:27:41.297312Z","end":"2026-02-13T14:27:41.448287Z","steps":["trace[1949286442] 'process raft request' (duration: 150.644776ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:27:42.512459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.155801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-13T14:27:42.512523Z","caller":"traceutil/trace.go:171","msg":"trace[1942815145] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1821; }","duration":"117.263064ms","start":"2026-02-13T14:27:42.395247Z","end":"2026-02-13T14:27:42.51251Z","steps":["trace[1942815145] 'range keys from in-memory index tree' (duration: 117.09407ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:27:42.740939Z","caller":"traceutil/trace.go:171","msg":"trace[222015582] linearizableReadLoop","detail":"{readStateIndex:1907; appliedIndex:1904; }","duration":"131.727178ms","start":"2026-02-13T14:27:42.609195Z","end":"2026-02-13T14:27:42.740922Z","steps":["trace[222015582] 'read index received' (duration: 65.71547ms)","trace[222015582] 'applied index is now lower than readState.Index' (duration: 66.011018ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:27:42.741296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.090307ms","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-13T14:27:42.741388Z","caller":"traceutil/trace.go:171","msg":"trace[38180792] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1825; }","duration":"132.19528ms","start":"2026-02-13T14:27:42.609182Z","end":"2026-02-13T14:27:42.741378Z","steps":["trace[38180792] 'agreement among raft nodes before linearized reading' (duration: 131.984945ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:27:42.742033Z","caller":"traceutil/trace.go:171","msg":"trace[1619812351] transaction","detail":"{read_only:false; response_revision:1823; number_of_response:1; }","duration":"224.502806ms","start":"2026-02-13T14:27:42.51752Z","end":"2026-02-13T14:27:42.742022Z","steps":["trace[1619812351] 'process raft request' (duration: 157.380562ms)","trace[1619812351] 'compare' (duration: 65.793143ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:27:42.742747Z","caller":"traceutil/trace.go:171","msg":"trace[407494546] transaction","detail":"{read_only:false; response_revision:1824; number_of_response:1; }","duration":"223.894491ms","start":"2026-02-13T14:27:42.518841Z","end":"2026-02-13T14:27:42.742736Z","steps":["trace[407494546] 'process raft request' (duration: 222.012928ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:27:42.743025Z","caller":"traceutil/trace.go:171","msg":"trace[560300756] transaction","detail":"{read_only:false; response_revision:1825; number_of_response:1; }","duration":"154.737792ms","start":"2026-02-13T14:27:42.588279Z","end":"2026-02-13T14:27:42.743017Z","steps":["trace[560300756] 'process raft request' (duration: 152.614603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:27:43.287062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.616372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:27:43.287448Z","caller":"traceutil/trace.go:171","msg":"trace[584219469] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1826; }","duration":"118.041872ms","start":"2026-02-13T14:27:43.169386Z","end":"2026-02-13T14:27:43.287428Z","steps":["trace[584219469] 'range keys from in-memory index tree' (duration: 117.53449ms)"],"step_count":1}