{"level":"warn","ts":"2026-01-28T13:06:15.496637Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-01-28T13:06:15.496746Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.206: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.206:2380","--initial-cluster=instance=https://162.253.55.206:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.206:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.206: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-01-28T13:06:15.497036Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-01-28T13:06:15.497072Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.206:2380"]}
{"level":"info","ts":"2026-01-28T13:06:15.497102Z","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-01-28T13:06:15.498078Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.206:2379"]}
{"level":"info","ts":"2026-01-28T13:06:15.498704Z","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.206:2380"],"listen-peer-urls":["https://162.253.55.206:2380"],"advertise-client-urls":["https://162.253.55.206:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.206:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.206: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-01-28T13:06:15.508654Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.457235ms"}
{"level":"info","ts":"2026-01-28T13:06:15.533737Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cfc9fec265e3384a","cluster-id":"9866fe2b0d49ed88"}
{"level":"info","ts":"2026-01-28T13:06:15.533873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a switched to configuration voters=()"}
{"level":"info","ts":"2026-01-28T13:06:15.533894Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became follower at term 0"}
{"level":"info","ts":"2026-01-28T13:06:15.533902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cfc9fec265e3384a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-01-28T13:06:15.533977Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became follower at term 1"}
{"level":"info","ts":"2026-01-28T13:06:15.534041Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a switched to configuration voters=(14972778547056425034)"}
{"level":"warn","ts":"2026-01-28T13:06:15.548906Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-01-28T13:06:15.550481Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-01-28T13:06:15.55183Z","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-01-28T13:06:15.553884Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cfc9fec265e3384a","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-01-28T13:06:15.554267Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cfc9fec265e3384a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-01-28T13:06:15.554432Z","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-01-28T13:06:15.554459Z","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-01-28T13:06:15.554481Z","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-01-28T13:06:15.555668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a switched to configuration voters=(14972778547056425034)"}
{"level":"info","ts":"2026-01-28T13:06:15.55585Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9866fe2b0d49ed88","local-member-id":"cfc9fec265e3384a","added-peer-id":"cfc9fec265e3384a","added-peer-peer-urls":["https://162.253.55.206:2380"]}
{"level":"info","ts":"2026-01-28T13:06:15.569823Z","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-01-28T13:06:15.571205Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cfc9fec265e3384a","initial-advertise-peer-urls":["https://162.253.55.206:2380"],"listen-peer-urls":["https://162.253.55.206:2380"],"advertise-client-urls":["https://162.253.55.206:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.206:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-01-28T13:06:15.571339Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-01-28T13:06:15.569958Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.206:2380"}
{"level":"info","ts":"2026-01-28T13:06:15.572663Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.206:2380"}
{"level":"info","ts":"2026-01-28T13:06:15.63599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a is starting a new election at term 1"}
{"level":"info","ts":"2026-01-28T13:06:15.636021Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became pre-candidate at term 1"}
{"level":"info","ts":"2026-01-28T13:06:15.636102Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a received MsgPreVoteResp from cfc9fec265e3384a at term 1"}
{"level":"info","ts":"2026-01-28T13:06:15.636132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became candidate at term 2"}
{"level":"info","ts":"2026-01-28T13:06:15.636142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a received MsgVoteResp from cfc9fec265e3384a at term 2"}
{"level":"info","ts":"2026-01-28T13:06:15.63615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became leader at term 2"}
{"level":"info","ts":"2026-01-28T13:06:15.636157Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cfc9fec265e3384a elected leader cfc9fec265e3384a at term 2"}
{"level":"info","ts":"2026-01-28T13:06:15.644634Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cfc9fec265e3384a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.206:2379]}","request-path":"/0/members/cfc9fec265e3384a/attributes","cluster-id":"9866fe2b0d49ed88","publish-timeout":"7s"}
{"level":"info","ts":"2026-01-28T13:06:15.644671Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-01-28T13:06:15.645058Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-01-28T13:06:15.645194Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-01-28T13:06:15.648806Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.206:2379"}
{"level":"info","ts":"2026-01-28T13:06:15.657482Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-01-28T13:06:15.657499Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-01-28T13:06:15.657663Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9866fe2b0d49ed88","local-member-id":"cfc9fec265e3384a","cluster-version":"3.5"}
{"level":"info","ts":"2026-01-28T13:06:15.660593Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-01-28T13:06:15.660616Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-01-28T13:06:15.665479Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-01-28T13:06:34.42273Z","caller":"traceutil/trace.go:171","msg":"trace[466819698] linearizableReadLoop","detail":"{readStateIndex:471; appliedIndex:470; }","duration":"397.857357ms","start":"2026-01-28T13:06:34.024851Z","end":"2026-01-28T13:06:34.422708Z","steps":["trace[466819698] 'read index received'  (duration: 394.782254ms)","trace[466819698] 'applied index is now lower than readState.Index'  (duration: 3.074033ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-28T13:06:34.422855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.009661ms","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-01-28T13:06:34.422903Z","caller":"traceutil/trace.go:171","msg":"trace[757129921] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:454; }","duration":"398.107604ms","start":"2026-01-28T13:06:34.024785Z","end":"2026-01-28T13:06:34.422892Z","steps":["trace[757129921] 'agreement among raft nodes before linearized reading'  (duration: 398.015532ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:06:34.422947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:06:34.024767Z","time spent":"398.171085ms","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"info","ts":"2026-01-28T13:06:34.42296Z","caller":"traceutil/trace.go:171","msg":"trace[1733394194] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"460.189159ms","start":"2026-01-28T13:06:33.962748Z","end":"2026-01-28T13:06:34.422937Z","steps":["trace[1733394194] 'process raft request'  (duration: 456.928741ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:06:34.423326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:06:33.962725Z","time spent":"460.278461ms","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:449 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"warn","ts":"2026-01-28T13:06:48.562662Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4056225958451180188,"retry-timeout":"500ms"}
{"level":"info","ts":"2026-01-28T13:06:48.82066Z","caller":"traceutil/trace.go:171","msg":"trace[1618101660] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"819.964296ms","start":"2026-01-28T13:06:48.000568Z","end":"2026-01-28T13:06:48.820532Z","steps":["trace[1618101660] 'process raft request'  (duration: 819.776971ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:06:48.820884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:06:48.000539Z","time spent":"820.221942ms","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:520 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:411 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"info","ts":"2026-01-28T13:06:48.821503Z","caller":"traceutil/trace.go:171","msg":"trace[1259380987] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:572; }","duration":"759.354056ms","start":"2026-01-28T13:06:48.062136Z","end":"2026-01-28T13:06:48.82149Z","steps":["trace[1259380987] 'read index received'  (duration: 759.348516ms)","trace[1259380987] 'applied index is now lower than readState.Index'  (duration: 4.26µs)"],"step_count":2}
{"level":"warn","ts":"2026-01-28T13:06:48.8228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.661158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"}
{"level":"info","ts":"2026-01-28T13:06:48.822837Z","caller":"traceutil/trace.go:171","msg":"trace[140302921] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:552; }","duration":"760.701649ms","start":"2026-01-28T13:06:48.062126Z","end":"2026-01-28T13:06:48.822828Z","steps":["trace[140302921] 'agreement among raft nodes before linearized reading'  (duration: 759.436058ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:06:48.822863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:06:48.062094Z","time spent":"760.764289ms","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"info","ts":"2026-01-28T13:06:48.954555Z","caller":"traceutil/trace.go:171","msg":"trace[1972165422] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"351.431097ms","start":"2026-01-28T13:06:48.603102Z","end":"2026-01-28T13:06:48.954533Z","steps":["trace[1972165422] 'process raft request'  (duration: 347.326669ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:06:48.954682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:06:48.603071Z","time spent":"351.5425ms","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" mod_revision:463 > success:<request_put:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" value_size:600 >> failure:<request_range:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" > >"}
{"level":"info","ts":"2026-01-28T13:06:48.957659Z","caller":"traceutil/trace.go:171","msg":"trace[1451724796] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"128.832962ms","start":"2026-01-28T13:06:48.82879Z","end":"2026-01-28T13:06:48.957623Z","steps":["trace[1451724796] 'process raft request'  (duration: 126.917576ms)"],"step_count":1}
{"level":"info","ts":"2026-01-28T13:06:48.957925Z","caller":"traceutil/trace.go:171","msg":"trace[1066855627] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"155.655184ms","start":"2026-01-28T13:06:48.802263Z","end":"2026-01-28T13:06:48.957918Z","steps":["trace[1066855627] 'process raft request'  (duration: 153.355739ms)"],"step_count":1}
{"level":"info","ts":"2026-01-28T13:06:48.957994Z","caller":"traceutil/trace.go:171","msg":"trace[1527237788] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:572; }","duration":"136.442964ms","start":"2026-01-28T13:06:48.821541Z","end":"2026-01-28T13:06:48.957984Z","steps":["trace[1527237788] 'read index received'  (duration: 128.917944ms)","trace[1527237788] 'applied index is now lower than readState.Index'  (duration: 7.52407ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-28T13:06:48.958117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.036116ms","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-01-28T13:06:48.958143Z","caller":"traceutil/trace.go:171","msg":"trace[641037850] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:555; }","duration":"784.107078ms","start":"2026-01-28T13:06:48.174025Z","end":"2026-01-28T13:06:48.958132Z","steps":["trace[641037850] 'agreement among raft nodes before linearized reading'  (duration: 783.981965ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:06:48.958163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:06:48.174014Z","time spent":"784.143269ms","remote":"127.0.0.1:54698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5201,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "}
{"level":"warn","ts":"2026-01-28T13:06:48.958323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.050982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-28T13:06:48.958338Z","caller":"traceutil/trace.go:171","msg":"trace[1110428152] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:555; }","duration":"232.103513ms","start":"2026-01-28T13:06:48.726229Z","end":"2026-01-28T13:06:48.958332Z","steps":["trace[1110428152] 'agreement among raft nodes before linearized reading'  (duration: 232.074682ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:06:48.958512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.378911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"info","ts":"2026-01-28T13:06:48.958527Z","caller":"traceutil/trace.go:171","msg":"trace[1937087929] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:555; }","duration":"332.437463ms","start":"2026-01-28T13:06:48.626084Z","end":"2026-01-28T13:06:48.958521Z","steps":["trace[1937087929] 'agreement among raft nodes before linearized reading'  (duration: 332.34088ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:06:48.95854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:06:48.626071Z","time spent":"332.465914ms","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-01-28T13:06:48.958639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.219901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"}
{"level":"info","ts":"2026-01-28T13:06:48.958656Z","caller":"traceutil/trace.go:171","msg":"trace[1597635899] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:555; }","duration":"384.287174ms","start":"2026-01-28T13:06:48.574361Z","end":"2026-01-28T13:06:48.958648Z","steps":["trace[1597635899] 'agreement among raft nodes before linearized reading'  (duration: 384.234122ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:06:48.95867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:06:48.57433Z","time spent":"384.335424ms","remote":"127.0.0.1:54698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10401,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "}
{"level":"warn","ts":"2026-01-28T13:06:48.95873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.776095ms","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-01-28T13:06:48.958744Z","caller":"traceutil/trace.go:171","msg":"trace[848678325] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:555; }","duration":"445.807575ms","start":"2026-01-28T13:06:48.51293Z","end":"2026-01-28T13:06:48.958737Z","steps":["trace[848678325] 'agreement among raft nodes before linearized reading'  (duration: 445.778934ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:06:48.95876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:06:48.512916Z","time spent":"445.838316ms","remote":"127.0.0.1:54894","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-01-28T13:06:48.958837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.370969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"info","ts":"2026-01-28T13:06:48.958855Z","caller":"traceutil/trace.go:171","msg":"trace[642460793] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:555; }","duration":"464.417451ms","start":"2026-01-28T13:06:48.494431Z","end":"2026-01-28T13:06:48.958849Z","steps":["trace[642460793] 'agreement among raft nodes before linearized reading'  (duration: 464.38224ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:06:48.95888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:06:48.494414Z","time spent":"464.451211ms","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"warn","ts":"2026-01-28T13:06:48.958963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.588115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"}
{"level":"info","ts":"2026-01-28T13:06:48.958997Z","caller":"traceutil/trace.go:171","msg":"trace[416276404] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:555; }","duration":"584.653997ms","start":"2026-01-28T13:06:48.374337Z","end":"2026-01-28T13:06:48.958991Z","steps":["trace[416276404] 'agreement among raft nodes before linearized reading'  (duration: 584.596546ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:06:48.959011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:06:48.374319Z","time spent":"584.688207ms","remote":"127.0.0.1:54698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5610,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "}
{"level":"warn","ts":"2026-01-28T13:06:50.476293Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4056225958451180212,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-01-28T13:06:50.977137Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4056225958451180212,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-01-28T13:06:51.057853Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.246517811s","expected-duration":"1s"}
{"level":"info","ts":"2026-01-28T13:06:51.058293Z","caller":"traceutil/trace.go:171","msg":"trace[1636068410] linearizableReadLoop","detail":"{readStateIndex:589; appliedIndex:588; }","duration":"1.082077236s","start":"2026-01-28T13:06:49.976202Z","end":"2026-01-28T13:06:51.058279Z","steps":["trace[1636068410] 'read index received'  (duration: 1.081810099s)","trace[1636068410] 'applied index is now lower than readState.Index'  (duration: 266.567µs)"],"step_count":2}
{"level":"info","ts":"2026-01-28T13:06:51.058371Z","caller":"traceutil/trace.go:171","msg":"trace[108483619] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"1.24733097s","start":"2026-01-28T13:06:49.811035Z","end":"2026-01-28T13:06:51.058366Z","steps":["trace[108483619] 'process raft request'  (duration: 1.247089114s)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:06:51.058437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:06:49.811016Z","time spent":"1.24737154s","remote":"127.0.0.1:54698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" mod_revision:0 > success:<request_put:<key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" value_size:187346 >> failure:<>"}
{"level":"warn","ts":"2026-01-28T13:06:51.058672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.082469245s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"}
{"level":"info","ts":"2026-01-28T13:06:51.058689Z","caller":"traceutil/trace.go:171","msg":"trace[1808217265] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:568; }","duration":"1.082547498s","start":"2026-01-28T13:06:49.976137Z","end":"2026-01-28T13:06:51.058684Z","steps":["trace[1808217265] 'agreement among raft nodes before linearized reading'  (duration: 1.082377234s)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:06:51.058704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:06:49.976118Z","time spent":"1.082581868s","remote":"127.0.0.1:54698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":1,"response size":188276,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "}
{"level":"warn","ts":"2026-01-28T13:06:51.058948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"884.50296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"}
{"level":"info","ts":"2026-01-28T13:06:51.05898Z","caller":"traceutil/trace.go:171","msg":"trace[840669306] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:568; }","duration":"884.564241ms","start":"2026-01-28T13:06:50.174402Z","end":"2026-01-28T13:06:51.058966Z","steps":["trace[840669306] 'agreement among raft nodes before linearized reading'  (duration: 884.455279ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:06:51.059001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:06:50.174379Z","time spent":"884.617773ms","remote":"127.0.0.1:54698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4357,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "}
{"level":"warn","ts":"2026-01-28T13:06:51.059336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.589078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"}
{"level":"info","ts":"2026-01-28T13:06:51.059351Z","caller":"traceutil/trace.go:171","msg":"trace[1220143143] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:568; }","duration":"233.626489ms","start":"2026-01-28T13:06:50.82572Z","end":"2026-01-28T13:06:51.059346Z","steps":["trace[1220143143] 'agreement among raft nodes before linearized reading'  (duration: 233.589639ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:06:51.059407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.537277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-28T13:06:51.059418Z","caller":"traceutil/trace.go:171","msg":"trace[436229644] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:568; }","duration":"335.570808ms","start":"2026-01-28T13:06:50.723844Z","end":"2026-01-28T13:06:51.059415Z","steps":["trace[436229644] 'agreement among raft nodes before linearized reading'  (duration: 335.552308ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:06:51.059431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:06:50.723832Z","time spent":"335.596658ms","remote":"127.0.0.1:54652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-01-28T13:06:51.059521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.799737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"}
{"level":"info","ts":"2026-01-28T13:06:51.059534Z","caller":"traceutil/trace.go:171","msg":"trace[202006807] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:568; }","duration":"685.813267ms","start":"2026-01-28T13:06:50.373716Z","end":"2026-01-28T13:06:51.059529Z","steps":["trace[202006807] 'agreement among raft nodes before linearized reading'  (duration: 685.767266ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:06:51.059546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:06:50.37366Z","time spent":"685.882558ms","remote":"127.0.0.1:54698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":24268,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "}
{"level":"info","ts":"2026-01-28T13:06:53.975758Z","caller":"traceutil/trace.go:171","msg":"trace[1354782705] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"686.312478ms","start":"2026-01-28T13:06:53.289423Z","end":"2026-01-28T13:06:53.975735Z","steps":["trace[1354782705] 'process raft request'  (duration: 686.197945ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:06:53.975906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:06:53.289411Z","time spent":"686.42331ms","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:573 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:410 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"warn","ts":"2026-01-28T13:06:58.05047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.113176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-28T13:06:58.050523Z","caller":"traceutil/trace.go:171","msg":"trace[696042546] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:595; }","duration":"322.206879ms","start":"2026-01-28T13:06:57.728303Z","end":"2026-01-28T13:06:58.05051Z","steps":["trace[696042546] 'range keys from in-memory index tree'  (duration: 322.048044ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:06:58.050544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:06:57.728283Z","time spent":"322.255749ms","remote":"127.0.0.1:54660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"info","ts":"2026-01-28T13:06:58.699763Z","caller":"traceutil/trace.go:171","msg":"trace[1170919632] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"643.870693ms","start":"2026-01-28T13:06:58.055876Z","end":"2026-01-28T13:06:58.699747Z","steps":["trace[1170919632] 'process raft request'  (duration: 643.78294ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:06:58.699888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:06:58.055863Z","time spent":"643.959745ms","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:588 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:411 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"info","ts":"2026-01-28T13:06:58.918371Z","caller":"traceutil/trace.go:171","msg":"trace[672056998] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"214.243785ms","start":"2026-01-28T13:06:58.704104Z","end":"2026-01-28T13:06:58.918348Z","steps":["trace[672056998] 'read index received'  (duration: 214.069101ms)","trace[672056998] 'applied index is now lower than readState.Index'  (duration: 173.484µs)"],"step_count":2}
{"level":"warn","ts":"2026-01-28T13:06:58.918519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.390589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-28T13:06:58.918555Z","caller":"traceutil/trace.go:171","msg":"trace[689377340] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:597; }","duration":"214.464111ms","start":"2026-01-28T13:06:58.704078Z","end":"2026-01-28T13:06:58.918542Z","steps":["trace[689377340] 'agreement among raft nodes before linearized reading'  (duration: 214.372719ms)"],"step_count":1}
{"level":"info","ts":"2026-01-28T13:06:58.918764Z","caller":"traceutil/trace.go:171","msg":"trace[1944429458] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"850.071946ms","start":"2026-01-28T13:06:58.068681Z","end":"2026-01-28T13:06:58.918752Z","steps":["trace[1944429458] 'process raft request'  (duration: 849.529233ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:06:58.918844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:06:58.068669Z","time spent":"850.120397ms","remote":"127.0.0.1:54828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/pods/kube-system/cilium-4fttn\" mod_revision:505 > success:<request_put:<key:\"/registry/pods/kube-system/cilium-4fttn\" value_size:16754 >> failure:<request_range:<key:\"/registry/pods/kube-system/cilium-4fttn\" > >"}
{"level":"warn","ts":"2026-01-28T13:07:04.29243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.376768ms","expected-duration":"100ms","prefix":"","request":"header:<ID:4056225958451180371 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/events/kube-system/cilium-4fttn.188ee6f042c9858b\" mod_revision:0 > success:<request_put:<key:\"/registry/events/kube-system/cilium-4fttn.188ee6f042c9858b\" value_size:640 lease:4056225958451179521 >> failure:<>>","response":"size:16"}
{"level":"info","ts":"2026-01-28T13:07:04.292486Z","caller":"traceutil/trace.go:171","msg":"trace[1058202175] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:653; }","duration":"1.010784949s","start":"2026-01-28T13:07:03.281692Z","end":"2026-01-28T13:07:04.292477Z","steps":["trace[1058202175] 'read index received'  (duration: 197.215786ms)","trace[1058202175] 'applied index is now lower than readState.Index'  (duration: 813.568253ms)"],"step_count":2}
{"level":"info","ts":"2026-01-28T13:07:04.292533Z","caller":"traceutil/trace.go:171","msg":"trace[954286013] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"1.100809263s","start":"2026-01-28T13:07:03.191717Z","end":"2026-01-28T13:07:04.292526Z","steps":["trace[954286013] 'process raft request'  (duration: 287.22525ms)","trace[954286013] 'compare'  (duration: 813.180004ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-28T13:07:04.292572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:07:03.191702Z","time spent":"1.100842834s","remote":"127.0.0.1:54720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/events/kube-system/cilium-4fttn.188ee6f042c9858b\" mod_revision:0 > success:<request_put:<key:\"/registry/events/kube-system/cilium-4fttn.188ee6f042c9858b\" value_size:640 lease:4056225958451179521 >> failure:<>"}
{"level":"warn","ts":"2026-01-28T13:07:04.292731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.011044075s","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-01-28T13:07:04.292745Z","caller":"traceutil/trace.go:171","msg":"trace[998428128] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:631; }","duration":"1.01120514s","start":"2026-01-28T13:07:03.281535Z","end":"2026-01-28T13:07:04.292741Z","steps":["trace[998428128] 'agreement among raft nodes before linearized reading'  (duration: 1.011123328s)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:04.292759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:07:03.281523Z","time spent":"1.01123341s","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"warn","ts":"2026-01-28T13:07:04.292843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.010423971s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"}
{"level":"info","ts":"2026-01-28T13:07:04.292855Z","caller":"traceutil/trace.go:171","msg":"trace[385592190] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:631; }","duration":"1.010472532s","start":"2026-01-28T13:07:03.282379Z","end":"2026-01-28T13:07:04.292851Z","steps":["trace[385592190] 'agreement among raft nodes before linearized reading'  (duration: 1.010436451s)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:04.292866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:07:03.28236Z","time spent":"1.010503103s","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"warn","ts":"2026-01-28T13:07:04.292981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.790746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4fttn\" ","response":"range_response_count:1 size:17918"}
{"level":"info","ts":"2026-01-28T13:07:04.292994Z","caller":"traceutil/trace.go:171","msg":"trace[289468212] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4fttn; range_end:; response_count:1; response_revision:631; }","duration":"226.833258ms","start":"2026-01-28T13:07:04.066157Z","end":"2026-01-28T13:07:04.29299Z","steps":["trace[289468212] 'agreement among raft nodes before linearized reading'  (duration: 226.790827ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:04.293063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.324003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-28T13:07:04.293074Z","caller":"traceutil/trace.go:171","msg":"trace[294385228] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"565.335173ms","start":"2026-01-28T13:07:03.727735Z","end":"2026-01-28T13:07:04.293071Z","steps":["trace[294385228] 'agreement among raft nodes before linearized reading'  (duration: 565.316843ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:04.293084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:07:03.72769Z","time spent":"565.391935ms","remote":"127.0.0.1:54652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-01-28T13:07:04.293153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.010630875s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"}
{"level":"info","ts":"2026-01-28T13:07:04.293163Z","caller":"traceutil/trace.go:171","msg":"trace[632181010] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:631; }","duration":"1.010650706s","start":"2026-01-28T13:07:03.28251Z","end":"2026-01-28T13:07:04.29316Z","steps":["trace[632181010] 'agreement among raft nodes before linearized reading'  (duration: 1.010620065s)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:04.293175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:07:03.282504Z","time spent":"1.010667687s","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-01-28T13:07:04.822692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.715672ms","expected-duration":"100ms","prefix":"","request":"header:<ID:4056225958451180375 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:619 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:371 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >>","response":"size:16"}
{"level":"info","ts":"2026-01-28T13:07:04.822763Z","caller":"traceutil/trace.go:171","msg":"trace[276980094] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"524.227001ms","start":"2026-01-28T13:07:04.298526Z","end":"2026-01-28T13:07:04.822753Z","steps":["trace[276980094] 'process raft request'  (duration: 152.407156ms)","trace[276980094] 'compare'  (duration: 371.547408ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-28T13:07:04.822791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:07:04.298517Z","time spent":"524.262222ms","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:619 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:371 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"info","ts":"2026-01-28T13:07:05.206561Z","caller":"traceutil/trace.go:171","msg":"trace[878295882] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"899.478188ms","start":"2026-01-28T13:07:04.307065Z","end":"2026-01-28T13:07:05.206543Z","steps":["trace[878295882] 'process raft request'  (duration: 899.431607ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:05.206685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:07:04.307045Z","time spent":"899.57313ms","remote":"127.0.0.1:54828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18065,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/pods/kube-system/cilium-4fttn\" mod_revision:628 > success:<request_put:<key:\"/registry/pods/kube-system/cilium-4fttn\" value_size:18016 >> failure:<request_range:<key:\"/registry/pods/kube-system/cilium-4fttn\" > >"}
{"level":"info","ts":"2026-01-28T13:07:05.20705Z","caller":"traceutil/trace.go:171","msg":"trace[1109694577] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"908.357631ms","start":"2026-01-28T13:07:04.298675Z","end":"2026-01-28T13:07:05.207032Z","steps":["trace[1109694577] 'process raft request'  (duration: 891.391525ms)","trace[1109694577] 'compare'  (duration: 16.054204ms)"],"step_count":2}
{"level":"info","ts":"2026-01-28T13:07:05.207098Z","caller":"traceutil/trace.go:171","msg":"trace[1507138904] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"908.386511ms","start":"2026-01-28T13:07:04.298698Z","end":"2026-01-28T13:07:05.207084Z","steps":["trace[1507138904] 'process raft request'  (duration: 907.747716ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:05.207137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:07:04.298669Z","time spent":"908.419432ms","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:618 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:412 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"warn","ts":"2026-01-28T13:07:05.207158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:07:04.298695Z","time spent":"908.432632ms","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:620 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"info","ts":"2026-01-28T13:07:05.212537Z","caller":"traceutil/trace.go:171","msg":"trace[1150487437] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:655; }","duration":"493.885295ms","start":"2026-01-28T13:07:04.71864Z","end":"2026-01-28T13:07:05.212525Z","steps":["trace[1150487437] 'read index received'  (duration: 471.435198ms)","trace[1150487437] 'applied index is now lower than readState.Index'  (duration: 22.448947ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-28T13:07:05.212887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.240024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"}
{"level":"info","ts":"2026-01-28T13:07:05.21291Z","caller":"traceutil/trace.go:171","msg":"trace[1164444549] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:635; }","duration":"494.292245ms","start":"2026-01-28T13:07:04.71861Z","end":"2026-01-28T13:07:05.212902Z","steps":["trace[1164444549] 'agreement among raft nodes before linearized reading'  (duration: 494.189513ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:05.212931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:07:04.718577Z","time spent":"494.350046ms","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"info","ts":"2026-01-28T13:07:09.480389Z","caller":"traceutil/trace.go:171","msg":"trace[1115154243] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"347.425651ms","start":"2026-01-28T13:07:09.13294Z","end":"2026-01-28T13:07:09.480365Z","steps":["trace[1115154243] 'process raft request'  (duration: 347.316599ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:09.480521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:07:09.132923Z","time spent":"347.517724ms","remote":"127.0.0.1:60266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2324,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/cilium.io/ciliumnodes/instance\" mod_revision:654 > success:<request_put:<key:\"/registry/cilium.io/ciliumnodes/instance\" value_size:2276 >> failure:<request_range:<key:\"/registry/cilium.io/ciliumnodes/instance\" > >"}
{"level":"info","ts":"2026-01-28T13:07:09.48088Z","caller":"traceutil/trace.go:171","msg":"trace[1482123759] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:681; }","duration":"265.233575ms","start":"2026-01-28T13:07:09.215624Z","end":"2026-01-28T13:07:09.480858Z","steps":["trace[1482123759] 'read index received'  (duration: 265.225325ms)","trace[1482123759] 'applied index is now lower than readState.Index'  (duration: 7.01µs)"],"step_count":2}
{"level":"warn","ts":"2026-01-28T13:07:09.481021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.393509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"}
{"level":"info","ts":"2026-01-28T13:07:09.481051Z","caller":"traceutil/trace.go:171","msg":"trace[246841014] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:656; }","duration":"265.45931ms","start":"2026-01-28T13:07:09.215583Z","end":"2026-01-28T13:07:09.481043Z","steps":["trace[246841014] 'agreement among raft nodes before linearized reading'  (duration: 265.362868ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:09.496163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.430415ms","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-01-28T13:07:09.496202Z","caller":"traceutil/trace.go:171","msg":"trace[1770278585] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:657; }","duration":"279.487506ms","start":"2026-01-28T13:07:09.216706Z","end":"2026-01-28T13:07:09.496194Z","steps":["trace[1770278585] 'agreement among raft nodes before linearized reading'  (duration: 279.395124ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:09.496284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.319936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"}
{"level":"info","ts":"2026-01-28T13:07:09.496296Z","caller":"traceutil/trace.go:171","msg":"trace[2060182401] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:657; }","duration":"206.360597ms","start":"2026-01-28T13:07:09.289932Z","end":"2026-01-28T13:07:09.496292Z","steps":["trace[2060182401] 'agreement among raft nodes before linearized reading'  (duration: 206.325386ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:09.496366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.265279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.206\" ","response":"range_response_count:1 size:135"}
{"level":"info","ts":"2026-01-28T13:07:09.496377Z","caller":"traceutil/trace.go:171","msg":"trace[2027482713] range","detail":"{range_begin:/registry/masterleases/162.253.55.206; range_end:; response_count:1; response_revision:657; }","duration":"248.29043ms","start":"2026-01-28T13:07:09.248083Z","end":"2026-01-28T13:07:09.496374Z","steps":["trace[2027482713] 'agreement among raft nodes before linearized reading'  (duration: 248.256889ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:09.496459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.19045ms","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-01-28T13:07:09.49647Z","caller":"traceutil/trace.go:171","msg":"trace[1394036283] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:657; }","duration":"264.231121ms","start":"2026-01-28T13:07:09.232235Z","end":"2026-01-28T13:07:09.496466Z","steps":["trace[1394036283] 'agreement among raft nodes before linearized reading'  (duration: 264.198941ms)"],"step_count":1}
{"level":"info","ts":"2026-01-28T13:07:13.706255Z","caller":"traceutil/trace.go:171","msg":"trace[1504098730] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"163.651555ms","start":"2026-01-28T13:07:13.542585Z","end":"2026-01-28T13:07:13.706237Z","steps":["trace[1504098730] 'process raft request'  (duration: 151.599097ms)","trace[1504098730] 'compare'  (duration: 11.975116ms)"],"step_count":2}
{"level":"info","ts":"2026-01-28T13:07:13.719138Z","caller":"traceutil/trace.go:171","msg":"trace[1688064242] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"175.801755ms","start":"2026-01-28T13:07:13.543327Z","end":"2026-01-28T13:07:13.719129Z","steps":["trace[1688064242] 'process raft request'  (duration: 175.728334ms)"],"step_count":1}
{"level":"info","ts":"2026-01-28T13:07:13.719284Z","caller":"traceutil/trace.go:171","msg":"trace[703417278] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"174.505435ms","start":"2026-01-28T13:07:13.544757Z","end":"2026-01-28T13:07:13.719263Z","steps":["trace[703417278] 'process raft request'  (duration: 174.352281ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:17.66347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.896644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"info","ts":"2026-01-28T13:07:17.663542Z","caller":"traceutil/trace.go:171","msg":"trace[1652176841] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:729; }","duration":"114.021628ms","start":"2026-01-28T13:07:17.549504Z","end":"2026-01-28T13:07:17.663526Z","steps":["trace[1652176841] 'range keys from in-memory index tree'  (duration: 113.751271ms)"],"step_count":1}
{"level":"info","ts":"2026-01-28T13:07:17.908968Z","caller":"traceutil/trace.go:171","msg":"trace[960571667] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"166.130065ms","start":"2026-01-28T13:07:17.742821Z","end":"2026-01-28T13:07:17.908951Z","steps":["trace[960571667] 'process raft request'  (duration: 166.029702ms)"],"step_count":1}
{"level":"info","ts":"2026-01-28T13:07:18.061867Z","caller":"traceutil/trace.go:171","msg":"trace[418664044] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"318.946011ms","start":"2026-01-28T13:07:17.742904Z","end":"2026-01-28T13:07:18.06185Z","steps":["trace[418664044] 'process raft request'  (duration: 312.549457ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:18.061991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:07:17.7429Z","time spent":"319.032363ms","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:728 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:411 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"warn","ts":"2026-01-28T13:07:19.988614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.617884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.206\" ","response":"range_response_count:1 size:135"}
{"level":"info","ts":"2026-01-28T13:07:19.988679Z","caller":"traceutil/trace.go:171","msg":"trace[1640537727] range","detail":"{range_begin:/registry/masterleases/162.253.55.206; range_end:; response_count:1; response_revision:734; }","duration":"739.716766ms","start":"2026-01-28T13:07:19.248946Z","end":"2026-01-28T13:07:19.988663Z","steps":["trace[1640537727] 'range keys from in-memory index tree'  (duration: 739.474931ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:19.988709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:07:19.248933Z","time spent":"739.769217ms","remote":"127.0.0.1:54682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.206\" "}
{"level":"info","ts":"2026-01-28T13:07:19.989408Z","caller":"traceutil/trace.go:171","msg":"trace[651752757] linearizableReadLoop","detail":"{readStateIndex:763; appliedIndex:762; }","duration":"261.900106ms","start":"2026-01-28T13:07:19.727498Z","end":"2026-01-28T13:07:19.989398Z","steps":["trace[651752757] 'read index received'  (duration: 202.258729ms)","trace[651752757] 'applied index is now lower than readState.Index'  (duration: 59.640177ms)"],"step_count":2}
{"level":"info","ts":"2026-01-28T13:07:19.9896Z","caller":"traceutil/trace.go:171","msg":"trace[51617990] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"375.432652ms","start":"2026-01-28T13:07:19.614157Z","end":"2026-01-28T13:07:19.98959Z","steps":["trace[51617990] 'process raft request'  (duration: 315.646551ms)","trace[51617990] 'compare'  (duration: 58.435068ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-28T13:07:19.989667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:07:19.614121Z","time spent":"375.498683ms","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" mod_revision:657 > success:<request_put:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" value_size:600 >> failure:<request_range:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" > >"}
{"level":"warn","ts":"2026-01-28T13:07:19.989773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.283745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-28T13:07:19.98979Z","caller":"traceutil/trace.go:171","msg":"trace[81571513] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:735; }","duration":"262.332276ms","start":"2026-01-28T13:07:19.727452Z","end":"2026-01-28T13:07:19.989784Z","steps":["trace[81571513] 'agreement among raft nodes before linearized reading'  (duration: 262.300146ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:19.989944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.859229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"info","ts":"2026-01-28T13:07:19.989964Z","caller":"traceutil/trace.go:171","msg":"trace[2033844162] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:735; }","duration":"257.893469ms","start":"2026-01-28T13:07:19.732065Z","end":"2026-01-28T13:07:19.989958Z","steps":["trace[2033844162] 'agreement among raft nodes before linearized reading'  (duration: 257.817068ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:19.990234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.598297ms","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-01-28T13:07:19.990255Z","caller":"traceutil/trace.go:171","msg":"trace[2035682644] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:735; }","duration":"184.634397ms","start":"2026-01-28T13:07:19.805614Z","end":"2026-01-28T13:07:19.990248Z","steps":["trace[2035682644] 'agreement among raft nodes before linearized reading'  (duration: 184.593226ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:19.990396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.880297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"info","ts":"2026-01-28T13:07:19.990429Z","caller":"traceutil/trace.go:171","msg":"trace[424210021] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:735; }","duration":"246.915927ms","start":"2026-01-28T13:07:19.743497Z","end":"2026-01-28T13:07:19.990413Z","steps":["trace[424210021] 'agreement among raft nodes before linearized reading'  (duration: 246.851146ms)"],"step_count":1}
{"level":"info","ts":"2026-01-28T13:07:20.135605Z","caller":"traceutil/trace.go:171","msg":"trace[1655352985] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"130.033731ms","start":"2026-01-28T13:07:20.005545Z","end":"2026-01-28T13:07:20.135579Z","steps":["trace[1655352985] 'process raft request'  (duration: 124.249553ms)"],"step_count":1}
{"level":"info","ts":"2026-01-28T13:07:20.333542Z","caller":"traceutil/trace.go:171","msg":"trace[1117522833] linearizableReadLoop","detail":"{readStateIndex:768; appliedIndex:764; }","duration":"268.300409ms","start":"2026-01-28T13:07:20.065226Z","end":"2026-01-28T13:07:20.333527Z","steps":["trace[1117522833] 'read index received'  (duration: 64.579945ms)","trace[1117522833] 'applied index is now lower than readState.Index'  (duration: 203.719994ms)"],"step_count":2}
{"level":"info","ts":"2026-01-28T13:07:20.333698Z","caller":"traceutil/trace.go:171","msg":"trace[704875933] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"327.598688ms","start":"2026-01-28T13:07:20.006091Z","end":"2026-01-28T13:07:20.33369Z","steps":["trace[704875933] 'process raft request'  (duration: 233.783823ms)","trace[704875933] 'compare'  (duration: 93.517717ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-28T13:07:20.333783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:07:20.006082Z","time spent":"327.645598ms","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:731 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:413 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"info","ts":"2026-01-28T13:07:20.333893Z","caller":"traceutil/trace.go:171","msg":"trace[618664440] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"327.190687ms","start":"2026-01-28T13:07:20.006698Z","end":"2026-01-28T13:07:20.333889Z","steps":["trace[618664440] 'process raft request'  (duration: 326.776997ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:20.333919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:07:20.006688Z","time spent":"327.217038ms","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:732 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"info","ts":"2026-01-28T13:07:20.333976Z","caller":"traceutil/trace.go:171","msg":"trace[1317025237] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"327.150356ms","start":"2026-01-28T13:07:20.006822Z","end":"2026-01-28T13:07:20.333972Z","steps":["trace[1317025237] 'process raft request'  (duration: 326.674885ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:20.334006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:07:20.006816Z","time spent":"327.169887ms","remote":"127.0.0.1:54682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/masterleases/162.253.55.206\" mod_revision:660 > success:<request_put:<key:\"/registry/masterleases/162.253.55.206\" value_size:67 lease:4056225958451180569 >> failure:<request_range:<key:\"/registry/masterleases/162.253.55.206\" > >"}
{"level":"warn","ts":"2026-01-28T13:07:20.334116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.888443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"}
{"level":"info","ts":"2026-01-28T13:07:20.334132Z","caller":"traceutil/trace.go:171","msg":"trace[1190421652] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:739; }","duration":"268.937794ms","start":"2026-01-28T13:07:20.065189Z","end":"2026-01-28T13:07:20.334127Z","steps":["trace[1190421652] 'agreement among raft nodes before linearized reading'  (duration: 268.871182ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:20.928853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:07:20.569695Z","time spent":"359.153963ms","remote":"127.0.0.1:54720","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""}
{"level":"info","ts":"2026-01-28T13:07:21.308567Z","caller":"traceutil/trace.go:171","msg":"trace[1956790956] linearizableReadLoop","detail":"{readStateIndex:771; appliedIndex:770; }","duration":"284.934287ms","start":"2026-01-28T13:07:21.023607Z","end":"2026-01-28T13:07:21.308541Z","steps":["trace[1956790956] 'read index received'  (duration: 210.2279ms)","trace[1956790956] 'applied index is now lower than readState.Index'  (duration: 74.705697ms)"],"step_count":2}
{"level":"info","ts":"2026-01-28T13:07:21.308639Z","caller":"traceutil/trace.go:171","msg":"trace[1855694010] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"378.684709ms","start":"2026-01-28T13:07:20.929949Z","end":"2026-01-28T13:07:21.308633Z","steps":["trace[1855694010] 'process raft request'  (duration: 303.952011ms)","trace[1855694010] 'compare'  (duration: 74.085603ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-28T13:07:21.308689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:07:20.929926Z","time spent":"378.725951ms","remote":"127.0.0.1:54720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":817,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/events/kube-system/coredns-5dd5756b68-tpg4v.188ee6f44e91a944\" mod_revision:0 > success:<request_put:<key:\"/registry/events/kube-system/coredns-5dd5756b68-tpg4v.188ee6f44e91a944\" value_size:729 lease:4056225958451180582 >> failure:<>"}
{"level":"warn","ts":"2026-01-28T13:07:21.309285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.673275ms","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-01-28T13:07:21.309316Z","caller":"traceutil/trace.go:171","msg":"trace[804077069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:741; }","duration":"285.725665ms","start":"2026-01-28T13:07:21.023575Z","end":"2026-01-28T13:07:21.309301Z","steps":["trace[804077069] 'agreement among raft nodes before linearized reading'  (duration: 285.193752ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:21.310023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.632634ms","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-01-28T13:07:21.310047Z","caller":"traceutil/trace.go:171","msg":"trace[1892382688] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:741; }","duration":"117.693356ms","start":"2026-01-28T13:07:21.192348Z","end":"2026-01-28T13:07:21.310041Z","steps":["trace[1892382688] 'agreement among raft nodes before linearized reading'  (duration: 117.641905ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:21.732863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.782575ms","expected-duration":"100ms","prefix":"","request":"header:<ID:4056225958451180587 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:734 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:370 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >>","response":"size:16"}
{"level":"info","ts":"2026-01-28T13:07:21.732992Z","caller":"traceutil/trace.go:171","msg":"trace[979295181] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"412.545658ms","start":"2026-01-28T13:07:21.320425Z","end":"2026-01-28T13:07:21.732971Z","steps":["trace[979295181] 'process raft request'  (duration: 302.600879ms)","trace[979295181] 'compare'  (duration: 109.580101ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-28T13:07:21.73309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:07:21.320414Z","time spent":"412.6213ms","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:734 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:370 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"info","ts":"2026-01-28T13:07:23.89795Z","caller":"traceutil/trace.go:171","msg":"trace[31150192] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"160.257393ms","start":"2026-01-28T13:07:23.737676Z","end":"2026-01-28T13:07:23.897934Z","steps":["trace[31150192] 'process raft request'  (duration: 160.149691ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:30.551374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.198991ms","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-01-28T13:07:30.551435Z","caller":"traceutil/trace.go:171","msg":"trace[886762001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:772; }","duration":"144.275562ms","start":"2026-01-28T13:07:30.407143Z","end":"2026-01-28T13:07:30.551418Z","steps":["trace[886762001] 'range keys from in-memory index tree'  (duration: 144.061038ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:30.551461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.52665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"}
{"level":"info","ts":"2026-01-28T13:07:30.551514Z","caller":"traceutil/trace.go:171","msg":"trace[1571282524] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:772; }","duration":"105.613272ms","start":"2026-01-28T13:07:30.445886Z","end":"2026-01-28T13:07:30.551499Z","steps":["trace[1571282524] 'range keys from in-memory index tree'  (duration: 105.378486ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:30.551629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.379578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"info","ts":"2026-01-28T13:07:30.551648Z","caller":"traceutil/trace.go:171","msg":"trace[1005952346] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:772; }","duration":"145.422229ms","start":"2026-01-28T13:07:30.406219Z","end":"2026-01-28T13:07:30.551641Z","steps":["trace[1005952346] 'range keys from in-memory index tree'  (duration: 145.294806ms)"],"step_count":1}
{"level":"info","ts":"2026-01-28T13:07:30.777638Z","caller":"traceutil/trace.go:171","msg":"trace[812108294] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"222.269021ms","start":"2026-01-28T13:07:30.55535Z","end":"2026-01-28T13:07:30.777619Z","steps":["trace[812108294] 'process raft request'  (duration: 168.421236ms)","trace[812108294] 'compare'  (duration: 53.348634ms)"],"step_count":2}
{"level":"info","ts":"2026-01-28T13:07:30.77776Z","caller":"traceutil/trace.go:171","msg":"trace[1623893302] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"220.475279ms","start":"2026-01-28T13:07:30.557278Z","end":"2026-01-28T13:07:30.777753Z","steps":["trace[1623893302] 'process raft request'  (duration: 219.944257ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:32.590052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.136306ms","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-01-28T13:07:32.590098Z","caller":"traceutil/trace.go:171","msg":"trace[1446008813] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:776; }","duration":"409.218538ms","start":"2026-01-28T13:07:32.180868Z","end":"2026-01-28T13:07:32.590086Z","steps":["trace[1446008813] 'range keys from in-memory index tree'  (duration: 409.004923ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:32.590116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:07:32.18085Z","time spent":"409.261589ms","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"info","ts":"2026-01-28T13:07:32.738332Z","caller":"traceutil/trace.go:171","msg":"trace[2018976929] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"236.35433ms","start":"2026-01-28T13:07:32.501964Z","end":"2026-01-28T13:07:32.738319Z","steps":["trace[2018976929] 'process raft request'  (duration: 236.272098ms)"],"step_count":1}
{"level":"info","ts":"2026-01-28T13:07:32.738584Z","caller":"traceutil/trace.go:171","msg":"trace[630418478] linearizableReadLoop","detail":"{readStateIndex:809; appliedIndex:809; }","duration":"171.751434ms","start":"2026-01-28T13:07:32.566827Z","end":"2026-01-28T13:07:32.738579Z","steps":["trace[630418478] 'read index received'  (duration: 171.749164ms)","trace[630418478] 'applied index is now lower than readState.Index'  (duration: 1.95µs)"],"step_count":2}
{"level":"warn","ts":"2026-01-28T13:07:32.738669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.831346ms","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-01-28T13:07:32.738688Z","caller":"traceutil/trace.go:171","msg":"trace[689661844] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:777; }","duration":"171.883506ms","start":"2026-01-28T13:07:32.5668Z","end":"2026-01-28T13:07:32.738683Z","steps":["trace[689661844] 'agreement among raft nodes before linearized reading'  (duration: 171.817015ms)"],"step_count":1}
{"level":"info","ts":"2026-01-28T13:07:32.741903Z","caller":"traceutil/trace.go:171","msg":"trace[119795084] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"148.929841ms","start":"2026-01-28T13:07:32.592967Z","end":"2026-01-28T13:07:32.741897Z","steps":["trace[119795084] 'process raft request'  (duration: 148.88837ms)"],"step_count":1}
{"level":"info","ts":"2026-01-28T13:07:34.089403Z","caller":"traceutil/trace.go:171","msg":"trace[202310295] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"104.994587ms","start":"2026-01-28T13:07:33.984394Z","end":"2026-01-28T13:07:34.089389Z","steps":["trace[202310295] 'process raft request'  (duration: 104.913916ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:46.834962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.872307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-28T13:07:46.835011Z","caller":"traceutil/trace.go:171","msg":"trace[1475125711] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:866; }","duration":"235.97617ms","start":"2026-01-28T13:07:46.599024Z","end":"2026-01-28T13:07:46.835Z","steps":["trace[1475125711] 'count revisions from in-memory index tree'  (duration: 235.754554ms)"],"step_count":1}
{"level":"info","ts":"2026-01-28T13:07:47.140693Z","caller":"traceutil/trace.go:171","msg":"trace[555265512] linearizableReadLoop","detail":"{readStateIndex:903; appliedIndex:902; }","duration":"177.17191ms","start":"2026-01-28T13:07:46.963505Z","end":"2026-01-28T13:07:47.140677Z","steps":["trace[555265512] 'read index received'  (duration: 176.979755ms)","trace[555265512] 'applied index is now lower than readState.Index'  (duration: 191.215µs)"],"step_count":2}
{"level":"info","ts":"2026-01-28T13:07:47.140955Z","caller":"traceutil/trace.go:171","msg":"trace[743061776] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"180.871016ms","start":"2026-01-28T13:07:46.960077Z","end":"2026-01-28T13:07:47.140948Z","steps":["trace[743061776] 'process raft request'  (duration: 180.455546ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:47.141144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.62527ms","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-01-28T13:07:47.141163Z","caller":"traceutil/trace.go:171","msg":"trace[890909329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:867; }","duration":"177.679022ms","start":"2026-01-28T13:07:46.963477Z","end":"2026-01-28T13:07:47.141156Z","steps":["trace[890909329] 'agreement among raft nodes before linearized reading'  (duration: 177.568129ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:47.141247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.682712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"info","ts":"2026-01-28T13:07:47.141258Z","caller":"traceutil/trace.go:171","msg":"trace[1196348905] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:867; }","duration":"177.701792ms","start":"2026-01-28T13:07:46.963553Z","end":"2026-01-28T13:07:47.141254Z","steps":["trace[1196348905] 'agreement among raft nodes before linearized reading'  (duration: 177.667331ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:47.141391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.385788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"}
{"level":"info","ts":"2026-01-28T13:07:47.141413Z","caller":"traceutil/trace.go:171","msg":"trace[1118634468] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:867; }","duration":"142.42932ms","start":"2026-01-28T13:07:46.998978Z","end":"2026-01-28T13:07:47.141407Z","steps":["trace[1118634468] 'agreement among raft nodes before linearized reading'  (duration: 142.383309ms)"],"step_count":1}
{"level":"info","ts":"2026-01-28T13:07:47.890305Z","caller":"traceutil/trace.go:171","msg":"trace[180023065] linearizableReadLoop","detail":"{readStateIndex:906; appliedIndex:904; }","duration":"164.058474ms","start":"2026-01-28T13:07:47.72623Z","end":"2026-01-28T13:07:47.890289Z","steps":["trace[180023065] 'read index received'  (duration: 163.87342ms)","trace[180023065] 'applied index is now lower than readState.Index'  (duration: 184.394µs)"],"step_count":2}
{"level":"info","ts":"2026-01-28T13:07:47.89049Z","caller":"traceutil/trace.go:171","msg":"trace[1290945190] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"737.4847ms","start":"2026-01-28T13:07:47.152997Z","end":"2026-01-28T13:07:47.890482Z","steps":["trace[1290945190] 'process raft request'  (duration: 737.187574ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:47.890559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:07:47.152963Z","time spent":"737.546962ms","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:864 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"info","ts":"2026-01-28T13:07:47.890675Z","caller":"traceutil/trace.go:171","msg":"trace[2092556154] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"735.009443ms","start":"2026-01-28T13:07:47.15566Z","end":"2026-01-28T13:07:47.890669Z","steps":["trace[2092556154] 'process raft request'  (duration: 734.603803ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:47.890709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:07:47.15565Z","time spent":"735.040133ms","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:865 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:411 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"warn","ts":"2026-01-28T13:07:47.890822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.589666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-28T13:07:47.890845Z","caller":"traceutil/trace.go:171","msg":"trace[1315187381] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:870; }","duration":"164.631498ms","start":"2026-01-28T13:07:47.726205Z","end":"2026-01-28T13:07:47.890836Z","steps":["trace[1315187381] 'agreement among raft nodes before linearized reading'  (duration: 164.597847ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:47.891056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.527069ms","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-01-28T13:07:47.891076Z","caller":"traceutil/trace.go:171","msg":"trace[1106676626] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:870; }","duration":"141.57703ms","start":"2026-01-28T13:07:47.749493Z","end":"2026-01-28T13:07:47.891071Z","steps":["trace[1106676626] 'agreement among raft nodes before linearized reading'  (duration: 141.481588ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:48.757712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.225931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-01-28T13:07:48.757761Z","caller":"traceutil/trace.go:171","msg":"trace[986118377] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:871; }","duration":"168.283623ms","start":"2026-01-28T13:07:48.589465Z","end":"2026-01-28T13:07:48.757749Z","steps":["trace[986118377] 'count revisions from in-memory index tree'  (duration: 168.165939ms)"],"step_count":1}
{"level":"info","ts":"2026-01-28T13:07:50.844049Z","caller":"traceutil/trace.go:171","msg":"trace[606037102] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"134.27162ms","start":"2026-01-28T13:07:50.709762Z","end":"2026-01-28T13:07:50.844034Z","steps":["trace[606037102] 'process raft request'  (duration: 127.203195ms)"],"step_count":1}
{"level":"info","ts":"2026-01-28T13:07:50.84393Z","caller":"traceutil/trace.go:171","msg":"trace[214165266] linearizableReadLoop","detail":"{readStateIndex:915; appliedIndex:914; }","duration":"119.630268ms","start":"2026-01-28T13:07:50.724283Z","end":"2026-01-28T13:07:50.843914Z","steps":["trace[214165266] 'read index received'  (duration: 112.675566ms)","trace[214165266] 'applied index is now lower than readState.Index'  (duration: 6.954062ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-28T13:07:50.845848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.987777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-28T13:07:50.845881Z","caller":"traceutil/trace.go:171","msg":"trace[1557136897] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:878; }","duration":"121.610925ms","start":"2026-01-28T13:07:50.724262Z","end":"2026-01-28T13:07:50.845873Z","steps":["trace[1557136897] 'agreement among raft nodes before linearized reading'  (duration: 119.992737ms)"],"step_count":1}
{"level":"info","ts":"2026-01-28T13:07:52.103367Z","caller":"traceutil/trace.go:171","msg":"trace[637778637] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"188.35476ms","start":"2026-01-28T13:07:51.914995Z","end":"2026-01-28T13:07:52.10335Z","steps":["trace[637778637] 'process raft request'  (duration: 188.240067ms)"],"step_count":1}
{"level":"info","ts":"2026-01-28T13:07:52.104946Z","caller":"traceutil/trace.go:171","msg":"trace[620338500] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"181.193233ms","start":"2026-01-28T13:07:51.923591Z","end":"2026-01-28T13:07:52.104785Z","steps":["trace[620338500] 'process raft request'  (duration: 181.089381ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:53.593503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.025551ms","expected-duration":"100ms","prefix":"","request":"header:<ID:4056225958451180927 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:879 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:430 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >>","response":"size:16"}
{"level":"info","ts":"2026-01-28T13:07:53.593561Z","caller":"traceutil/trace.go:171","msg":"trace[632907060] linearizableReadLoop","detail":"{readStateIndex:922; appliedIndex:921; }","duration":"400.233719ms","start":"2026-01-28T13:07:53.193318Z","end":"2026-01-28T13:07:53.593552Z","steps":["trace[632907060] 'read index received'  (duration: 276.058365ms)","trace[632907060] 'applied index is now lower than readState.Index'  (duration: 124.174744ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-28T13:07:53.593651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.331741ms","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-01-28T13:07:53.593664Z","caller":"traceutil/trace.go:171","msg":"trace[186198520] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:885; }","duration":"400.343701ms","start":"2026-01-28T13:07:53.193316Z","end":"2026-01-28T13:07:53.593659Z","steps":["trace[186198520] 'agreement among raft nodes before linearized reading'  (duration: 400.259889ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:53.593679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:07:53.193292Z","time spent":"400.383783ms","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"info","ts":"2026-01-28T13:07:53.593806Z","caller":"traceutil/trace.go:171","msg":"trace[1579357176] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"404.856707ms","start":"2026-01-28T13:07:53.188944Z","end":"2026-01-28T13:07:53.5938Z","steps":["trace[1579357176] 'process raft request'  (duration: 280.472328ms)","trace[1579357176] 'compare'  (duration: 123.692613ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-28T13:07:53.593834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:07:53.18893Z","time spent":"404.888027ms","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:879 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:430 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"info","ts":"2026-01-28T13:07:54.378545Z","caller":"traceutil/trace.go:171","msg":"trace[323146320] linearizableReadLoop","detail":"{readStateIndex:924; appliedIndex:923; }","duration":"262.522709ms","start":"2026-01-28T13:07:54.116009Z","end":"2026-01-28T13:07:54.378532Z","steps":["trace[323146320] 'read index received'  (duration: 262.419457ms)","trace[323146320] 'applied index is now lower than readState.Index'  (duration: 102.662µs)"],"step_count":2}
{"level":"info","ts":"2026-01-28T13:07:54.378677Z","caller":"traceutil/trace.go:171","msg":"trace[1460594882] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"269.284456ms","start":"2026-01-28T13:07:54.109387Z","end":"2026-01-28T13:07:54.378671Z","steps":["trace[1460594882] 'process raft request'  (duration: 269.076731ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:54.378824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.801525ms","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-01-28T13:07:54.378844Z","caller":"traceutil/trace.go:171","msg":"trace[49887388] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:887; }","duration":"262.845667ms","start":"2026-01-28T13:07:54.115993Z","end":"2026-01-28T13:07:54.378839Z","steps":["trace[49887388] 'agreement among raft nodes before linearized reading'  (duration: 262.770215ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:58.915483Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4056225958451180961,"retry-timeout":"500ms"}
{"level":"info","ts":"2026-01-28T13:07:59.018822Z","caller":"traceutil/trace.go:171","msg":"trace[548446590] linearizableReadLoop","detail":"{readStateIndex:935; appliedIndex:934; }","duration":"603.855956ms","start":"2026-01-28T13:07:58.414948Z","end":"2026-01-28T13:07:59.018804Z","steps":["trace[548446590] 'read index received'  (duration: 603.712472ms)","trace[548446590] 'applied index is now lower than readState.Index'  (duration: 142.583µs)"],"step_count":2}
{"level":"info","ts":"2026-01-28T13:07:59.018922Z","caller":"traceutil/trace.go:171","msg":"trace[1134772550] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"607.978651ms","start":"2026-01-28T13:07:58.410934Z","end":"2026-01-28T13:07:59.018913Z","steps":["trace[1134772550] 'process raft request'  (duration: 607.762406ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:59.019006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:07:58.410926Z","time spent":"608.018982ms","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:892 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:411 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"warn","ts":"2026-01-28T13:07:59.019204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.401634ms","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-01-28T13:07:59.019267Z","caller":"traceutil/trace.go:171","msg":"trace[225644218] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:897; }","duration":"603.483387ms","start":"2026-01-28T13:07:58.415766Z","end":"2026-01-28T13:07:59.019249Z","steps":["trace[225644218] 'agreement among raft nodes before linearized reading'  (duration: 603.314343ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:59.019305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:07:58.415761Z","time spent":"603.536088ms","remote":"127.0.0.1:54894","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-01-28T13:07:59.019458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.508711ms","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-01-28T13:07:59.019468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.325127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-28T13:07:59.019497Z","caller":"traceutil/trace.go:171","msg":"trace[332851985] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:897; }","duration":"293.375008ms","start":"2026-01-28T13:07:58.726113Z","end":"2026-01-28T13:07:59.019488Z","steps":["trace[332851985] 'agreement among raft nodes before linearized reading'  (duration: 293.332607ms)"],"step_count":1}
{"level":"info","ts":"2026-01-28T13:07:59.019494Z","caller":"traceutil/trace.go:171","msg":"trace[1380651084] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:897; }","duration":"604.566661ms","start":"2026-01-28T13:07:58.414917Z","end":"2026-01-28T13:07:59.019483Z","steps":["trace[1380651084] 'agreement among raft nodes before linearized reading'  (duration: 604.470329ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:59.019554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:07:58.414903Z","time spent":"604.642064ms","remote":"127.0.0.1:54894","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-01-28T13:07:59.753395Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4056225958451180965,"retry-timeout":"500ms"}
{"level":"info","ts":"2026-01-28T13:07:59.822457Z","caller":"traceutil/trace.go:171","msg":"trace[748989456] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"797.416797ms","start":"2026-01-28T13:07:59.025026Z","end":"2026-01-28T13:07:59.822443Z","steps":["trace[748989456] 'process raft request'  (duration: 797.340815ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:59.822553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:07:59.025015Z","time spent":"797.483568ms","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:893 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:371 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"info","ts":"2026-01-28T13:07:59.856949Z","caller":"traceutil/trace.go:171","msg":"trace[1523776356] linearizableReadLoop","detail":"{readStateIndex:937; appliedIndex:936; }","duration":"604.139342ms","start":"2026-01-28T13:07:59.252784Z","end":"2026-01-28T13:07:59.856923Z","steps":["trace[1523776356] 'read index received'  (duration: 569.977346ms)","trace[1523776356] 'applied index is now lower than readState.Index'  (duration: 34.157686ms)"],"step_count":2}
{"level":"info","ts":"2026-01-28T13:07:59.857115Z","caller":"traceutil/trace.go:171","msg":"trace[567053777] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"830.928428ms","start":"2026-01-28T13:07:59.026174Z","end":"2026-01-28T13:07:59.857102Z","steps":["trace[567053777] 'process raft request'  (duration: 830.604371ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:59.857192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.117301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"warn","ts":"2026-01-28T13:07:59.857218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:07:59.026165Z","time spent":"830.981399ms","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:894 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"warn","ts":"2026-01-28T13:07:59.857273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.49158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.206\" ","response":"range_response_count:1 size:135"}
{"level":"info","ts":"2026-01-28T13:07:59.857288Z","caller":"traceutil/trace.go:171","msg":"trace[718863818] range","detail":"{range_begin:/registry/masterleases/162.253.55.206; range_end:; response_count:1; response_revision:899; }","duration":"604.543122ms","start":"2026-01-28T13:07:59.25274Z","end":"2026-01-28T13:07:59.857283Z","steps":["trace[718863818] 'agreement among raft nodes before linearized reading'  (duration: 604.502741ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:59.857309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:07:59.252719Z","time spent":"604.584542ms","remote":"127.0.0.1:54682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.206\" "}
{"level":"info","ts":"2026-01-28T13:07:59.85723Z","caller":"traceutil/trace.go:171","msg":"trace[493128325] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:899; }","duration":"202.183183ms","start":"2026-01-28T13:07:59.655036Z","end":"2026-01-28T13:07:59.857219Z","steps":["trace[493128325] 'agreement among raft nodes before linearized reading'  (duration: 202.07406ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:07:59.85744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.058987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"info","ts":"2026-01-28T13:07:59.857455Z","caller":"traceutil/trace.go:171","msg":"trace[98352549] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:899; }","duration":"177.090957ms","start":"2026-01-28T13:07:59.680359Z","end":"2026-01-28T13:07:59.85745Z","steps":["trace[98352549] 'agreement among raft nodes before linearized reading'  (duration: 177.050266ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:08:00.065687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.873315ms","expected-duration":"100ms","prefix":"","request":"header:<ID:4056225958451180971 username:\"kube-apiserver-etcd-client\" auth_revision:1 > lease_grant:<ttl:15-second id:384a9c04b63375aa>","response":"size:41"}
{"level":"info","ts":"2026-01-28T13:08:00.065918Z","caller":"traceutil/trace.go:171","msg":"trace[366179927] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"203.38186ms","start":"2026-01-28T13:07:59.862519Z","end":"2026-01-28T13:08:00.065901Z","steps":["trace[366179927] 'process raft request'  (duration: 203.345619ms)"],"step_count":1}
{"level":"info","ts":"2026-01-28T13:08:00.066309Z","caller":"traceutil/trace.go:171","msg":"trace[641642201] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"204.340753ms","start":"2026-01-28T13:07:59.861958Z","end":"2026-01-28T13:08:00.066298Z","steps":["trace[641642201] 'process raft request'  (duration: 203.81325ms)"],"step_count":1}
{"level":"info","ts":"2026-01-28T13:08:01.158136Z","caller":"traceutil/trace.go:171","msg":"trace[1358503494] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"131.868663ms","start":"2026-01-28T13:08:01.026253Z","end":"2026-01-28T13:08:01.158122Z","steps":["trace[1358503494] 'process raft request'  (duration: 131.782511ms)"],"step_count":1}
{"level":"info","ts":"2026-01-28T13:08:01.269887Z","caller":"traceutil/trace.go:171","msg":"trace[2126724148] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"186.339983ms","start":"2026-01-28T13:08:01.083532Z","end":"2026-01-28T13:08:01.269872Z","steps":["trace[2126724148] 'process raft request'  (duration: 186.260651ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:08:04.121811Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4056225958451180994,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-01-28T13:08:04.236987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.403603ms","expected-duration":"100ms","prefix":"","request":"header:<ID:4056225958451180996 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-node-lease/instance\" mod_revision:884 > success:<request_put:<key:\"/registry/leases/kube-node-lease/instance\" value_size:471 >> failure:<request_range:<key:\"/registry/leases/kube-node-lease/instance\" > >>","response":"size:16"}
{"level":"info","ts":"2026-01-28T13:08:04.237069Z","caller":"traceutil/trace.go:171","msg":"trace[1711029552] linearizableReadLoop","detail":"{readStateIndex:949; appliedIndex:948; }","duration":"615.516187ms","start":"2026-01-28T13:08:03.621541Z","end":"2026-01-28T13:08:04.237057Z","steps":["trace[1711029552] 'read index received'  (duration: 507.975801ms)","trace[1711029552] 'applied index is now lower than readState.Index'  (duration: 107.539616ms)"],"step_count":2}
{"level":"info","ts":"2026-01-28T13:08:04.237186Z","caller":"traceutil/trace.go:171","msg":"trace[680235306] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"1.026448926s","start":"2026-01-28T13:08:03.210729Z","end":"2026-01-28T13:08:04.237178Z","steps":["trace[680235306] 'process raft request'  (duration: 918.795117ms)","trace[680235306] 'compare'  (duration: 107.27495ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-28T13:08:04.237225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:08:03.210713Z","time spent":"1.026491517s","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-node-lease/instance\" mod_revision:884 > success:<request_put:<key:\"/registry/leases/kube-node-lease/instance\" value_size:471 >> failure:<request_range:<key:\"/registry/leases/kube-node-lease/instance\" > >"}
{"level":"warn","ts":"2026-01-28T13:08:04.237371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.831134ms","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-01-28T13:08:04.237398Z","caller":"traceutil/trace.go:171","msg":"trace[1343759043] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:910; }","duration":"615.892586ms","start":"2026-01-28T13:08:03.621497Z","end":"2026-01-28T13:08:04.237389Z","steps":["trace[1343759043] 'agreement among raft nodes before linearized reading'  (duration: 615.834665ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:08:04.237421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:08:03.62148Z","time spent":"615.936938ms","remote":"127.0.0.1:54942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":53,"response size":31,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "}
{"level":"warn","ts":"2026-01-28T13:08:04.237503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.149519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-28T13:08:04.237525Z","caller":"traceutil/trace.go:171","msg":"trace[28466188] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:910; }","duration":"476.19482ms","start":"2026-01-28T13:08:03.761325Z","end":"2026-01-28T13:08:04.23752Z","steps":["trace[28466188] 'agreement among raft nodes before linearized reading'  (duration: 476.163389ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:08:04.237544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:08:03.761312Z","time spent":"476.228681ms","remote":"127.0.0.1:54652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-01-28T13:08:04.237693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.170047ms","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-01-28T13:08:04.237715Z","caller":"traceutil/trace.go:171","msg":"trace[500959883] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:910; }","duration":"161.201108ms","start":"2026-01-28T13:08:04.076509Z","end":"2026-01-28T13:08:04.23771Z","steps":["trace[500959883] 'agreement among raft nodes before linearized reading'  (duration: 161.127676ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:08:04.23781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.506501ms","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-01-28T13:08:04.237829Z","caller":"traceutil/trace.go:171","msg":"trace[1573195223] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:910; }","duration":"163.561542ms","start":"2026-01-28T13:08:04.074263Z","end":"2026-01-28T13:08:04.237825Z","steps":["trace[1573195223] 'agreement among raft nodes before linearized reading'  (duration: 163.513171ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:08:04.237896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.553399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-01-28T13:08:04.237917Z","caller":"traceutil/trace.go:171","msg":"trace[632546883] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:910; }","duration":"292.59372ms","start":"2026-01-28T13:08:03.945319Z","end":"2026-01-28T13:08:04.237913Z","steps":["trace[632546883] 'agreement among raft nodes before linearized reading'  (duration: 292.5623ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:08:04.238003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.180045ms","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-01-28T13:08:04.238023Z","caller":"traceutil/trace.go:171","msg":"trace[1683345492] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:910; }","duration":"372.215636ms","start":"2026-01-28T13:08:03.865803Z","end":"2026-01-28T13:08:04.238019Z","steps":["trace[1683345492] 'agreement among raft nodes before linearized reading'  (duration: 372.166995ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:08:04.238042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:08:03.865794Z","time spent":"372.244637ms","remote":"127.0.0.1:54894","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-01-28T13:08:04.238134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.197365ms","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-01-28T13:08:04.238154Z","caller":"traceutil/trace.go:171","msg":"trace[1475592180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:910; }","duration":"402.235276ms","start":"2026-01-28T13:08:03.835914Z","end":"2026-01-28T13:08:04.238149Z","steps":["trace[1475592180] 'agreement among raft nodes before linearized reading'  (duration: 402.184675ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:08:04.238169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:08:03.835897Z","time spent":"402.268877ms","remote":"127.0.0.1:54894","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-01-28T13:08:04.23824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.712303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-01-28T13:08:04.23826Z","caller":"traceutil/trace.go:171","msg":"trace[542954607] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:910; }","duration":"428.743263ms","start":"2026-01-28T13:08:03.809512Z","end":"2026-01-28T13:08:04.238255Z","steps":["trace[542954607] 'agreement among raft nodes before linearized reading'  (duration: 428.711953ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:08:04.238279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:08:03.809505Z","time spent":"428.770155ms","remote":"127.0.0.1:55068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":31,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "}
{"level":"warn","ts":"2026-01-28T13:08:05.240497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.254409ms","expected-duration":"100ms","prefix":"","request":"header:<ID:4056225958451180999 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:907 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:412 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >>","response":"size:16"}
{"level":"info","ts":"2026-01-28T13:08:05.240558Z","caller":"traceutil/trace.go:171","msg":"trace[288194374] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"996.608959ms","start":"2026-01-28T13:08:04.243935Z","end":"2026-01-28T13:08:05.240544Z","steps":["trace[288194374] 'process raft request'  (duration: 397.263698ms)","trace[288194374] 'compare'  (duration: 598.935962ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-28T13:08:05.240615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:08:04.243921Z","time spent":"996.680411ms","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:907 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:412 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"info","ts":"2026-01-28T13:08:05.310143Z","caller":"traceutil/trace.go:171","msg":"trace[486917734] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"1.065911765s","start":"2026-01-28T13:08:04.24421Z","end":"2026-01-28T13:08:05.310122Z","steps":["trace[486917734] 'process raft request'  (duration: 1.065750931s)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:08:05.310212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:08:04.244202Z","time spent":"1.065976746s","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:908 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:430 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"info","ts":"2026-01-28T13:08:05.310378Z","caller":"traceutil/trace.go:171","msg":"trace[379133986] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"1.063898448s","start":"2026-01-28T13:08:04.246475Z","end":"2026-01-28T13:08:05.310374Z","steps":["trace[379133986] 'process raft request'  (duration: 1.063536659s)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:08:05.310413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:08:04.246469Z","time spent":"1.063930328s","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:905 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:371 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"info","ts":"2026-01-28T13:08:05.310503Z","caller":"traceutil/trace.go:171","msg":"trace[1228476835] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"1.049076173s","start":"2026-01-28T13:08:04.261423Z","end":"2026-01-28T13:08:05.310499Z","steps":["trace[1228476835] 'process raft request'  (duration: 1.048606872s)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:08:05.310526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:08:04.261401Z","time spent":"1.049112844s","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:906 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"warn","ts":"2026-01-28T13:08:05.830345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.287397ms","expected-duration":"100ms","prefix":"","request":"header:<ID:4056225958451181003 > lease_revoke:<id:384a9c04b633755f>","response":"size:29"}
{"level":"info","ts":"2026-01-28T13:08:05.830408Z","caller":"traceutil/trace.go:171","msg":"trace[725579102] linearizableReadLoop","detail":"{readStateIndex:951; appliedIndex:949; }","duration":"1.586144021s","start":"2026-01-28T13:08:04.244254Z","end":"2026-01-28T13:08:05.830398Z","steps":["trace[725579102] 'read index received'  (duration: 396.952362ms)","trace[725579102] 'applied index is now lower than readState.Index'  (duration: 1.189190769s)"],"step_count":2}
{"level":"warn","ts":"2026-01-28T13:08:05.830797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.58653359s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-28T13:08:05.830814Z","caller":"traceutil/trace.go:171","msg":"trace[161696373] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:914; }","duration":"1.586556611s","start":"2026-01-28T13:08:04.244252Z","end":"2026-01-28T13:08:05.830809Z","steps":["trace[161696373] 'agreement among raft nodes before linearized reading'  (duration: 1.58651916s)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:08:05.830829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:08:04.244234Z","time spent":"1.586591621s","remote":"127.0.0.1:54660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-01-28T13:08:05.830972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.945528ms","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-01-28T13:08:05.830985Z","caller":"traceutil/trace.go:171","msg":"trace[875312470] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:914; }","duration":"573.974579ms","start":"2026-01-28T13:08:05.257006Z","end":"2026-01-28T13:08:05.830981Z","steps":["trace[875312470] 'agreement among raft nodes before linearized reading'  (duration: 573.911917ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:08:05.830998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:08:05.256997Z","time spent":"573.99613ms","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"warn","ts":"2026-01-28T13:08:05.831098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.514011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-01-28T13:08:05.831111Z","caller":"traceutil/trace.go:171","msg":"trace[1356206278] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:914; }","duration":"293.550673ms","start":"2026-01-28T13:08:05.537557Z","end":"2026-01-28T13:08:05.831107Z","steps":["trace[1356206278] 'agreement among raft nodes before linearized reading'  (duration: 293.528902ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:08:05.831211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.639328ms","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-01-28T13:08:05.831226Z","caller":"traceutil/trace.go:171","msg":"trace[1597138660] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:914; }","duration":"563.662928ms","start":"2026-01-28T13:08:05.267559Z","end":"2026-01-28T13:08:05.831222Z","steps":["trace[1597138660] 'agreement among raft nodes before linearized reading'  (duration: 563.607667ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:08:05.831237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:08:05.267554Z","time spent":"563.679659ms","remote":"127.0.0.1:54720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":107,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "}
{"level":"warn","ts":"2026-01-28T13:08:12.151679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.26066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-01-28T13:08:12.151739Z","caller":"traceutil/trace.go:171","msg":"trace[877931654] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:952; }","duration":"125.378683ms","start":"2026-01-28T13:08:12.026346Z","end":"2026-01-28T13:08:12.151724Z","steps":["trace[877931654] 'count revisions from in-memory index tree'  (duration: 125.194328ms)"],"step_count":1}
{"level":"info","ts":"2026-01-28T13:08:15.490612Z","caller":"traceutil/trace.go:171","msg":"trace[835938842] linearizableReadLoop","detail":"{readStateIndex:1008; appliedIndex:1007; }","duration":"145.49205ms","start":"2026-01-28T13:08:15.345102Z","end":"2026-01-28T13:08:15.490594Z","steps":["trace[835938842] 'read index received'  (duration: 108.359615ms)","trace[835938842] 'applied index is now lower than readState.Index'  (duration: 37.131625ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-28T13:08:15.490775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.655654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"info","ts":"2026-01-28T13:08:15.490794Z","caller":"traceutil/trace.go:171","msg":"trace[1913399991] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:966; }","duration":"145.706206ms","start":"2026-01-28T13:08:15.345081Z","end":"2026-01-28T13:08:15.490788Z","steps":["trace[1913399991] 'agreement among raft nodes before linearized reading'  (duration: 145.606063ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:08:15.490842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.989426ms","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-01-28T13:08:15.490888Z","caller":"traceutil/trace.go:171","msg":"trace[451403763] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:966; }","duration":"132.069209ms","start":"2026-01-28T13:08:15.358808Z","end":"2026-01-28T13:08:15.490878Z","steps":["trace[451403763] 'agreement among raft nodes before linearized reading'  (duration: 131.899945ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:08:15.491023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.833663ms","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-01-28T13:08:15.49104Z","caller":"traceutil/trace.go:171","msg":"trace[696341977] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:966; }","duration":"131.861474ms","start":"2026-01-28T13:08:15.359174Z","end":"2026-01-28T13:08:15.491036Z","steps":["trace[696341977] 'agreement among raft nodes before linearized reading'  (duration: 131.817312ms)"],"step_count":1}
{"level":"info","ts":"2026-01-28T13:08:16.495892Z","caller":"traceutil/trace.go:171","msg":"trace[1657788087] linearizableReadLoop","detail":"{readStateIndex:1019; appliedIndex:1018; }","duration":"258.005205ms","start":"2026-01-28T13:08:16.237868Z","end":"2026-01-28T13:08:16.495874Z","steps":["trace[1657788087] 'read index received'  (duration: 257.897952ms)","trace[1657788087] 'applied index is now lower than readState.Index'  (duration: 104.243µs)"],"step_count":2}
{"level":"warn","ts":"2026-01-28T13:08:16.49598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.105907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-28T13:08:16.496001Z","caller":"traceutil/trace.go:171","msg":"trace[403575094] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:977; }","duration":"343.016346ms","start":"2026-01-28T13:08:16.152979Z","end":"2026-01-28T13:08:16.495995Z","steps":["trace[403575094] 'agreement among raft nodes before linearized reading'  (duration: 342.962074ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:08:16.496023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:08:16.152939Z","time spent":"343.078197ms","remote":"127.0.0.1:54776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "}
{"level":"info","ts":"2026-01-28T13:08:16.496067Z","caller":"traceutil/trace.go:171","msg":"trace[1238470844] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"491.058677ms","start":"2026-01-28T13:08:16.004992Z","end":"2026-01-28T13:08:16.49605Z","steps":["trace[1238470844] 'process raft request'  (duration: 490.78669ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:08:16.496168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:08:16.004976Z","time spent":"491.131378ms","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:964 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:409 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"info","ts":"2026-01-28T13:08:19.675142Z","caller":"traceutil/trace.go:171","msg":"trace[1144931062] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"138.392515ms","start":"2026-01-28T13:08:19.536727Z","end":"2026-01-28T13:08:19.67512Z","steps":["trace[1144931062] 'process raft request'  (duration: 138.068558ms)"],"step_count":1}
{"level":"info","ts":"2026-01-28T13:08:19.675354Z","caller":"traceutil/trace.go:171","msg":"trace[631049501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:986; }","duration":"100.006101ms","start":"2026-01-28T13:08:19.575327Z","end":"2026-01-28T13:08:19.675334Z","steps":["trace[631049501] 'agreement among raft nodes before linearized reading'  (duration: 99.863048ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:08:19.675518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.134794ms","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-01-28T13:08:19.675558Z","caller":"traceutil/trace.go:171","msg":"trace[1256839800] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:986; }","duration":"100.202685ms","start":"2026-01-28T13:08:19.575342Z","end":"2026-01-28T13:08:19.675544Z","steps":["trace[1256839800] 'agreement among raft nodes before linearized reading'  (duration: 100.084952ms)"],"step_count":1}
{"level":"info","ts":"2026-01-28T13:08:19.823497Z","caller":"traceutil/trace.go:171","msg":"trace[546890845] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"141.992279ms","start":"2026-01-28T13:08:19.681481Z","end":"2026-01-28T13:08:19.823473Z","steps":["trace[546890845] 'process raft request'  (duration: 139.619224ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:08:20.373041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.914455ms","expected-duration":"100ms","prefix":"","request":"header:<ID:4056225958451181164 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:981 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >>","response":"size:16"}
{"level":"info","ts":"2026-01-28T13:08:20.373098Z","caller":"traceutil/trace.go:171","msg":"trace[835070242] linearizableReadLoop","detail":"{readStateIndex:1031; appliedIndex:1029; }","duration":"643.852727ms","start":"2026-01-28T13:08:19.729236Z","end":"2026-01-28T13:08:20.373089Z","steps":["trace[835070242] 'read index received'  (duration: 91.869931ms)","trace[835070242] 'applied index is now lower than readState.Index'  (duration: 551.982126ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-28T13:08:20.37315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.913929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-28T13:08:20.373163Z","caller":"traceutil/trace.go:171","msg":"trace[1716921647] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:988; }","duration":"643.94719ms","start":"2026-01-28T13:08:19.72921Z","end":"2026-01-28T13:08:20.373157Z","steps":["trace[1716921647] 'agreement among raft nodes before linearized reading'  (duration: 643.903279ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:08:20.373178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:08:19.729194Z","time spent":"643.98147ms","remote":"127.0.0.1:54652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"info","ts":"2026-01-28T13:08:20.373321Z","caller":"traceutil/trace.go:171","msg":"trace[601806761] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"689.912521ms","start":"2026-01-28T13:08:19.683403Z","end":"2026-01-28T13:08:20.373316Z","steps":["trace[601806761] 'process raft request'  (duration: 409.656158ms)","trace[601806761] 'compare'  (duration: 279.663879ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-28T13:08:20.373347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:08:19.68339Z","time spent":"689.944282ms","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:981 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"warn","ts":"2026-01-28T13:08:21.416545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.312392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"}
{"level":"info","ts":"2026-01-28T13:08:21.41661Z","caller":"traceutil/trace.go:171","msg":"trace[2128960617] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:988; }","duration":"905.413865ms","start":"2026-01-28T13:08:20.511185Z","end":"2026-01-28T13:08:21.416599Z","steps":["trace[2128960617] 'range keys from in-memory index tree'  (duration: 905.21304ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:08:21.416629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:08:20.51117Z","time spent":"905.454846ms","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"warn","ts":"2026-01-28T13:08:21.416693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.164958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"}
{"level":"info","ts":"2026-01-28T13:08:21.416736Z","caller":"traceutil/trace.go:171","msg":"trace[1271054558] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:988; }","duration":"681.218419ms","start":"2026-01-28T13:08:20.735506Z","end":"2026-01-28T13:08:21.416725Z","steps":["trace[1271054558] 'range keys from in-memory index tree'  (duration: 681.042944ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:08:21.416757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:08:20.735453Z","time spent":"681.299681ms","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":534,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "}
{"level":"warn","ts":"2026-01-28T13:08:21.45904Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4056225958451181168,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-01-28T13:08:21.485365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:08:20.824948Z","time spent":"660.411444ms","remote":"127.0.0.1:54720","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""}
{"level":"warn","ts":"2026-01-28T13:08:22.035532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.804077ms","expected-duration":"100ms","prefix":"","request":"header:<ID:4056225958451181171 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" mod_revision:976 > success:<request_put:<key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" value_size:425 >> failure:<request_range:<key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" > >>","response":"size:16"}
{"level":"info","ts":"2026-01-28T13:08:22.03569Z","caller":"traceutil/trace.go:171","msg":"trace[1599827325] linearizableReadLoop","detail":"{readStateIndex:1034; appliedIndex:1032; }","duration":"1.077641749s","start":"2026-01-28T13:08:20.958034Z","end":"2026-01-28T13:08:22.035675Z","steps":["trace[1599827325] 'read index received'  (duration: 527.446555ms)","trace[1599827325] 'applied index is now lower than readState.Index'  (duration: 550.194254ms)"],"step_count":2}
{"level":"info","ts":"2026-01-28T13:08:22.035857Z","caller":"traceutil/trace.go:171","msg":"trace[801836767] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"609.117288ms","start":"2026-01-28T13:08:21.426728Z","end":"2026-01-28T13:08:22.035845Z","steps":["trace[801836767] 'process raft request'  (duration: 411.931172ms)","trace[801836767] 'compare'  (duration: 196.633403ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-28T13:08:22.035913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:08:21.426712Z","time spent":"609.170629ms","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":495,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" mod_revision:976 > success:<request_put:<key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" value_size:425 >> failure:<request_range:<key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" > >"}
{"level":"info","ts":"2026-01-28T13:08:22.036152Z","caller":"traceutil/trace.go:171","msg":"trace[659817771] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"581.228588ms","start":"2026-01-28T13:08:21.454912Z","end":"2026-01-28T13:08:22.036141Z","steps":["trace[659817771] 'process raft request'  (duration: 580.702666ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:08:22.036207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:08:21.454891Z","time spent":"581.285749ms","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" mod_revision:944 > success:<request_put:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" value_size:600 >> failure:<request_range:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" > >"}
{"level":"warn","ts":"2026-01-28T13:08:22.036341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.078321085s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-01-28T13:08:22.036369Z","caller":"traceutil/trace.go:171","msg":"trace[2018341371] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:990; }","duration":"1.078403766s","start":"2026-01-28T13:08:20.957956Z","end":"2026-01-28T13:08:22.03636Z","steps":["trace[2018341371] 'agreement among raft nodes before linearized reading'  (duration: 1.078353605s)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:08:22.036399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:08:20.957905Z","time spent":"1.078487509s","remote":"127.0.0.1:60254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":3,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "}
{"level":"warn","ts":"2026-01-28T13:08:22.536761Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4056225958451181175,"retry-timeout":"500ms"}
{"level":"info","ts":"2026-01-28T13:08:22.705148Z","caller":"traceutil/trace.go:171","msg":"trace[1390973889] linearizableReadLoop","detail":"{readStateIndex:1036; appliedIndex:1034; }","duration":"669.416653ms","start":"2026-01-28T13:08:22.035716Z","end":"2026-01-28T13:08:22.705132Z","steps":["trace[1390973889] 'read index received'  (duration: 622.308745ms)","trace[1390973889] 'applied index is now lower than readState.Index'  (duration: 47.107168ms)"],"step_count":2}
{"level":"info","ts":"2026-01-28T13:08:22.705661Z","caller":"traceutil/trace.go:171","msg":"trace[1024886145] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"1.217851416s","start":"2026-01-28T13:08:21.487754Z","end":"2026-01-28T13:08:22.705606Z","steps":["trace[1024886145] 'process raft request'  (duration: 1.170281887s)","trace[1024886145] 'compare'  (duration: 46.29666ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-28T13:08:22.705789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:08:21.487738Z","time spent":"1.21799239s","remote":"127.0.0.1:54720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":871,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/events/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-sqfht.188ee702560ecaa2\" mod_revision:0 > success:<request_put:<key:\"/registry/events/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-sqfht.188ee702560ecaa2\" value_size:766 lease:4056225958451181169 >> failure:<>"}
{"level":"warn","ts":"2026-01-28T13:08:22.705884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"973.04283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-28T13:08:22.705915Z","caller":"traceutil/trace.go:171","msg":"trace[1944326399] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:992; }","duration":"973.106262ms","start":"2026-01-28T13:08:21.7328Z","end":"2026-01-28T13:08:22.705907Z","steps":["trace[1944326399] 'agreement among raft nodes before linearized reading'  (duration: 973.02628ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:08:22.705938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:08:21.732784Z","time spent":"973.149473ms","remote":"127.0.0.1:54652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-01-28T13:08:22.706025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.024876148s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"info","ts":"2026-01-28T13:08:22.706049Z","caller":"traceutil/trace.go:171","msg":"trace[702171599] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:992; }","duration":"1.024922469s","start":"2026-01-28T13:08:21.681119Z","end":"2026-01-28T13:08:22.706042Z","steps":["trace[702171599] 'agreement among raft nodes before linearized reading'  (duration: 1.024822257s)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:08:22.706068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:08:21.681103Z","time spent":"1.02496158s","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-01-28T13:08:22.706167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.289776023s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"info","ts":"2026-01-28T13:08:22.706183Z","caller":"traceutil/trace.go:171","msg":"trace[1519681600] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:992; }","duration":"1.289823514s","start":"2026-01-28T13:08:21.416353Z","end":"2026-01-28T13:08:22.706177Z","steps":["trace[1519681600] 'agreement among raft nodes before linearized reading'  (duration: 1.289770623s)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:08:22.706202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:08:21.416335Z","time spent":"1.289863145s","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"info","ts":"2026-01-28T13:08:22.7062Z","caller":"traceutil/trace.go:171","msg":"trace[2004363749] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"1.171128348s","start":"2026-01-28T13:08:21.535062Z","end":"2026-01-28T13:08:22.706191Z","steps":["trace[2004363749] 'process raft request'  (duration: 1.170020712s)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:08:22.706336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.353579ms","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-01-28T13:08:22.706356Z","caller":"traceutil/trace.go:171","msg":"trace[798398792] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:992; }","duration":"282.39659ms","start":"2026-01-28T13:08:22.423952Z","end":"2026-01-28T13:08:22.706349Z","steps":["trace[798398792] 'agreement among raft nodes before linearized reading'  (duration: 282.347219ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:08:22.70648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:08:21.535049Z","time spent":"1.171383863s","remote":"127.0.0.1:54894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:983 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:411 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"warn","ts":"2026-01-28T13:08:22.706676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"880.316399ms","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-01-28T13:08:22.706695Z","caller":"traceutil/trace.go:171","msg":"trace[561377779] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:992; }","duration":"880.363969ms","start":"2026-01-28T13:08:21.826326Z","end":"2026-01-28T13:08:22.70669Z","steps":["trace[561377779] 'agreement among raft nodes before linearized reading'  (duration: 880.310758ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:08:22.706712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:08:21.826306Z","time spent":"880.402171ms","remote":"127.0.0.1:54894","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-01-28T13:08:22.706781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"914.566697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-01-28T13:08:22.706801Z","caller":"traceutil/trace.go:171","msg":"trace[259064895] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:992; }","duration":"914.613258ms","start":"2026-01-28T13:08:21.792183Z","end":"2026-01-28T13:08:22.706796Z","steps":["trace[259064895] 'agreement among raft nodes before linearized reading'  (duration: 914.580767ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:08:22.706816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:08:21.792168Z","time spent":"914.644269ms","remote":"127.0.0.1:55082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":3,"response size":31,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "}
{"level":"info","ts":"2026-01-28T13:08:25.012311Z","caller":"traceutil/trace.go:171","msg":"trace[9826956] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"295.409735ms","start":"2026-01-28T13:08:24.716885Z","end":"2026-01-28T13:08:25.012295Z","steps":["trace[9826956] 'process raft request'  (duration: 295.297072ms)"],"step_count":1}
{"level":"info","ts":"2026-01-28T13:08:25.012618Z","caller":"traceutil/trace.go:171","msg":"trace[1231011748] linearizableReadLoop","detail":"{readStateIndex:1050; appliedIndex:1050; }","duration":"286.888816ms","start":"2026-01-28T13:08:24.725723Z","end":"2026-01-28T13:08:25.012612Z","steps":["trace[1231011748] 'read index received'  (duration: 286.886116ms)","trace[1231011748] 'applied index is now lower than readState.Index'  (duration: 2.36µs)"],"step_count":2}
{"level":"warn","ts":"2026-01-28T13:08:25.012775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.037529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"info","ts":"2026-01-28T13:08:25.012802Z","caller":"traceutil/trace.go:171","msg":"trace[219497785] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1005; }","duration":"287.091521ms","start":"2026-01-28T13:08:24.725704Z","end":"2026-01-28T13:08:25.012795Z","steps":["trace[219497785] 'agreement among raft nodes before linearized reading'  (duration: 286.992739ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:08:25.014044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.851175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"info","ts":"2026-01-28T13:08:25.014391Z","caller":"traceutil/trace.go:171","msg":"trace[1490397494] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1006; }","duration":"284.017699ms","start":"2026-01-28T13:08:24.73014Z","end":"2026-01-28T13:08:25.014157Z","steps":["trace[1490397494] 'agreement among raft nodes before linearized reading'  (duration: 283.781794ms)"],"step_count":1}
{"level":"info","ts":"2026-01-28T13:08:25.014793Z","caller":"traceutil/trace.go:171","msg":"trace[597493503] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"108.709573ms","start":"2026-01-28T13:08:24.906074Z","end":"2026-01-28T13:08:25.014783Z","steps":["trace[597493503] 'process raft request'  (duration: 107.74449ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:08:25.015045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.040891ms","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-01-28T13:08:25.015087Z","caller":"traceutil/trace.go:171","msg":"trace[113117555] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1006; }","duration":"269.083892ms","start":"2026-01-28T13:08:24.745996Z","end":"2026-01-28T13:08:25.01508Z","steps":["trace[113117555] 'agreement among raft nodes before linearized reading'  (duration: 268.868347ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:08:25.014848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.909784ms","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-01-28T13:08:25.015206Z","caller":"traceutil/trace.go:171","msg":"trace[1829408376] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1006; }","duration":"271.283822ms","start":"2026-01-28T13:08:24.743903Z","end":"2026-01-28T13:08:25.015187Z","steps":["trace[1829408376] 'agreement among raft nodes before linearized reading'  (duration: 270.884363ms)"],"step_count":1}
{"level":"info","ts":"2026-01-28T13:10:56.019374Z","caller":"traceutil/trace.go:171","msg":"trace[1790951944] linearizableReadLoop","detail":"{readStateIndex:1716; appliedIndex:1715; }","duration":"102.268611ms","start":"2026-01-28T13:10:55.917084Z","end":"2026-01-28T13:10:56.019352Z","steps":["trace[1790951944] 'read index received'  (duration: 70.708767ms)","trace[1790951944] 'applied index is now lower than readState.Index'  (duration: 31.559234ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-28T13:10:56.019525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.421855ms","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-01-28T13:10:56.019542Z","caller":"traceutil/trace.go:171","msg":"trace[747609651] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1641; }","duration":"102.480116ms","start":"2026-01-28T13:10:55.917056Z","end":"2026-01-28T13:10:56.019537Z","steps":["trace[747609651] 'agreement among raft nodes before linearized reading'  (duration: 102.368564ms)"],"step_count":1}
{"level":"info","ts":"2026-01-28T13:10:56.019654Z","caller":"traceutil/trace.go:171","msg":"trace[1165722538] transaction","detail":"{read_only:false; response_revision:1641; number_of_response:1; }","duration":"115.47569ms","start":"2026-01-28T13:10:55.90415Z","end":"2026-01-28T13:10:56.019625Z","steps":["trace[1165722538] 'process raft request'  (duration: 83.621678ms)","trace[1165722538] 'compare'  (duration: 31.497073ms)"],"step_count":2}
{"level":"info","ts":"2026-01-28T13:11:34.154209Z","caller":"traceutil/trace.go:171","msg":"trace[1176977583] transaction","detail":"{read_only:false; response_revision:1824; number_of_response:1; }","duration":"135.73508ms","start":"2026-01-28T13:11:34.01845Z","end":"2026-01-28T13:11:34.154185Z","steps":["trace[1176977583] 'process raft request'  (duration: 135.499205ms)"],"step_count":1}
{"level":"info","ts":"2026-01-28T13:11:39.411137Z","caller":"traceutil/trace.go:171","msg":"trace[1795334063] linearizableReadLoop","detail":"{readStateIndex:1933; appliedIndex:1932; }","duration":"111.006595ms","start":"2026-01-28T13:11:39.300108Z","end":"2026-01-28T13:11:39.411114Z","steps":["trace[1795334063] 'read index received'  (duration: 19.28217ms)","trace[1795334063] 'applied index is now lower than readState.Index'  (duration: 91.723015ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-28T13:11:39.411454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.302661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"}
{"level":"info","ts":"2026-01-28T13:11:39.411519Z","caller":"traceutil/trace.go:171","msg":"trace[1541245752] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1848; }","duration":"111.378613ms","start":"2026-01-28T13:11:39.300104Z","end":"2026-01-28T13:11:39.411483Z","steps":["trace[1541245752] 'agreement among raft nodes before linearized reading'  (duration: 111.123277ms)"],"step_count":1}
{"level":"info","ts":"2026-01-28T13:11:39.411912Z","caller":"traceutil/trace.go:171","msg":"trace[1736764679] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"126.865504ms","start":"2026-01-28T13:11:39.285028Z","end":"2026-01-28T13:11:39.411893Z","steps":["trace[1736764679] 'process raft request'  (duration: 34.420952ms)","trace[1736764679] 'compare'  (duration: 91.426128ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-28T13:11:40.248221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.588728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"info","ts":"2026-01-28T13:11:40.248292Z","caller":"traceutil/trace.go:171","msg":"trace[236488960] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1850; }","duration":"120.702531ms","start":"2026-01-28T13:11:40.127572Z","end":"2026-01-28T13:11:40.248275Z","steps":["trace[236488960] 'range keys from in-memory index tree'  (duration: 120.439985ms)"],"step_count":1}
{"level":"info","ts":"2026-01-28T13:11:40.364179Z","caller":"traceutil/trace.go:171","msg":"trace[1126321077] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"112.793197ms","start":"2026-01-28T13:11:40.251366Z","end":"2026-01-28T13:11:40.364159Z","steps":["trace[1126321077] 'process raft request'  (duration: 112.682324ms)"],"step_count":1}
{"level":"info","ts":"2026-01-28T13:11:40.411751Z","caller":"traceutil/trace.go:171","msg":"trace[1818760579] linearizableReadLoop","detail":"{readStateIndex:1937; appliedIndex:1936; }","duration":"157.415005ms","start":"2026-01-28T13:11:40.254313Z","end":"2026-01-28T13:11:40.411728Z","steps":["trace[1818760579] 'read index received'  (duration: 109.862668ms)","trace[1818760579] 'applied index is now lower than readState.Index'  (duration: 47.551467ms)"],"step_count":2}
{"level":"info","ts":"2026-01-28T13:11:40.412759Z","caller":"traceutil/trace.go:171","msg":"trace[1024414704] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"159.568336ms","start":"2026-01-28T13:11:40.253175Z","end":"2026-01-28T13:11:40.412743Z","steps":["trace[1024414704] 'process raft request'  (duration: 158.43718ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:11:40.412932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.608253ms","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-01-28T13:11:40.41295Z","caller":"traceutil/trace.go:171","msg":"trace[956659359] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1852; }","duration":"158.659845ms","start":"2026-01-28T13:11:40.254285Z","end":"2026-01-28T13:11:40.412945Z","steps":["trace[956659359] 'agreement among raft nodes before linearized reading'  (duration: 158.575663ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-28T13:11:40.413037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.847252ms","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-01-28T13:11:40.413049Z","caller":"traceutil/trace.go:171","msg":"trace[1874589898] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1852; }","duration":"101.890053ms","start":"2026-01-28T13:11:40.311155Z","end":"2026-01-28T13:11:40.413046Z","steps":["trace[1874589898] 'agreement among raft nodes before linearized reading'  (duration: 101.853542ms)"],"step_count":1}