{"level":"warn","ts":"2026-02-26T03:06:41.854517Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-26T03:06:41.854603Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.110: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.110:2380","--initial-cluster=instance=https://162.253.55.110:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.110:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.110:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-26T03:06:41.854685Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-26T03:06:41.8547Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.110:2380"]} {"level":"info","ts":"2026-02-26T03:06:41.85473Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-26T03:06:41.8555Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"]} {"level":"info","ts":"2026-02-26T03:06:41.855589Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.110:2380"],"listen-peer-urls":["https://162.253.55.110:2380"],"advertise-client-urls":["https://162.253.55.110:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.110:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-26T03:06:41.860383Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.426266ms"} {"level":"info","ts":"2026-02-26T03:06:41.86904Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f52f30d5714009d5","cluster-id":"650981a7a383c714"} {"level":"info","ts":"2026-02-26T03:06:41.869126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=()"} {"level":"info","ts":"2026-02-26T03:06:41.86915Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became follower at term 0"} {"level":"info","ts":"2026-02-26T03:06:41.869162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f52f30d5714009d5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-26T03:06:41.869172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became follower at term 1"} {"level":"info","ts":"2026-02-26T03:06:41.869224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=(17667393556483934677)"} {"level":"warn","ts":"2026-02-26T03:06:41.879139Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-26T03:06:41.880913Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-26T03:06:41.882306Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-26T03:06:41.886235Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f52f30d5714009d5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-26T03:06:41.886995Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f52f30d5714009d5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-26T03:06:41.88714Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-26T03:06:41.887179Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-26T03:06:41.887189Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-26T03:06:41.889105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=(17667393556483934677)"} {"level":"info","ts":"2026-02-26T03:06:41.889352Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"650981a7a383c714","local-member-id":"f52f30d5714009d5","added-peer-id":"f52f30d5714009d5","added-peer-peer-urls":["https://162.253.55.110:2380"]} {"level":"info","ts":"2026-02-26T03:06:41.891547Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-26T03:06:41.891799Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f52f30d5714009d5","initial-advertise-peer-urls":["https://162.253.55.110:2380"],"listen-peer-urls":["https://162.253.55.110:2380"],"advertise-client-urls":["https://162.253.55.110:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-26T03:06:41.891837Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-26T03:06:41.892204Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.110:2380"} {"level":"info","ts":"2026-02-26T03:06:41.892228Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.110:2380"} {"level":"info","ts":"2026-02-26T03:06:42.270121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 is starting a new election at term 1"} {"level":"info","ts":"2026-02-26T03:06:42.270207Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-26T03:06:42.270256Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 received MsgPreVoteResp from f52f30d5714009d5 at term 1"} {"level":"info","ts":"2026-02-26T03:06:42.270275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became candidate at term 2"} {"level":"info","ts":"2026-02-26T03:06:42.270284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 received MsgVoteResp from f52f30d5714009d5 at term 2"} {"level":"info","ts":"2026-02-26T03:06:42.270298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became leader at term 2"} {"level":"info","ts":"2026-02-26T03:06:42.27031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f52f30d5714009d5 elected leader f52f30d5714009d5 at term 2"} {"level":"info","ts":"2026-02-26T03:06:42.272022Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f52f30d5714009d5","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.110:2379]}","request-path":"/0/members/f52f30d5714009d5/attributes","cluster-id":"650981a7a383c714","publish-timeout":"7s"} {"level":"info","ts":"2026-02-26T03:06:42.272213Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T03:06:42.272309Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T03:06:42.272858Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T03:06:42.274842Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-26T03:06:42.275893Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.110:2379"} {"level":"info","ts":"2026-02-26T03:06:42.283528Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-26T03:06:42.283886Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-26T03:06:42.284415Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"650981a7a383c714","local-member-id":"f52f30d5714009d5","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T03:06:42.284611Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T03:06:42.284654Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T03:06:47.087185Z","caller":"traceutil/trace.go:171","msg":"trace[1306428582] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"132.572542ms","start":"2026-02-26T03:06:46.954593Z","end":"2026-02-26T03:06:47.087165Z","steps":["trace[1306428582] 'process raft request' (duration: 132.311885ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:06:47.087191Z","caller":"traceutil/trace.go:171","msg":"trace[291811109] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"132.563502ms","start":"2026-02-26T03:06:46.954601Z","end":"2026-02-26T03:06:47.087164Z","steps":["trace[291811109] 'process raft request' (duration: 132.416518ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:06:47.215982Z","caller":"traceutil/trace.go:171","msg":"trace[1053892491] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"125.366279ms","start":"2026-02-26T03:06:47.090586Z","end":"2026-02-26T03:06:47.215952Z","steps":["trace[1053892491] 'process raft request' (duration: 75.620765ms)","trace[1053892491] 'compare' (duration: 49.223782ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:06:47.216075Z","caller":"traceutil/trace.go:171","msg":"trace[930847513] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"120.207945ms","start":"2026-02-26T03:06:47.095816Z","end":"2026-02-26T03:06:47.216024Z","steps":["trace[930847513] 'process raft request' (duration: 119.820746ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:06:47.33457Z","caller":"traceutil/trace.go:171","msg":"trace[1183259113] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"112.89095ms","start":"2026-02-26T03:06:47.221637Z","end":"2026-02-26T03:06:47.334528Z","steps":["trace[1183259113] 'process raft request' (duration: 98.057654ms)","trace[1183259113] 'compare' (duration: 14.612201ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:06:47.334667Z","caller":"traceutil/trace.go:171","msg":"trace[829216129] linearizableReadLoop","detail":"{readStateIndex:301; appliedIndex:299; }","duration":"112.793318ms","start":"2026-02-26T03:06:47.221855Z","end":"2026-02-26T03:06:47.334648Z","steps":["trace[829216129] 'read index received' (duration: 1.555968ms)","trace[829216129] 'applied index is now lower than readState.Index' (duration: 111.23529ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:06:47.334644Z","caller":"traceutil/trace.go:171","msg":"trace[593455169] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"104.453988ms","start":"2026-02-26T03:06:47.23016Z","end":"2026-02-26T03:06:47.334614Z","steps":["trace[593455169] 'process raft request' (duration: 104.361486ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:06:47.334793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.909601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pv-protection-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T03:06:47.334881Z","caller":"traceutil/trace.go:171","msg":"trace[136689463] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pv-protection-controller; range_end:; response_count:0; response_revision:293; }","duration":"113.043254ms","start":"2026-02-26T03:06:47.221825Z","end":"2026-02-26T03:06:47.334868Z","steps":["trace[136689463] 'agreement among raft nodes before linearized reading' (duration: 112.906201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:06:47.334959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.330175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-26T03:06:47.33497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.292046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:115"} {"level":"info","ts":"2026-02-26T03:06:47.335021Z","caller":"traceutil/trace.go:171","msg":"trace[1589187351] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:293; }","duration":"109.415027ms","start":"2026-02-26T03:06:47.225588Z","end":"2026-02-26T03:06:47.335002Z","steps":["trace[1589187351] 'agreement among raft nodes before linearized reading' (duration: 109.291184ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:06:47.335043Z","caller":"traceutil/trace.go:171","msg":"trace[2070711835] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:293; }","duration":"112.409879ms","start":"2026-02-26T03:06:47.222617Z","end":"2026-02-26T03:06:47.335027Z","steps":["trace[2070711835] 'agreement among raft nodes before linearized reading' (duration: 112.237335ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:06:47.509958Z","caller":"traceutil/trace.go:171","msg":"trace[942674068] linearizableReadLoop","detail":"{readStateIndex:307; appliedIndex:306; }","duration":"119.095478ms","start":"2026-02-26T03:06:47.390839Z","end":"2026-02-26T03:06:47.509935Z","steps":["trace[942674068] 'read index received' (duration: 75.367739ms)","trace[942674068] 'applied index is now lower than readState.Index' (duration: 43.726659ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:06:47.5101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.245822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T03:06:47.510133Z","caller":"traceutil/trace.go:171","msg":"trace[2026309547] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:0; response_revision:298; }","duration":"119.335904ms","start":"2026-02-26T03:06:47.390786Z","end":"2026-02-26T03:06:47.510122Z","steps":["trace[2026309547] 'agreement among raft nodes before linearized reading' (duration: 119.244142ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:06:47.510109Z","caller":"traceutil/trace.go:171","msg":"trace[468778526] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"129.882857ms","start":"2026-02-26T03:06:47.380184Z","end":"2026-02-26T03:06:47.510067Z","steps":["trace[468778526] 'process raft request' (duration: 85.988874ms)","trace[468778526] 'compare' (duration: 43.571606ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:07:06.646457Z","caller":"traceutil/trace.go:171","msg":"trace[477199825] linearizableReadLoop","detail":"{readStateIndex:484; appliedIndex:483; }","duration":"281.925988ms","start":"2026-02-26T03:07:06.364513Z","end":"2026-02-26T03:07:06.646439Z","steps":["trace[477199825] 'read index received' (duration: 281.764874ms)","trace[477199825] 'applied index is now lower than readState.Index' (duration: 160.514µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:07:06.646569Z","caller":"traceutil/trace.go:171","msg":"trace[1799317036] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"388.544468ms","start":"2026-02-26T03:07:06.257985Z","end":"2026-02-26T03:07:06.64653Z","steps":["trace[1799317036] 'process raft request' (duration: 388.341623ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:07:06.646605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.083312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-26T03:07:06.646745Z","caller":"traceutil/trace.go:171","msg":"trace[1195388678] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:466; }","duration":"282.246666ms","start":"2026-02-26T03:07:06.364472Z","end":"2026-02-26T03:07:06.646719Z","steps":["trace[1195388678] 'agreement among raft nodes before linearized reading' (duration: 282.065741ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:07:06.647068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:07:06.257953Z","time spent":"388.703702ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T03:07:07.030339Z","caller":"traceutil/trace.go:171","msg":"trace[415899456] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"379.0826ms","start":"2026-02-26T03:07:06.651233Z","end":"2026-02-26T03:07:07.030316Z","steps":["trace[415899456] 'process raft request' (duration: 378.974518ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:07:07.030409Z","caller":"traceutil/trace.go:171","msg":"trace[1897657766] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"379.246553ms","start":"2026-02-26T03:07:06.651141Z","end":"2026-02-26T03:07:07.030388Z","steps":["trace[1897657766] 'process raft request' (duration: 304.351715ms)","trace[1897657766] 'compare' (duration: 74.592092ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:07:07.030468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:07:06.651227Z","time spent":"379.176081ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T03:07:07.030512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:07:06.651123Z","time spent":"379.333726ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T03:07:12.792051Z","caller":"traceutil/trace.go:171","msg":"trace[490571070] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"117.670975ms","start":"2026-02-26T03:07:12.674357Z","end":"2026-02-26T03:07:12.792028Z","steps":["trace[490571070] 'process raft request' (duration: 117.533981ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:07:13.321897Z","caller":"traceutil/trace.go:171","msg":"trace[1750252726] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"262.649425ms","start":"2026-02-26T03:07:13.059221Z","end":"2026-02-26T03:07:13.32187Z","steps":["trace[1750252726] 'process raft request' (duration: 262.500401ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:07:13.47258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.933198ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T03:07:13.472865Z","caller":"traceutil/trace.go:171","msg":"trace[276684128] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"413.389453ms","start":"2026-02-26T03:07:13.059463Z","end":"2026-02-26T03:07:13.472853Z","steps":["trace[276684128] 'process raft request' (duration: 303.041325ms)","trace[276684128] 'compare' (duration: 109.796795ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:07:13.472902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:07:13.05945Z","time spent":"413.433374ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T03:07:13.990768Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708644692655690074,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-26T03:07:13.992098Z","caller":"traceutil/trace.go:171","msg":"trace[367075554] linearizableReadLoop","detail":"{readStateIndex:535; appliedIndex:534; }","duration":"501.589821ms","start":"2026-02-26T03:07:13.490493Z","end":"2026-02-26T03:07:13.992083Z","steps":["trace[367075554] 'read index received' (duration: 501.388576ms)","trace[367075554] 'applied index is now lower than readState.Index' (duration: 200.655µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:07:13.992233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.726314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-26T03:07:13.992255Z","caller":"traceutil/trace.go:171","msg":"trace[1082158507] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:516; }","duration":"501.803836ms","start":"2026-02-26T03:07:13.490446Z","end":"2026-02-26T03:07:13.99225Z","steps":["trace[1082158507] 'agreement among raft nodes before linearized reading' (duration: 501.700913ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:07:13.99228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:07:13.490431Z","time spent":"501.839447ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-26T03:07:13.992381Z","caller":"traceutil/trace.go:171","msg":"trace[509320627] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"513.665931ms","start":"2026-02-26T03:07:13.478682Z","end":"2026-02-26T03:07:13.992348Z","steps":["trace[509320627] 'process raft request' (duration: 513.281642ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:07:13.992641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:07:13.478661Z","time spent":"513.884136ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4567,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T03:07:16.244127Z","caller":"traceutil/trace.go:171","msg":"trace[637089214] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:542; }","duration":"162.294886ms","start":"2026-02-26T03:07:16.081808Z","end":"2026-02-26T03:07:16.244103Z","steps":["trace[637089214] 'read index received' (duration: 162.121032ms)","trace[637089214] 'applied index is now lower than readState.Index' (duration: 172.904µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:07:16.244308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.48619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T03:07:16.244353Z","caller":"traceutil/trace.go:171","msg":"trace[904940919] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:523; }","duration":"162.568933ms","start":"2026-02-26T03:07:16.081767Z","end":"2026-02-26T03:07:16.244336Z","steps":["trace[904940919] 'agreement among raft nodes before linearized reading' (duration: 162.431919ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:07:16.244437Z","caller":"traceutil/trace.go:171","msg":"trace[948077005] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"238.431634ms","start":"2026-02-26T03:07:16.005983Z","end":"2026-02-26T03:07:16.244415Z","steps":["trace[948077005] 'process raft request' (duration: 237.981183ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:07:20.536636Z","caller":"traceutil/trace.go:171","msg":"trace[1611880156] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"269.688314ms","start":"2026-02-26T03:07:20.266927Z","end":"2026-02-26T03:07:20.536615Z","steps":["trace[1611880156] 'process raft request' (duration: 269.53121ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:07:20.738617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.879622ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-02-26T03:07:21.59098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.919059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-26T03:07:21.591052Z","caller":"traceutil/trace.go:171","msg":"trace[658331940] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:536; }","duration":"235.102173ms","start":"2026-02-26T03:07:21.355932Z","end":"2026-02-26T03:07:21.591034Z","steps":["trace[658331940] 'range keys from in-memory index tree' (duration: 234.724084ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:07:21.591046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.65498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-9qd6t\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-02-26T03:07:21.591132Z","caller":"traceutil/trace.go:171","msg":"trace[1538890522] range","detail":"{range_begin:/registry/pods/kube-system/cilium-9qd6t; range_end:; response_count:1; response_revision:536; }","duration":"528.796354ms","start":"2026-02-26T03:07:21.062313Z","end":"2026-02-26T03:07:21.591109Z","steps":["trace[1538890522] 'range keys from in-memory index tree' (duration: 528.524288ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:07:21.591184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:07:21.062295Z","time spent":"528.874486ms","remote":"127.0.0.1:39918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-9qd6t\" "} {"level":"warn","ts":"2026-02-26T03:07:23.630565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.936529298s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T03:07:23.630684Z","caller":"traceutil/trace.go:171","msg":"trace[573427416] linearizableReadLoop","detail":"{readStateIndex:560; appliedIndex:559; }","duration":"1.548460521s","start":"2026-02-26T03:07:22.082209Z","end":"2026-02-26T03:07:23.630669Z","steps":["trace[573427416] 'read index received' (duration: 40.361µs)","trace[573427416] 'applied index is now lower than readState.Index' (duration: 1.54841881s)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:07:23.631003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.54877866s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T03:07:23.631093Z","caller":"traceutil/trace.go:171","msg":"trace[1208464674] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:539; }","duration":"1.548929503s","start":"2026-02-26T03:07:22.082143Z","end":"2026-02-26T03:07:23.631072Z","steps":["trace[1208464674] 'agreement among raft nodes before linearized reading' (duration: 1.54879534s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:07:23.63113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.089990306s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-26T03:07:23.631148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:07:22.082124Z","time spent":"1.549011855s","remote":"127.0.0.1:39740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T03:07:23.631179Z","caller":"traceutil/trace.go:171","msg":"trace[1159368857] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:539; }","duration":"1.090074418s","start":"2026-02-26T03:07:22.541089Z","end":"2026-02-26T03:07:23.631163Z","steps":["trace[1159368857] 'agreement among raft nodes before linearized reading' (duration: 1.089929715s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:07:23.631222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:07:22.541068Z","time spent":"1.09014336s","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T03:07:23.631244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.778637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-26T03:07:23.631276Z","caller":"traceutil/trace.go:171","msg":"trace[1263752023] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:539; }","duration":"751.853069ms","start":"2026-02-26T03:07:22.879413Z","end":"2026-02-26T03:07:23.631266Z","steps":["trace[1263752023] 'agreement among raft nodes before linearized reading' (duration: 751.766637ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:07:23.631144Z","caller":"traceutil/trace.go:171","msg":"trace[38374438] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"2.02453719s","start":"2026-02-26T03:07:21.606588Z","end":"2026-02-26T03:07:23.631125Z","steps":["trace[38374438] 'process raft request' (duration: 87.368718ms)","trace[38374438] 'compare' (duration: 1.936317622s)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:07:23.631313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:07:22.879392Z","time spent":"751.91329ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T03:07:23.631393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:07:21.606561Z","time spent":"2.024774647s","remote":"127.0.0.1:39918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T03:07:25.394337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.870424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T03:07:25.394431Z","caller":"traceutil/trace.go:171","msg":"trace[1569332221] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:572; }","duration":"168.991677ms","start":"2026-02-26T03:07:25.225417Z","end":"2026-02-26T03:07:25.394408Z","steps":["trace[1569332221] 'range keys from in-memory index tree' (duration: 168.73609ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:07:36.294857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.300173ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T03:07:36.294977Z","caller":"traceutil/trace.go:171","msg":"trace[1321670614] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:694; }","duration":"381.431967ms","start":"2026-02-26T03:07:35.913529Z","end":"2026-02-26T03:07:36.294961Z","steps":["trace[1321670614] 'range keys from in-memory index tree' (duration: 381.285083ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:07:36.295001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.758559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-lcbj5\" ","response":"range_response_count:1 size:5050"} {"level":"warn","ts":"2026-02-26T03:07:36.294993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.320466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T03:07:36.295281Z","caller":"traceutil/trace.go:171","msg":"trace[411872339] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-lcbj5; range_end:; response_count:1; response_revision:694; }","duration":"126.064526ms","start":"2026-02-26T03:07:36.169199Z","end":"2026-02-26T03:07:36.295264Z","steps":["trace[411872339] 'range keys from in-memory index tree' (duration: 125.613646ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:07:36.295296Z","caller":"traceutil/trace.go:171","msg":"trace[184952800] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:694; }","duration":"212.677955ms","start":"2026-02-26T03:07:36.082607Z","end":"2026-02-26T03:07:36.295285Z","steps":["trace[184952800] 'range keys from in-memory index tree' (duration: 212.251985ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:07:42.144056Z","caller":"traceutil/trace.go:171","msg":"trace[1625965121] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"340.040441ms","start":"2026-02-26T03:07:41.803993Z","end":"2026-02-26T03:07:42.144033Z","steps":["trace[1625965121] 'process raft request' (duration: 339.760324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:07:42.144404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:07:41.803979Z","time spent":"340.299608ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T03:07:43.752082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.497881ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T03:07:43.752149Z","caller":"traceutil/trace.go:171","msg":"trace[2007479698] linearizableReadLoop","detail":"{readStateIndex:761; appliedIndex:759; }","duration":"1.944431056s","start":"2026-02-26T03:07:41.807707Z","end":"2026-02-26T03:07:43.752138Z","steps":["trace[2007479698] 'read index received' (duration: 336.042804ms)","trace[2007479698] 'applied index is now lower than readState.Index' (duration: 1.608387332s)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:07:43.752188Z","caller":"traceutil/trace.go:171","msg":"trace[704921821] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"1.947010079s","start":"2026-02-26T03:07:41.805156Z","end":"2026-02-26T03:07:43.752166Z","steps":["trace[704921821] 'process raft request' (duration: 1.228381956s)","trace[704921821] 'compare' (duration: 718.06078ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:07:43.75227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.944562929s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-26T03:07:43.752288Z","caller":"traceutil/trace.go:171","msg":"trace[718166997] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:734; }","duration":"1.944593901s","start":"2026-02-26T03:07:41.807688Z","end":"2026-02-26T03:07:43.752282Z","steps":["trace[718166997] 'agreement among raft nodes before linearized reading' (duration: 1.944510318s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:07:43.752291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:07:41.80514Z","time spent":"1.94709664s","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T03:07:43.752306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:07:41.807678Z","time spent":"1.944623421s","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T03:07:43.75263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.944822915s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-26T03:07:43.75267Z","caller":"traceutil/trace.go:171","msg":"trace[2028848349] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:734; }","duration":"1.944904788s","start":"2026-02-26T03:07:41.807754Z","end":"2026-02-26T03:07:43.752659Z","steps":["trace[2028848349] 'agreement among raft nodes before linearized reading' (duration: 1.944799215s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:07:43.752693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.868396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-02-26T03:07:43.752694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:07:41.807741Z","time spent":"1.944947988s","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T03:07:43.752686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.672393735s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T03:07:43.752721Z","caller":"traceutil/trace.go:171","msg":"trace[690690431] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:734; }","duration":"640.934438ms","start":"2026-02-26T03:07:43.111779Z","end":"2026-02-26T03:07:43.752713Z","steps":["trace[690690431] 'agreement among raft nodes before linearized reading' (duration: 640.816855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:07:43.752743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:07:43.111759Z","time spent":"640.97973ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-26T03:07:43.75275Z","caller":"traceutil/trace.go:171","msg":"trace[811435237] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:734; }","duration":"1.672495128s","start":"2026-02-26T03:07:42.080238Z","end":"2026-02-26T03:07:43.752733Z","steps":["trace[811435237] 'agreement among raft nodes before linearized reading' (duration: 1.672374995s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:07:43.752779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:07:42.080221Z","time spent":"1.67255321s","remote":"127.0.0.1:39740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T03:07:44.039321Z","caller":"traceutil/trace.go:171","msg":"trace[970272056] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"284.042521ms","start":"2026-02-26T03:07:43.755258Z","end":"2026-02-26T03:07:44.039301Z","steps":["trace[970272056] 'process raft request' (duration: 275.630418ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:07:44.043432Z","caller":"traceutil/trace.go:171","msg":"trace[1141567646] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"280.847322ms","start":"2026-02-26T03:07:43.762565Z","end":"2026-02-26T03:07:44.043412Z","steps":["trace[1141567646] 'process raft request' (duration: 280.716029ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:07:44.043602Z","caller":"traceutil/trace.go:171","msg":"trace[700472399] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"280.969195ms","start":"2026-02-26T03:07:43.762623Z","end":"2026-02-26T03:07:44.043592Z","steps":["trace[700472399] 'process raft request' (duration: 280.75413ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:07:44.043587Z","caller":"traceutil/trace.go:171","msg":"trace[828240445] linearizableReadLoop","detail":"{readStateIndex:764; appliedIndex:761; }","duration":"277.800148ms","start":"2026-02-26T03:07:43.765767Z","end":"2026-02-26T03:07:44.043567Z","steps":["trace[828240445] 'read index received' (duration: 265.110343ms)","trace[828240445] 'applied index is now lower than readState.Index' (duration: 12.685475ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:07:44.043697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.922972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T03:07:44.043723Z","caller":"traceutil/trace.go:171","msg":"trace[368117029] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:737; }","duration":"277.977533ms","start":"2026-02-26T03:07:43.765738Z","end":"2026-02-26T03:07:44.043716Z","steps":["trace[368117029] 'agreement among raft nodes before linearized reading' (duration: 277.893771ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:08:10.316077Z","caller":"traceutil/trace.go:171","msg":"trace[758843797] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"117.182636ms","start":"2026-02-26T03:08:10.198862Z","end":"2026-02-26T03:08:10.316045Z","steps":["trace[758843797] 'process raft request' (duration: 116.890759ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:08:11.808541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.032103ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-26T03:08:12.471553Z","caller":"traceutil/trace.go:171","msg":"trace[928314384] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"140.279574ms","start":"2026-02-26T03:08:12.331243Z","end":"2026-02-26T03:08:12.471523Z","steps":["trace[928314384] 'process raft request' (duration: 109.02876ms)","trace[928314384] 'compare' (duration: 31.133981ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:08:19.452085Z","caller":"traceutil/trace.go:171","msg":"trace[1689055838] linearizableReadLoop","detail":"{readStateIndex:876; appliedIndex:875; }","duration":"369.710606ms","start":"2026-02-26T03:08:19.082347Z","end":"2026-02-26T03:08:19.452058Z","steps":["trace[1689055838] 'read index received' (duration: 369.573922ms)","trace[1689055838] 'applied index is now lower than readState.Index' (duration: 135.954µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:08:19.452196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.841578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T03:08:19.452183Z","caller":"traceutil/trace.go:171","msg":"trace[1603752389] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"397.322583ms","start":"2026-02-26T03:08:19.054831Z","end":"2026-02-26T03:08:19.452154Z","steps":["trace[1603752389] 'process raft request' (duration: 397.069287ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:08:19.452225Z","caller":"traceutil/trace.go:171","msg":"trace[1681607130] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:841; }","duration":"369.92607ms","start":"2026-02-26T03:08:19.08229Z","end":"2026-02-26T03:08:19.452216Z","steps":["trace[1681607130] 'agreement among raft nodes before linearized reading' (duration: 369.861589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:08:19.452251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:19.082266Z","time spent":"369.978592ms","remote":"127.0.0.1:39740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T03:08:19.452338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:19.054803Z","time spent":"397.441615ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T03:08:20.88232Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708644692655690802,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T03:08:21.455588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.100118642s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-26T03:08:21.455675Z","caller":"traceutil/trace.go:171","msg":"trace[1705125946] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:842; }","duration":"1.100233905s","start":"2026-02-26T03:08:20.355404Z","end":"2026-02-26T03:08:21.455638Z","steps":["trace[1705125946] 'range keys from in-memory index tree' (duration: 1.099852926s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:08:21.455707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:20.35538Z","time spent":"1.100318758s","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T03:08:21.455546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.189629451s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-26T03:08:21.455853Z","caller":"traceutil/trace.go:171","msg":"trace[2043645547] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:842; }","duration":"1.19000728s","start":"2026-02-26T03:08:20.265837Z","end":"2026-02-26T03:08:21.455844Z","steps":["trace[2043645547] 'range keys from in-memory index tree' (duration: 1.189486218s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:08:21.455882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:20.265821Z","time spent":"1.190052652s","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T03:08:21.621696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.628847ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-26T03:08:21.621825Z","caller":"traceutil/trace.go:171","msg":"trace[366466689] linearizableReadLoop","detail":"{readStateIndex:878; appliedIndex:877; }","duration":"1.240583149s","start":"2026-02-26T03:08:20.381218Z","end":"2026-02-26T03:08:21.621801Z","steps":["trace[366466689] 'read index received' (duration: 736.788918ms)","trace[366466689] 'applied index is now lower than readState.Index' (duration: 503.792121ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:08:21.622353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.241111952s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-26T03:08:21.622492Z","caller":"traceutil/trace.go:171","msg":"trace[551381706] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:842; }","duration":"1.241193004s","start":"2026-02-26T03:08:20.381202Z","end":"2026-02-26T03:08:21.622395Z","steps":["trace[551381706] 'agreement among raft nodes before linearized reading' (duration: 1.240901477s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:08:21.622675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:20.381141Z","time spent":"1.241389859s","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-26T03:08:21.64396Z","caller":"traceutil/trace.go:171","msg":"trace[1252133243] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"183.300251ms","start":"2026-02-26T03:08:21.460633Z","end":"2026-02-26T03:08:21.643934Z","steps":["trace[1252133243] 'process raft request' (duration: 183.041475ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:08:21.644419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.080883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T03:08:21.644493Z","caller":"traceutil/trace.go:171","msg":"trace[752613676] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:844; }","duration":"564.196976ms","start":"2026-02-26T03:08:21.080276Z","end":"2026-02-26T03:08:21.644473Z","steps":["trace[752613676] 'agreement among raft nodes before linearized reading' (duration: 564.077874ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:08:21.644536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:21.080259Z","time spent":"564.266248ms","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T03:08:21.64454Z","caller":"traceutil/trace.go:171","msg":"trace[1524090311] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"183.895665ms","start":"2026-02-26T03:08:21.460633Z","end":"2026-02-26T03:08:21.644528Z","steps":["trace[1524090311] 'process raft request' (duration: 183.560377ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:08:21.644785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.14023406s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-26T03:08:21.644826Z","caller":"traceutil/trace.go:171","msg":"trace[792793507] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:844; }","duration":"1.140305971s","start":"2026-02-26T03:08:20.50451Z","end":"2026-02-26T03:08:21.644816Z","steps":["trace[792793507] 'agreement among raft nodes before linearized reading' (duration: 1.139765648s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:08:21.644855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:20.504491Z","time spent":"1.140357613s","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T03:08:21.644894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"976.506481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T03:08:21.645105Z","caller":"traceutil/trace.go:171","msg":"trace[1479120199] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:844; }","duration":"976.631084ms","start":"2026-02-26T03:08:20.668321Z","end":"2026-02-26T03:08:21.644952Z","steps":["trace[1479120199] 'agreement among raft nodes before linearized reading' (duration: 976.342057ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:08:21.645178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:20.668306Z","time spent":"976.854179ms","remote":"127.0.0.1:39918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":10,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-02-26T03:08:35.584746Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708644692655691007,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T03:08:35.704725Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.490401716s","expected-duration":"1s"} {"level":"info","ts":"2026-02-26T03:08:35.705325Z","caller":"traceutil/trace.go:171","msg":"trace[16862489] linearizableReadLoop","detail":"{readStateIndex:971; appliedIndex:970; }","duration":"621.456228ms","start":"2026-02-26T03:08:35.083847Z","end":"2026-02-26T03:08:35.705303Z","steps":["trace[16862489] 'read index received' (duration: 621.169711ms)","trace[16862489] 'applied index is now lower than readState.Index' (duration: 285.427µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:08:35.705525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.663393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T03:08:35.705581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.067956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.110\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-26T03:08:35.705576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.908142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T03:08:35.705627Z","caller":"traceutil/trace.go:171","msg":"trace[1752373956] range","detail":"{range_begin:/registry/masterleases/162.253.55.110; range_end:; response_count:1; response_revision:931; }","duration":"375.153368ms","start":"2026-02-26T03:08:35.33046Z","end":"2026-02-26T03:08:35.705614Z","steps":["trace[1752373956] 'agreement among raft nodes before linearized reading' (duration: 374.970833ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:08:35.705636Z","caller":"traceutil/trace.go:171","msg":"trace[96303303] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"1.49141585s","start":"2026-02-26T03:08:34.214195Z","end":"2026-02-26T03:08:35.705611Z","steps":["trace[96303303] 'process raft request' (duration: 1.490880017s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:08:35.705666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:35.330448Z","time spent":"375.20876ms","remote":"127.0.0.1:39758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.110\" "} {"level":"info","ts":"2026-02-26T03:08:35.705671Z","caller":"traceutil/trace.go:171","msg":"trace[1153373027] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:931; }","duration":"132.041784ms","start":"2026-02-26T03:08:35.573603Z","end":"2026-02-26T03:08:35.705644Z","steps":["trace[1153373027] 'agreement among raft nodes before linearized reading' (duration: 131.87101ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:08:35.705729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:34.214169Z","time spent":"1.491507752s","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T03:08:35.705672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.991396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T03:08:35.705825Z","caller":"traceutil/trace.go:171","msg":"trace[1460347906] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:931; }","duration":"373.17901ms","start":"2026-02-26T03:08:35.332623Z","end":"2026-02-26T03:08:35.705802Z","steps":["trace[1460347906] 'agreement among raft nodes before linearized reading' (duration: 372.949675ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:08:35.705924Z","caller":"traceutil/trace.go:171","msg":"trace[739797323] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:931; }","duration":"622.104244ms","start":"2026-02-26T03:08:35.08381Z","end":"2026-02-26T03:08:35.705915Z","steps":["trace[739797323] 'agreement among raft nodes before linearized reading' (duration: 621.599232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:08:35.70591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:35.332617Z","time spent":"373.275582ms","remote":"127.0.0.1:40200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-02-26T03:08:35.705949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:35.083795Z","time spent":"622.147275ms","remote":"127.0.0.1:39740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T03:08:36.219404Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708644692655691011,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T03:08:36.536135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.466644ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T03:08:36.53623Z","caller":"traceutil/trace.go:171","msg":"trace[240307083] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:931; }","duration":"615.572317ms","start":"2026-02-26T03:08:35.920646Z","end":"2026-02-26T03:08:36.536218Z","steps":["trace[240307083] 'range keys from in-memory index tree' (duration: 615.454344ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:08:36.536476Z","caller":"traceutil/trace.go:171","msg":"trace[2037981702] linearizableReadLoop","detail":"{readStateIndex:973; appliedIndex:971; }","duration":"817.462695ms","start":"2026-02-26T03:08:35.718996Z","end":"2026-02-26T03:08:36.536458Z","steps":["trace[2037981702] 'read index received' (duration: 814.966165ms)","trace[2037981702] 'applied index is now lower than readState.Index' (duration: 2.49599ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:08:36.536578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:35.706903Z","time spent":"829.67236ms","remote":"127.0.0.1:39758","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-26T03:08:36.536712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.710011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-26T03:08:36.536735Z","caller":"traceutil/trace.go:171","msg":"trace[1751414048] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:932; }","duration":"817.760132ms","start":"2026-02-26T03:08:35.718968Z","end":"2026-02-26T03:08:36.536728Z","steps":["trace[1751414048] 'agreement among raft nodes before linearized reading' (duration: 817.635179ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:08:36.536755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:35.718957Z","time spent":"817.792144ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T03:08:36.536825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.674111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-26T03:08:36.536853Z","caller":"traceutil/trace.go:171","msg":"trace[1990634389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:932; }","duration":"817.728722ms","start":"2026-02-26T03:08:35.719116Z","end":"2026-02-26T03:08:36.536845Z","steps":["trace[1990634389] 'agreement among raft nodes before linearized reading' (duration: 817.620149ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:08:36.536873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:35.719112Z","time spent":"817.756222ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-26T03:08:36.536937Z","caller":"traceutil/trace.go:171","msg":"trace[1672636918] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"459.494932ms","start":"2026-02-26T03:08:36.077408Z","end":"2026-02-26T03:08:36.536903Z","steps":["trace[1672636918] 'process raft request' (duration: 458.922348ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:08:36.53704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.263433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-26T03:08:36.537058Z","caller":"traceutil/trace.go:171","msg":"trace[468339737] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:932; }","duration":"810.295263ms","start":"2026-02-26T03:08:35.726758Z","end":"2026-02-26T03:08:36.537053Z","steps":["trace[468339737] 'agreement among raft nodes before linearized reading' (duration: 810.245662ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:08:36.53705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.781491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-26T03:08:36.537072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:35.726751Z","time spent":"810.317643ms","remote":"127.0.0.1:39976","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-02-26T03:08:36.537085Z","caller":"traceutil/trace.go:171","msg":"trace[690436127] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:932; }","duration":"809.874133ms","start":"2026-02-26T03:08:35.727202Z","end":"2026-02-26T03:08:36.537076Z","steps":["trace[690436127] 'agreement among raft nodes before linearized reading' (duration: 809.78281ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:08:36.537115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:35.727171Z","time spent":"809.936554ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-26T03:08:36.537109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:36.07737Z","time spent":"459.642826ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T03:08:48.773484Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708644692655691110,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T03:08:48.836064Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.078738851s","expected-duration":"1s"} {"level":"info","ts":"2026-02-26T03:08:48.836743Z","caller":"traceutil/trace.go:171","msg":"trace[361004008] linearizableReadLoop","detail":"{readStateIndex:1009; appliedIndex:1008; }","duration":"563.59161ms","start":"2026-02-26T03:08:48.273126Z","end":"2026-02-26T03:08:48.836718Z","steps":["trace[361004008] 'read index received' (duration: 563.311243ms)","trace[361004008] 'applied index is now lower than readState.Index' (duration: 279.607µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:08:48.837125Z","caller":"traceutil/trace.go:171","msg":"trace[1187535366] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"1.079885469s","start":"2026-02-26T03:08:47.757228Z","end":"2026-02-26T03:08:48.837113Z","steps":["trace[1187535366] 'process raft request' (duration: 1.079315995s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:08:48.837248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:47.757211Z","time spent":"1.07994384s","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T03:08:48.837445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.311449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T03:08:48.837469Z","caller":"traceutil/trace.go:171","msg":"trace[431964146] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:966; }","duration":"564.37564ms","start":"2026-02-26T03:08:48.273085Z","end":"2026-02-26T03:08:48.83746Z","steps":["trace[431964146] 'agreement among raft nodes before linearized reading' (duration: 564.326888ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:08:48.837486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:48.273064Z","time spent":"564.417981ms","remote":"127.0.0.1:39942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-02-26T03:08:48.837748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.194146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T03:08:48.837824Z","caller":"traceutil/trace.go:171","msg":"trace[1969117432] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:966; }","duration":"287.34069ms","start":"2026-02-26T03:08:48.550465Z","end":"2026-02-26T03:08:48.837805Z","steps":["trace[1969117432] 'agreement among raft nodes before linearized reading' (duration: 287.132005ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:08:48.837833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.197428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-26T03:08:48.837854Z","caller":"traceutil/trace.go:171","msg":"trace[2106566759] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:966; }","duration":"218.227568ms","start":"2026-02-26T03:08:48.619621Z","end":"2026-02-26T03:08:48.837848Z","steps":["trace[2106566759] 'agreement among raft nodes before linearized reading' (duration: 218.128446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:08:48.837961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.307157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-26T03:08:48.837979Z","caller":"traceutil/trace.go:171","msg":"trace[752043470] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:966; }","duration":"217.339987ms","start":"2026-02-26T03:08:48.620633Z","end":"2026-02-26T03:08:48.837973Z","steps":["trace[752043470] 'agreement among raft nodes before linearized reading' (duration: 217.290896ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:08:48.838214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.837396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-26T03:08:48.838247Z","caller":"traceutil/trace.go:171","msg":"trace[158251367] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:966; }","duration":"221.883847ms","start":"2026-02-26T03:08:48.616354Z","end":"2026-02-26T03:08:48.838238Z","steps":["trace[158251367] 'agreement among raft nodes before linearized reading' (duration: 221.719433ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:08:48.838292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.212324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-26T03:08:48.838314Z","caller":"traceutil/trace.go:171","msg":"trace[1787712768] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:966; }","duration":"229.287205ms","start":"2026-02-26T03:08:48.609019Z","end":"2026-02-26T03:08:48.838306Z","steps":["trace[1787712768] 'agreement among raft nodes before linearized reading' (duration: 229.231604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:08:53.302914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.530065ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T03:08:53.303012Z","caller":"traceutil/trace.go:171","msg":"trace[1445408810] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"578.046112ms","start":"2026-02-26T03:08:52.724947Z","end":"2026-02-26T03:08:53.302993Z","steps":["trace[1445408810] 'process raft request' (duration: 99.365534ms)","trace[1445408810] 'compare' (duration: 478.174105ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:08:53.303066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:52.724926Z","time spent":"578.115064ms","remote":"127.0.0.1:40118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":797,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T03:08:53.350689Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708644692655691161,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T03:08:53.774502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.692614ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T03:08:53.774591Z","caller":"traceutil/trace.go:171","msg":"trace[7024482] linearizableReadLoop","detail":"{readStateIndex:1037; appliedIndex:1036; }","duration":"924.160352ms","start":"2026-02-26T03:08:52.850419Z","end":"2026-02-26T03:08:53.774579Z","steps":["trace[7024482] 'read index received' (duration: 668.232463ms)","trace[7024482] 'applied index is now lower than readState.Index' (duration: 255.922859ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:08:53.774655Z","caller":"traceutil/trace.go:171","msg":"trace[212988478] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"1.046127702s","start":"2026-02-26T03:08:52.728519Z","end":"2026-02-26T03:08:53.774647Z","steps":["trace[212988478] 'process raft request' (duration: 790.208562ms)","trace[212988478] 'compare' (duration: 255.491451ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:08:53.774699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:52.72851Z","time spent":"1.046167464s","remote":"127.0.0.1:39918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11375,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T03:08:53.774783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.842971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-26T03:08:53.774835Z","caller":"traceutil/trace.go:171","msg":"trace[1257982980] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:993; }","duration":"903.932703ms","start":"2026-02-26T03:08:52.870891Z","end":"2026-02-26T03:08:53.774823Z","steps":["trace[1257982980] 'agreement among raft nodes before linearized reading' (duration: 903.8062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:08:53.774862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:52.870874Z","time spent":"903.982684ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T03:08:53.775012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"924.600793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-26T03:08:53.775041Z","caller":"traceutil/trace.go:171","msg":"trace[1507083450] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:993; }","duration":"924.699556ms","start":"2026-02-26T03:08:52.850333Z","end":"2026-02-26T03:08:53.775033Z","steps":["trace[1507083450] 'agreement among raft nodes before linearized reading' (duration: 924.638554ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:08:53.775058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:52.850295Z","time spent":"924.758847ms","remote":"127.0.0.1:39976","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-02-26T03:08:53.775102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"894.149416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-26T03:08:53.775134Z","caller":"traceutil/trace.go:171","msg":"trace[1359092513] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:993; }","duration":"894.196078ms","start":"2026-02-26T03:08:52.88093Z","end":"2026-02-26T03:08:53.775126Z","steps":["trace[1359092513] 'agreement among raft nodes before linearized reading' (duration: 894.084155ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:08:53.775159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:52.880924Z","time spent":"894.230199ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T03:08:53.77517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"898.243296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-26T03:08:53.775186Z","caller":"traceutil/trace.go:171","msg":"trace[670488890] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:993; }","duration":"898.279656ms","start":"2026-02-26T03:08:52.876901Z","end":"2026-02-26T03:08:53.775181Z","steps":["trace[670488890] 'agreement among raft nodes before linearized reading' (duration: 898.231615ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:08:53.775206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:52.876892Z","time spent":"898.310048ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-26T03:08:53.775276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.884953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T03:08:53.775298Z","caller":"traceutil/trace.go:171","msg":"trace[2006378045] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:993; }","duration":"693.936714ms","start":"2026-02-26T03:08:53.081356Z","end":"2026-02-26T03:08:53.775292Z","steps":["trace[2006378045] 'agreement among raft nodes before linearized reading' (duration: 693.900753ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:08:53.775316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:53.081341Z","time spent":"693.971855ms","remote":"127.0.0.1:39740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T03:08:53.775433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"894.143136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-26T03:08:53.775462Z","caller":"traceutil/trace.go:171","msg":"trace[568039658] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:993; }","duration":"894.183497ms","start":"2026-02-26T03:08:52.881271Z","end":"2026-02-26T03:08:53.775455Z","steps":["trace[568039658] 'agreement among raft nodes before linearized reading' (duration: 894.040293ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:08:53.775483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:52.881265Z","time spent":"894.214457ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-26T03:08:54.344401Z","caller":"traceutil/trace.go:171","msg":"trace[174951728] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"561.105182ms","start":"2026-02-26T03:08:53.783269Z","end":"2026-02-26T03:08:54.344374Z","steps":["trace[174951728] 'process raft request' (duration: 534.751644ms)","trace[174951728] 'compare' (duration: 25.99858ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:08:54.344535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:53.783251Z","time spent":"561.214325ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T03:08:54.474872Z","caller":"traceutil/trace.go:171","msg":"trace[1027544471] linearizableReadLoop","detail":"{readStateIndex:1045; appliedIndex:1037; }","duration":"392.975374ms","start":"2026-02-26T03:08:54.081875Z","end":"2026-02-26T03:08:54.47485Z","steps":["trace[1027544471] 'read index received' (duration: 236.156201ms)","trace[1027544471] 'applied index is now lower than readState.Index' (duration: 156.818333ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:08:54.47515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.24986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T03:08:54.47569Z","caller":"traceutil/trace.go:171","msg":"trace[1921559675] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1001; }","duration":"393.848535ms","start":"2026-02-26T03:08:54.081832Z","end":"2026-02-26T03:08:54.475681Z","steps":["trace[1921559675] 'agreement among raft nodes before linearized reading' (duration: 393.213539ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:08:54.477201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:54.081814Z","time spent":"395.361442ms","remote":"127.0.0.1:39740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T03:08:54.475252Z","caller":"traceutil/trace.go:171","msg":"trace[742894374] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"690.147172ms","start":"2026-02-26T03:08:53.785087Z","end":"2026-02-26T03:08:54.475234Z","steps":["trace[742894374] 'process raft request' (duration: 689.607369ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:08:54.475243Z","caller":"traceutil/trace.go:171","msg":"trace[672141037] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"689.742652ms","start":"2026-02-26T03:08:53.785469Z","end":"2026-02-26T03:08:54.475212Z","steps":["trace[672141037] 'process raft request' (duration: 689.300112ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:08:54.47741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:53.78507Z","time spent":"692.278763ms","remote":"127.0.0.1:39988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T03:08:54.475298Z","caller":"traceutil/trace.go:171","msg":"trace[896963789] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"685.717825ms","start":"2026-02-26T03:08:53.789571Z","end":"2026-02-26T03:08:54.475289Z","steps":["trace[896963789] 'process raft request' (duration: 685.240364ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:08:54.47534Z","caller":"traceutil/trace.go:171","msg":"trace[1383142716] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"691.831073ms","start":"2026-02-26T03:08:53.783501Z","end":"2026-02-26T03:08:54.475333Z","steps":["trace[1383142716] 'process raft request' (duration: 691.098465ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:08:54.475436Z","caller":"traceutil/trace.go:171","msg":"trace[1547732394] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"691.880775ms","start":"2026-02-26T03:08:53.783539Z","end":"2026-02-26T03:08:54.47542Z","steps":["trace[1547732394] 'process raft request' (duration: 691.096015ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:08:54.475492Z","caller":"traceutil/trace.go:171","msg":"trace[783274970] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"691.847114ms","start":"2026-02-26T03:08:53.783632Z","end":"2026-02-26T03:08:54.475479Z","steps":["trace[783274970] 'process raft request' (duration: 691.032644ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:08:54.475506Z","caller":"traceutil/trace.go:171","msg":"trace[294019287] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"692.024028ms","start":"2026-02-26T03:08:53.783471Z","end":"2026-02-26T03:08:54.475495Z","steps":["trace[294019287] 'process raft request' (duration: 663.618381ms)","trace[294019287] 'compare' (duration: 27.400573ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:08:54.477658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:53.789559Z","time spent":"688.067352ms","remote":"127.0.0.1:40212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T03:08:54.477723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:53.783463Z","time spent":"694.1918ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T03:08:54.477764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:53.783534Z","time spent":"694.159149ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T03:08:54.477896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:53.783627Z","time spent":"694.238202ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T03:08:54.477935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:53.783486Z","time spent":"694.404885ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T03:08:54.477482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:53.785462Z","time spent":"691.977486ms","remote":"127.0.0.1:39896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":915,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T03:08:55.187406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.698152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T03:08:55.187478Z","caller":"traceutil/trace.go:171","msg":"trace[1173718564] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1002; }","duration":"104.810675ms","start":"2026-02-26T03:08:55.082653Z","end":"2026-02-26T03:08:55.187463Z","steps":["trace[1173718564] 'range keys from in-memory index tree' (duration: 104.628251ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:08:55.508479Z","caller":"traceutil/trace.go:171","msg":"trace[399879021] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"135.138669ms","start":"2026-02-26T03:08:55.373299Z","end":"2026-02-26T03:08:55.508437Z","steps":["trace[399879021] 'process raft request' (duration: 55.33464ms)","trace[399879021] 'compare' (duration: 79.700317ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:08:56.098658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.297341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T03:08:56.098735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.688176ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T03:08:56.098815Z","caller":"traceutil/trace.go:171","msg":"trace[159854460] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1003; }","duration":"240.511737ms","start":"2026-02-26T03:08:55.858284Z","end":"2026-02-26T03:08:56.098796Z","steps":["trace[159854460] 'count revisions from in-memory index tree' (duration: 240.198279ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:08:56.098836Z","caller":"traceutil/trace.go:171","msg":"trace[786852605] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1003; }","duration":"184.804969ms","start":"2026-02-26T03:08:55.914011Z","end":"2026-02-26T03:08:56.098816Z","steps":["trace[786852605] 'range keys from in-memory index tree' (duration: 184.674346ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:08:56.688525Z","caller":"traceutil/trace.go:171","msg":"trace[226931611] linearizableReadLoop","detail":"{readStateIndex:1049; appliedIndex:1048; }","duration":"206.770902ms","start":"2026-02-26T03:08:56.481738Z","end":"2026-02-26T03:08:56.688509Z","steps":["trace[226931611] 'read index received' (duration: 206.583937ms)","trace[226931611] 'applied index is now lower than readState.Index' (duration: 186.405µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:08:56.688676Z","caller":"traceutil/trace.go:171","msg":"trace[1838244456] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"335.006793ms","start":"2026-02-26T03:08:56.353606Z","end":"2026-02-26T03:08:56.688613Z","steps":["trace[1838244456] 'process raft request' (duration: 334.778858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:08:56.688783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.034557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-26T03:08:56.688798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.972396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-26T03:08:56.688819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:56.353582Z","time spent":"335.155175ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T03:08:56.688851Z","caller":"traceutil/trace.go:171","msg":"trace[1640000166] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1004; }","duration":"207.14046ms","start":"2026-02-26T03:08:56.48169Z","end":"2026-02-26T03:08:56.68883Z","steps":["trace[1640000166] 'agreement among raft nodes before linearized reading' (duration: 206.913055ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:08:56.688886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.654709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-26T03:08:56.688865Z","caller":"traceutil/trace.go:171","msg":"trace[1100841585] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1004; }","duration":"207.093339ms","start":"2026-02-26T03:08:56.481757Z","end":"2026-02-26T03:08:56.68885Z","steps":["trace[1100841585] 'agreement among raft nodes before linearized reading' (duration: 206.884715ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:08:56.689061Z","caller":"traceutil/trace.go:171","msg":"trace[421180680] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1004; }","duration":"204.822273ms","start":"2026-02-26T03:08:56.484137Z","end":"2026-02-26T03:08:56.688959Z","steps":["trace[421180680] 'agreement among raft nodes before linearized reading' (duration: 204.554167ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:08:56.689014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.876834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-26T03:08:56.68926Z","caller":"traceutil/trace.go:171","msg":"trace[1010709753] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1004; }","duration":"205.164112ms","start":"2026-02-26T03:08:56.484081Z","end":"2026-02-26T03:08:56.689246Z","steps":["trace[1010709753] 'agreement among raft nodes before linearized reading' (duration: 204.63167ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:08:57.297995Z","caller":"traceutil/trace.go:171","msg":"trace[1145248771] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"605.597887ms","start":"2026-02-26T03:08:56.692374Z","end":"2026-02-26T03:08:57.297972Z","steps":["trace[1145248771] 'process raft request' (duration: 583.900573ms)","trace[1145248771] 'compare' (duration: 21.458239ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:08:57.298109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:56.692354Z","time spent":"605.69302ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T03:08:57.346619Z","caller":"traceutil/trace.go:171","msg":"trace[791731006] linearizableReadLoop","detail":"{readStateIndex:1053; appliedIndex:1049; }","duration":"265.612525ms","start":"2026-02-26T03:08:57.080988Z","end":"2026-02-26T03:08:57.3466Z","steps":["trace[791731006] 'read index received' (duration: 195.369066ms)","trace[791731006] 'applied index is now lower than readState.Index' (duration: 70.242839ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:08:57.346715Z","caller":"traceutil/trace.go:171","msg":"trace[1300842274] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"256.512363ms","start":"2026-02-26T03:08:57.090175Z","end":"2026-02-26T03:08:57.346688Z","steps":["trace[1300842274] 'process raft request' (duration: 256.34341ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:08:57.346736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.742837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T03:08:57.346842Z","caller":"traceutil/trace.go:171","msg":"trace[164112627] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1009; }","duration":"265.902811ms","start":"2026-02-26T03:08:57.080932Z","end":"2026-02-26T03:08:57.346835Z","steps":["trace[164112627] 'agreement among raft nodes before linearized reading' (duration: 265.766228ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:08:57.346859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.362333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T03:08:57.34697Z","caller":"traceutil/trace.go:171","msg":"trace[479737293] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1009; }","duration":"111.488566ms","start":"2026-02-26T03:08:57.235453Z","end":"2026-02-26T03:08:57.346941Z","steps":["trace[479737293] 'agreement among raft nodes before linearized reading' (duration: 111.302412ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:08:57.346912Z","caller":"traceutil/trace.go:171","msg":"trace[1940612067] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"653.294771ms","start":"2026-02-26T03:08:56.693609Z","end":"2026-02-26T03:08:57.346904Z","steps":["trace[1940612067] 'process raft request' (duration: 652.883911ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:08:57.347219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:56.6936Z","time spent":"653.535636ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T03:08:57.34665Z","caller":"traceutil/trace.go:171","msg":"trace[767068450] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"653.564649ms","start":"2026-02-26T03:08:56.693052Z","end":"2026-02-26T03:08:57.346617Z","steps":["trace[767068450] 'process raft request' (duration: 653.418265ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:08:57.347724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:56.693044Z","time spent":"654.628953ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T03:08:57.348003Z","caller":"traceutil/trace.go:171","msg":"trace[15216717] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"655.372232ms","start":"2026-02-26T03:08:56.692606Z","end":"2026-02-26T03:08:57.347978Z","steps":["trace[15216717] 'process raft request' (duration: 653.767534ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:08:57.348132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:56.692597Z","time spent":"655.477684ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T03:08:57.969157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.446365ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-26T03:08:57.969245Z","caller":"traceutil/trace.go:171","msg":"trace[1746907280] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"231.415657ms","start":"2026-02-26T03:08:57.737813Z","end":"2026-02-26T03:08:57.969229Z","steps":["trace[1746907280] 'process raft request' (duration: 33.840609ms)","trace[1746907280] 'compare' (duration: 197.319182ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:08:59.018168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"884.218777ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T03:08:59.01834Z","caller":"traceutil/trace.go:171","msg":"trace[1616833743] linearizableReadLoop","detail":"{readStateIndex:1059; appliedIndex:1057; }","duration":"936.289615ms","start":"2026-02-26T03:08:58.082035Z","end":"2026-02-26T03:08:59.018325Z","steps":["trace[1616833743] 'read index received' (duration: 51.854594ms)","trace[1616833743] 'applied index is now lower than readState.Index' (duration: 884.434131ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:08:59.01842Z","caller":"traceutil/trace.go:171","msg":"trace[261610684] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"1.12747774s","start":"2026-02-26T03:08:57.890932Z","end":"2026-02-26T03:08:59.01841Z","steps":["trace[261610684] 'process raft request' (duration: 1.127325667s)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:08:59.01841Z","caller":"traceutil/trace.go:171","msg":"trace[129895842] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"1.277271232s","start":"2026-02-26T03:08:57.7411Z","end":"2026-02-26T03:08:59.018371Z","steps":["trace[129895842] 'process raft request' (duration: 392.776559ms)","trace[129895842] 'compare' (duration: 883.832838ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:08:59.018472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:57.89091Z","time spent":"1.127532341s","remote":"127.0.0.1:39786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T03:08:59.018553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:57.741086Z","time spent":"1.277393015s","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T03:08:59.018642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.323019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-26T03:08:59.018756Z","caller":"traceutil/trace.go:171","msg":"trace[440757598] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1014; }","duration":"325.474243ms","start":"2026-02-26T03:08:58.693267Z","end":"2026-02-26T03:08:59.018741Z","steps":["trace[440757598] 'agreement among raft nodes before linearized reading' (duration: 325.192866ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:08:59.018795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:58.693248Z","time spent":"325.535775ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T03:08:59.018822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"936.793448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T03:08:59.018856Z","caller":"traceutil/trace.go:171","msg":"trace[1580933900] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1014; }","duration":"936.9015ms","start":"2026-02-26T03:08:58.081944Z","end":"2026-02-26T03:08:59.018845Z","steps":["trace[1580933900] 'agreement among raft nodes before linearized reading' (duration: 936.846189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:08:59.018884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:58.081927Z","time spent":"936.951002ms","remote":"127.0.0.1:39740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T03:08:59.257962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.947092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T03:08:59.258032Z","caller":"traceutil/trace.go:171","msg":"trace[48272733] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1014; }","duration":"106.032214ms","start":"2026-02-26T03:08:59.151986Z","end":"2026-02-26T03:08:59.258018Z","steps":["trace[48272733] 'range keys from in-memory index tree' (duration: 105.883021ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:08:59.258053Z","caller":"traceutil/trace.go:171","msg":"trace[502624427] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"103.647207ms","start":"2026-02-26T03:08:59.154389Z","end":"2026-02-26T03:08:59.258036Z","steps":["trace[502624427] 'process raft request' (duration: 103.594876ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:08:59.258164Z","caller":"traceutil/trace.go:171","msg":"trace[846705865] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"104.390286ms","start":"2026-02-26T03:08:59.153724Z","end":"2026-02-26T03:08:59.258114Z","steps":["trace[846705865] 'process raft request' (duration: 35.771555ms)","trace[846705865] 'compare' (duration: 68.382485ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:09:00.239485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.542844ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T03:09:00.23955Z","caller":"traceutil/trace.go:171","msg":"trace[1165681634] linearizableReadLoop","detail":"{readStateIndex:1062; appliedIndex:1061; }","duration":"886.229724ms","start":"2026-02-26T03:08:59.353311Z","end":"2026-02-26T03:09:00.239541Z","steps":["trace[1165681634] 'read index received' (duration: 8.452605ms)","trace[1165681634] 'applied index is now lower than readState.Index' (duration: 877.776329ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:09:00.239728Z","caller":"traceutil/trace.go:171","msg":"trace[532223742] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"935.324752ms","start":"2026-02-26T03:08:59.304396Z","end":"2026-02-26T03:09:00.239721Z","steps":["trace[532223742] 'process raft request' (duration: 57.487622ms)","trace[532223742] 'compare' (duration: 877.41284ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:09:00.239766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:59.304379Z","time spent":"935.368583ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T03:09:00.239803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.49271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-26T03:09:00.239856Z","caller":"traceutil/trace.go:171","msg":"trace[1599522001] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1017; }","duration":"886.601113ms","start":"2026-02-26T03:08:59.353245Z","end":"2026-02-26T03:09:00.239846Z","steps":["trace[1599522001] 'agreement among raft nodes before linearized reading' (duration: 886.46405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:09:00.239877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:59.353222Z","time spent":"886.650464ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-26T03:09:00.239888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"885.964977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-26T03:09:00.239962Z","caller":"traceutil/trace.go:171","msg":"trace[1251286784] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1017; }","duration":"886.067009ms","start":"2026-02-26T03:08:59.353875Z","end":"2026-02-26T03:09:00.239942Z","steps":["trace[1251286784] 'agreement among raft nodes before linearized reading' (duration: 885.874685ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:09:00.239987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.675154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-26T03:09:00.240006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:59.353858Z","time spent":"886.139041ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-26T03:09:00.240006Z","caller":"traceutil/trace.go:171","msg":"trace[535380814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1017; }","duration":"886.719726ms","start":"2026-02-26T03:08:59.35328Z","end":"2026-02-26T03:09:00.24Z","steps":["trace[535380814] 'agreement among raft nodes before linearized reading' (duration: 886.633914ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:09:00.240037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:08:59.353265Z","time spent":"886.768717ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T03:09:00.677108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.213193ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T03:09:00.677375Z","caller":"traceutil/trace.go:171","msg":"trace[133662640] linearizableReadLoop","detail":"{readStateIndex:1065; appliedIndex:1062; }","duration":"429.504689ms","start":"2026-02-26T03:09:00.247858Z","end":"2026-02-26T03:09:00.677362Z","steps":["trace[133662640] 'read index received' (duration: 297.994758ms)","trace[133662640] 'applied index is now lower than readState.Index' (duration: 131.509211ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:09:00.677438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.578141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T03:09:00.677453Z","caller":"traceutil/trace.go:171","msg":"trace[2099004096] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1021; }","duration":"429.611023ms","start":"2026-02-26T03:09:00.247837Z","end":"2026-02-26T03:09:00.677448Z","steps":["trace[2099004096] 'agreement among raft nodes before linearized reading' (duration: 429.559361ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:09:00.677484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:09:00.247828Z","time spent":"429.640403ms","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T03:09:00.677624Z","caller":"traceutil/trace.go:171","msg":"trace[1834636984] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"435.057594ms","start":"2026-02-26T03:09:00.242558Z","end":"2026-02-26T03:09:00.677616Z","steps":["trace[1834636984] 'process raft request' (duration: 303.269076ms)","trace[1834636984] 'compare' (duration: 131.099231ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:09:00.67766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:09:00.242545Z","time spent":"435.094735ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T03:09:00.677822Z","caller":"traceutil/trace.go:171","msg":"trace[147509378] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"435.178897ms","start":"2026-02-26T03:09:00.242637Z","end":"2026-02-26T03:09:00.677816Z","steps":["trace[147509378] 'process raft request' (duration: 434.555271ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:09:00.67786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:09:00.242618Z","time spent":"435.224488ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T03:09:00.677963Z","caller":"traceutil/trace.go:171","msg":"trace[525061404] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"434.818968ms","start":"2026-02-26T03:09:00.243115Z","end":"2026-02-26T03:09:00.677934Z","steps":["trace[525061404] 'process raft request' (duration: 434.186693ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:09:00.678113Z","caller":"traceutil/trace.go:171","msg":"trace[14683517] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"427.358487ms","start":"2026-02-26T03:09:00.250748Z","end":"2026-02-26T03:09:00.678106Z","steps":["trace[14683517] 'process raft request' (duration: 426.589439ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:09:00.678148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:09:00.25074Z","time spent":"427.390157ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T03:09:00.678106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:09:00.243104Z","time spent":"434.925921ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T03:09:10.169831Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708644692655691344,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-26T03:09:10.532885Z","caller":"traceutil/trace.go:171","msg":"trace[1712812453] linearizableReadLoop","detail":"{readStateIndex:1131; appliedIndex:1130; }","duration":"863.390382ms","start":"2026-02-26T03:09:09.66947Z","end":"2026-02-26T03:09:10.53286Z","steps":["trace[1712812453] 'read index received' (duration: 863.136756ms)","trace[1712812453] 'applied index is now lower than readState.Index' (duration: 252.576µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:09:10.532986Z","caller":"traceutil/trace.go:171","msg":"trace[1231306883] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"893.786117ms","start":"2026-02-26T03:09:09.639176Z","end":"2026-02-26T03:09:10.532962Z","steps":["trace[1231306883] 'process raft request' (duration: 893.5079ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:09:10.533034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.534796ms","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-02-26T03:09:10.533069Z","caller":"traceutil/trace.go:171","msg":"trace[139416613] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1084; }","duration":"863.620978ms","start":"2026-02-26T03:09:09.669436Z","end":"2026-02-26T03:09:10.533057Z","steps":["trace[139416613] 'agreement among raft nodes before linearized reading' (duration: 863.537906ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:09:10.533102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:09:09.669421Z","time spent":"863.671999ms","remote":"127.0.0.1:35106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":3,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-26T03:09:10.533371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.54871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T03:09:10.533456Z","caller":"traceutil/trace.go:171","msg":"trace[1490942543] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1084; }","duration":"422.657403ms","start":"2026-02-26T03:09:10.110755Z","end":"2026-02-26T03:09:10.533412Z","steps":["trace[1490942543] 'agreement among raft nodes before linearized reading' (duration: 422.468708ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:09:10.533469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.447785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-26T03:09:10.533533Z","caller":"traceutil/trace.go:171","msg":"trace[530604813] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1084; }","duration":"254.544626ms","start":"2026-02-26T03:09:10.278969Z","end":"2026-02-26T03:09:10.533513Z","steps":["trace[530604813] 'agreement among raft nodes before linearized reading' (duration: 254.326811ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:09:10.533575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:09:10.11074Z","time spent":"422.766075ms","remote":"127.0.0.1:39740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T03:09:10.569785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:09:09.639151Z","time spent":"893.88243ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T03:09:10.96736Z","caller":"traceutil/trace.go:171","msg":"trace[925107714] linearizableReadLoop","detail":"{readStateIndex:1132; appliedIndex:1131; }","duration":"245.18609ms","start":"2026-02-26T03:09:10.722157Z","end":"2026-02-26T03:09:10.967343Z","steps":["trace[925107714] 'read index received' (duration: 243.020228ms)","trace[925107714] 'applied index is now lower than readState.Index' (duration: 2.165222ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T03:09:10.967402Z","caller":"traceutil/trace.go:171","msg":"trace[2126318691] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"394.839161ms","start":"2026-02-26T03:09:10.572543Z","end":"2026-02-26T03:09:10.967382Z","steps":["trace[2126318691] 'process raft request' (duration: 392.623308ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:09:10.967496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:09:10.572533Z","time spent":"394.910502ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T03:09:10.967539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.366594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-26T03:09:10.967566Z","caller":"traceutil/trace.go:171","msg":"trace[1098062580] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1085; }","duration":"245.449416ms","start":"2026-02-26T03:09:10.722107Z","end":"2026-02-26T03:09:10.967557Z","steps":["trace[1098062580] 'agreement among raft nodes before linearized reading' (duration: 245.322383ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:09:10.969226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.83251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-26T03:09:10.969241Z","caller":"traceutil/trace.go:171","msg":"trace[1840451427] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"214.094519ms","start":"2026-02-26T03:09:10.755109Z","end":"2026-02-26T03:09:10.969204Z","steps":["trace[1840451427] 'process raft request' (duration: 213.918704ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:09:10.969345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.72214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-26T03:09:10.969387Z","caller":"traceutil/trace.go:171","msg":"trace[434618416] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1086; }","duration":"242.791712ms","start":"2026-02-26T03:09:10.726587Z","end":"2026-02-26T03:09:10.969379Z","steps":["trace[434618416] 'agreement among raft nodes before linearized reading' (duration: 242.6968ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:09:10.969287Z","caller":"traceutil/trace.go:171","msg":"trace[1232903858] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1086; }","duration":"246.915121ms","start":"2026-02-26T03:09:10.722356Z","end":"2026-02-26T03:09:10.969271Z","steps":["trace[1232903858] 'agreement among raft nodes before linearized reading' (duration: 246.768459ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:09:10.969278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.392676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-26T03:09:10.969461Z","caller":"traceutil/trace.go:171","msg":"trace[1246299742] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1086; }","duration":"231.621872ms","start":"2026-02-26T03:09:10.737833Z","end":"2026-02-26T03:09:10.969455Z","steps":["trace[1246299742] 'agreement among raft nodes before linearized reading' (duration: 231.371897ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:10:19.540952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.624487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-26T03:10:19.541039Z","caller":"traceutil/trace.go:171","msg":"trace[306425999] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1364; }","duration":"211.7421ms","start":"2026-02-26T03:10:19.32928Z","end":"2026-02-26T03:10:19.541022Z","steps":["trace[306425999] 'range keys from in-memory index tree' (duration: 211.491174ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:10:19.541136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.704165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-26T03:10:19.541168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.116289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-26T03:10:19.541059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.618953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-26T03:10:19.541218Z","caller":"traceutil/trace.go:171","msg":"trace[502721324] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1364; }","duration":"222.841979ms","start":"2026-02-26T03:10:19.318352Z","end":"2026-02-26T03:10:19.541194Z","steps":["trace[502721324] 'range keys from in-memory index tree' (duration: 222.48463ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:10:19.541225Z","caller":"traceutil/trace.go:171","msg":"trace[1243303622] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1364; }","duration":"224.338544ms","start":"2026-02-26T03:10:19.31687Z","end":"2026-02-26T03:10:19.541209Z","steps":["trace[1243303622] 'range keys from in-memory index tree' (duration: 223.974655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:10:19.54126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.507419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-02-26T03:10:19.541255Z","caller":"traceutil/trace.go:171","msg":"trace[1062537735] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1364; }","duration":"222.88199ms","start":"2026-02-26T03:10:19.318358Z","end":"2026-02-26T03:10:19.54124Z","steps":["trace[1062537735] 'range keys from in-memory index tree' (duration: 222.444149ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T03:10:19.541301Z","caller":"traceutil/trace.go:171","msg":"trace[2133285232] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1364; }","duration":"224.588771ms","start":"2026-02-26T03:10:19.3167Z","end":"2026-02-26T03:10:19.541289Z","steps":["trace[2133285232] 'range keys from in-memory index tree' (duration: 224.369865ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:10:19.541431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.641882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-26T03:10:19.541469Z","caller":"traceutil/trace.go:171","msg":"trace[1301581178] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1364; }","duration":"272.713453ms","start":"2026-02-26T03:10:19.268746Z","end":"2026-02-26T03:10:19.54146Z","steps":["trace[1301581178] 'range keys from in-memory index tree' (duration: 272.528649ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T03:12:25.90311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.571983ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T03:12:25.903489Z","caller":"traceutil/trace.go:171","msg":"trace[1703691449] transaction","detail":"{read_only:false; response_revision:1913; number_of_response:1; }","duration":"527.463501ms","start":"2026-02-26T03:12:25.376Z","end":"2026-02-26T03:12:25.903463Z","steps":["trace[1703691449] 'process raft request' (duration: 40.470118ms)","trace[1703691449] 'compare' (duration: 486.118722ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T03:12:25.903568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T03:12:25.37598Z","time spent":"527.548403ms","remote":"127.0.0.1:39758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}