{"level":"warn","ts":"2026-02-05T00:44:39.600077Z","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-05T00:44:39.600337Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.212: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.212:2380","--initial-cluster=instance=https://162.253.55.212:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.212:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.212: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-05T00:44:39.600461Z","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-05T00:44:39.600542Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.212:2380"]} {"level":"info","ts":"2026-02-05T00:44:39.60061Z","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-05T00:44:39.601252Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"]} {"level":"info","ts":"2026-02-05T00:44:39.60141Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.212:2380"],"listen-peer-urls":["https://162.253.55.212:2380"],"advertise-client-urls":["https://162.253.55.212:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.212: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-05T00:44:39.619555Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"17.824129ms"} {"level":"info","ts":"2026-02-05T00:44:39.629625Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d078b1e39c21939c","cluster-id":"cdc9ba8de57e3589"} {"level":"info","ts":"2026-02-05T00:44:39.629777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=()"} {"level":"info","ts":"2026-02-05T00:44:39.629859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became follower at term 0"} {"level":"info","ts":"2026-02-05T00:44:39.629945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d078b1e39c21939c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-05T00:44:39.629995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became follower at term 1"} {"level":"info","ts":"2026-02-05T00:44:39.630073Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=(15021952148229428124)"} {"level":"warn","ts":"2026-02-05T00:44:39.639225Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-05T00:44:39.656196Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-05T00:44:39.660964Z","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-05T00:44:39.665825Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d078b1e39c21939c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-05T00:44:39.674048Z","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-05T00:44:39.674179Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d078b1e39c21939c","initial-advertise-peer-urls":["https://162.253.55.212:2380"],"listen-peer-urls":["https://162.253.55.212:2380"],"advertise-client-urls":["https://162.253.55.212:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-05T00:44:39.674203Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-05T00:44:39.674247Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.212:2380"} {"level":"info","ts":"2026-02-05T00:44:39.674252Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.212:2380"} {"level":"info","ts":"2026-02-05T00:44:39.677709Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d078b1e39c21939c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-05T00:44:39.678477Z","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-05T00:44:39.678498Z","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-05T00:44:39.678507Z","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-05T00:44:39.680422Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=(15021952148229428124)"} {"level":"info","ts":"2026-02-05T00:44:39.680569Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cdc9ba8de57e3589","local-member-id":"d078b1e39c21939c","added-peer-id":"d078b1e39c21939c","added-peer-peer-urls":["https://162.253.55.212:2380"]} {"level":"info","ts":"2026-02-05T00:44:39.830435Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c is starting a new election at term 1"} {"level":"info","ts":"2026-02-05T00:44:39.830535Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became pre-candidate at term 1"} {"level":"info","ts":"2026-02-05T00:44:39.830606Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c received MsgPreVoteResp from d078b1e39c21939c at term 1"} {"level":"info","ts":"2026-02-05T00:44:39.830657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became candidate at term 2"} {"level":"info","ts":"2026-02-05T00:44:39.830707Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c received MsgVoteResp from d078b1e39c21939c at term 2"} {"level":"info","ts":"2026-02-05T00:44:39.830749Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became leader at term 2"} {"level":"info","ts":"2026-02-05T00:44:39.830786Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d078b1e39c21939c elected leader d078b1e39c21939c at term 2"} {"level":"info","ts":"2026-02-05T00:44:39.832162Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T00:44:39.833324Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d078b1e39c21939c","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.212:2379]}","request-path":"/0/members/d078b1e39c21939c/attributes","cluster-id":"cdc9ba8de57e3589","publish-timeout":"7s"} {"level":"info","ts":"2026-02-05T00:44:39.833511Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T00:44:39.833741Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cdc9ba8de57e3589","local-member-id":"d078b1e39c21939c","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T00:44:39.833824Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T00:44:39.833864Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T00:44:39.833892Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T00:44:39.840517Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.212:2379"} {"level":"info","ts":"2026-02-05T00:44:39.840735Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-05T00:44:39.84077Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-05T00:44:39.848845Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-05T00:44:58.010849Z","caller":"traceutil/trace.go:171","msg":"trace[1651804831] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"114.442238ms","start":"2026-02-05T00:44:57.896186Z","end":"2026-02-05T00:44:58.010629Z","steps":["trace[1651804831] 'process raft request' (duration: 61.917782ms)","trace[1651804831] 'compare' (duration: 52.434194ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T00:44:59.456169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.592593ms","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-05T00:44:59.456302Z","caller":"traceutil/trace.go:171","msg":"trace[709384406] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:406; }","duration":"630.774138ms","start":"2026-02-05T00:44:58.825509Z","end":"2026-02-05T00:44:59.456283Z","steps":["trace[709384406] 'range keys from in-memory index tree' (duration: 630.574652ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:44:59.457246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.003492627s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T00:44:59.457315Z","caller":"traceutil/trace.go:171","msg":"trace[762093432] linearizableReadLoop","detail":"{readStateIndex:417; appliedIndex:416; }","duration":"1.157083245s","start":"2026-02-05T00:44:58.300221Z","end":"2026-02-05T00:44:59.457304Z","steps":["trace[762093432] 'read index received' (duration: 152.881461ms)","trace[762093432] 'applied index is now lower than readState.Index' (duration: 1.004200774s)"],"step_count":2} {"level":"warn","ts":"2026-02-05T00:44:59.463597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.163357269s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-05T00:44:59.463652Z","caller":"traceutil/trace.go:171","msg":"trace[1248010866] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:407; }","duration":"1.163451151s","start":"2026-02-05T00:44:58.300187Z","end":"2026-02-05T00:44:59.463638Z","steps":["trace[1248010866] 'agreement among raft nodes before linearized reading' (duration: 1.163313128s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:44:59.463679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:44:58.300171Z","time spent":"1.163503002s","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-05T00:44:59.463761Z","caller":"traceutil/trace.go:171","msg":"trace[1774074694] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"1.413133876s","start":"2026-02-05T00:44:58.05062Z","end":"2026-02-05T00:44:59.463754Z","steps":["trace[1774074694] 'process raft request' (duration: 402.511585ms)","trace[1774074694] 'compare' (duration: 1.003382755s)"],"step_count":2} {"level":"warn","ts":"2026-02-05T00:44:59.463809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:44:58.050596Z","time spent":"1.413176067s","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T00:44:59.529918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.215853805s","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-05T00:44:59.529973Z","caller":"traceutil/trace.go:171","msg":"trace[878828222] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:407; }","duration":"1.215942607s","start":"2026-02-05T00:44:58.314018Z","end":"2026-02-05T00:44:59.529961Z","steps":["trace[878828222] 'agreement among raft nodes before linearized reading' (duration: 1.215824554s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:44:59.530012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:44:58.314004Z","time spent":"1.216002939s","remote":"127.0.0.1:40426","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-05T00:44:59.530137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"939.786574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T00:44:59.530162Z","caller":"traceutil/trace.go:171","msg":"trace[1455133370] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:408; }","duration":"939.835465ms","start":"2026-02-05T00:44:58.59032Z","end":"2026-02-05T00:44:59.530156Z","steps":["trace[1455133370] 'agreement among raft nodes before linearized reading' (duration: 939.763243ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:44:59.530177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:44:58.590305Z","time spent":"939.869076ms","remote":"127.0.0.1:40152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T00:45:09.789526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.969148ms","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-05T00:45:09.789586Z","caller":"traceutil/trace.go:171","msg":"trace[997133217] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:466; }","duration":"293.070191ms","start":"2026-02-05T00:45:09.496505Z","end":"2026-02-05T00:45:09.789575Z","steps":["trace[997133217] 'range keys from in-memory index tree' (duration: 292.912188ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:45:09.789777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.786944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T00:45:09.78979Z","caller":"traceutil/trace.go:171","msg":"trace[828465551] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:466; }","duration":"457.834595ms","start":"2026-02-05T00:45:09.331951Z","end":"2026-02-05T00:45:09.789786Z","steps":["trace[828465551] 'range keys from in-memory index tree' (duration: 457.747343ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:45:09.789804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:45:09.331937Z","time spent":"457.862026ms","remote":"127.0.0.1:40154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T00:45:09.789994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.429131ms","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-05T00:45:09.790008Z","caller":"traceutil/trace.go:171","msg":"trace[1638303764] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:466; }","duration":"186.482673ms","start":"2026-02-05T00:45:09.603521Z","end":"2026-02-05T00:45:09.790004Z","steps":["trace[1638303764] 'range keys from in-memory index tree' (duration: 186.288888ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:45:09.790124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.69399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-05T00:45:09.790142Z","caller":"traceutil/trace.go:171","msg":"trace[540172427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:466; }","duration":"150.760953ms","start":"2026-02-05T00:45:09.639376Z","end":"2026-02-05T00:45:09.790137Z","steps":["trace[540172427] 'range keys from in-memory index tree' (duration: 149.878242ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:45:12.017814Z","caller":"traceutil/trace.go:171","msg":"trace[526182942] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"121.08565ms","start":"2026-02-05T00:45:11.896702Z","end":"2026-02-05T00:45:12.017788Z","steps":["trace[526182942] 'process raft request' (duration: 41.384931ms)","trace[526182942] 'compare' (duration: 79.605557ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T00:45:12.034446Z","caller":"traceutil/trace.go:171","msg":"trace[409938806] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"135.127633ms","start":"2026-02-05T00:45:11.899302Z","end":"2026-02-05T00:45:12.034429Z","steps":["trace[409938806] 'process raft request' (duration: 135.064621ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:45:13.263494Z","caller":"traceutil/trace.go:171","msg":"trace[522833388] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"108.657455ms","start":"2026-02-05T00:45:13.154821Z","end":"2026-02-05T00:45:13.263478Z","steps":["trace[522833388] 'process raft request' (duration: 108.552263ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:45:20.15301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.094346ms","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-05T00:45:20.153081Z","caller":"traceutil/trace.go:171","msg":"trace[487135027] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:503; }","duration":"227.216769ms","start":"2026-02-05T00:45:19.925847Z","end":"2026-02-05T00:45:20.153063Z","steps":["trace[487135027] 'range keys from in-memory index tree' (duration: 227.028544ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:45:20.153231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.716048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-05T00:45:20.153258Z","caller":"traceutil/trace.go:171","msg":"trace[131974661] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:503; }","duration":"149.764909ms","start":"2026-02-05T00:45:20.003485Z","end":"2026-02-05T00:45:20.15325Z","steps":["trace[131974661] 'range keys from in-memory index tree' (duration: 149.617066ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:45:20.153419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.422648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-05T00:45:20.153445Z","caller":"traceutil/trace.go:171","msg":"trace[299211664] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:503; }","duration":"114.448358ms","start":"2026-02-05T00:45:20.038988Z","end":"2026-02-05T00:45:20.153437Z","steps":["trace[299211664] 'range keys from in-memory index tree' (duration: 114.345336ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:45:22.716489Z","caller":"traceutil/trace.go:171","msg":"trace[1481077014] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:572; }","duration":"116.559396ms","start":"2026-02-05T00:45:22.59991Z","end":"2026-02-05T00:45:22.71647Z","steps":["trace[1481077014] 'read index received' (duration: 116.29649ms)","trace[1481077014] 'applied index is now lower than readState.Index' (duration: 261.996µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T00:45:22.716717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.784461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-02-05T00:45:22.716745Z","caller":"traceutil/trace.go:171","msg":"trace[1703341277] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:557; }","duration":"116.872103ms","start":"2026-02-05T00:45:22.599866Z","end":"2026-02-05T00:45:22.716738Z","steps":["trace[1703341277] 'agreement among raft nodes before linearized reading' (duration: 116.690719ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:45:22.717013Z","caller":"traceutil/trace.go:171","msg":"trace[47749973] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"165.06265ms","start":"2026-02-05T00:45:22.55194Z","end":"2026-02-05T00:45:22.717003Z","steps":["trace[47749973] 'process raft request' (duration: 164.333213ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:45:32.326974Z","caller":"traceutil/trace.go:171","msg":"trace[912028933] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"102.75771ms","start":"2026-02-05T00:45:32.224194Z","end":"2026-02-05T00:45:32.326952Z","steps":["trace[912028933] 'process raft request' (duration: 102.404792ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:45:32.539499Z","caller":"traceutil/trace.go:171","msg":"trace[1099912483] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"206.316088ms","start":"2026-02-05T00:45:32.333167Z","end":"2026-02-05T00:45:32.539483Z","steps":["trace[1099912483] 'process raft request' (duration: 206.228596ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:45:32.578889Z","caller":"traceutil/trace.go:171","msg":"trace[329002670] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"243.841181ms","start":"2026-02-05T00:45:32.335031Z","end":"2026-02-05T00:45:32.578872Z","steps":["trace[329002670] 'process raft request' (duration: 243.336589ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:45:32.579002Z","caller":"traceutil/trace.go:171","msg":"trace[688706125] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"243.956754ms","start":"2026-02-05T00:45:32.335038Z","end":"2026-02-05T00:45:32.578995Z","steps":["trace[688706125] 'process raft request' (duration: 243.443852ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:45:32.579078Z","caller":"traceutil/trace.go:171","msg":"trace[2061803233] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"242.726235ms","start":"2026-02-05T00:45:32.336346Z","end":"2026-02-05T00:45:32.579072Z","steps":["trace[2061803233] 'process raft request' (duration: 242.159022ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:45:44.731257Z","caller":"traceutil/trace.go:171","msg":"trace[1618956427] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"149.685807ms","start":"2026-02-05T00:45:44.581561Z","end":"2026-02-05T00:45:44.731247Z","steps":["trace[1618956427] 'process raft request' (duration: 149.414801ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:45:44.731963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.021907ms","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-05T00:45:44.731995Z","caller":"traceutil/trace.go:171","msg":"trace[860624877] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:704; }","duration":"102.112579ms","start":"2026-02-05T00:45:44.629871Z","end":"2026-02-05T00:45:44.731984Z","steps":["trace[860624877] 'agreement among raft nodes before linearized reading' (duration: 102.006467ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:45:44.731102Z","caller":"traceutil/trace.go:171","msg":"trace[2119933423] linearizableReadLoop","detail":"{readStateIndex:726; appliedIndex:725; }","duration":"101.147817ms","start":"2026-02-05T00:45:44.629937Z","end":"2026-02-05T00:45:44.731085Z","steps":["trace[2119933423] 'read index received' (duration: 100.976103ms)","trace[2119933423] 'applied index is now lower than readState.Index' (duration: 171.094µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T00:45:45.380542Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548129549145057,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T00:45:45.629452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.74926ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T00:45:45.629506Z","caller":"traceutil/trace.go:171","msg":"trace[725175506] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"893.605264ms","start":"2026-02-05T00:45:44.735891Z","end":"2026-02-05T00:45:45.629497Z","steps":["trace[725175506] 'process raft request' (duration: 69.753031ms)","trace[725175506] 'compare' (duration: 823.565806ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T00:45:45.629535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:45:44.735877Z","time spent":"893.644475ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T00:45:45.65917Z","caller":"traceutil/trace.go:171","msg":"trace[2030406547] linearizableReadLoop","detail":"{readStateIndex:729; appliedIndex:727; }","duration":"778.905244ms","start":"2026-02-05T00:45:44.880247Z","end":"2026-02-05T00:45:45.659152Z","steps":["trace[2030406547] 'read index received' (duration: 778.789241ms)","trace[2030406547] 'applied index is now lower than readState.Index' (duration: 115.523µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T00:45:45.659326Z","caller":"traceutil/trace.go:171","msg":"trace[1875445124] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"921.166831ms","start":"2026-02-05T00:45:44.738154Z","end":"2026-02-05T00:45:45.659321Z","steps":["trace[1875445124] 'process raft request' (duration: 920.914315ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:45:45.659375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:45:44.738145Z","time spent":"921.194961ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T00:45:45.659478Z","caller":"traceutil/trace.go:171","msg":"trace[1837724637] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"920.167847ms","start":"2026-02-05T00:45:44.739306Z","end":"2026-02-05T00:45:45.659474Z","steps":["trace[1837724637] 'process raft request' (duration: 919.817139ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:45:45.659501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:45:44.739298Z","time spent":"920.189927ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T00:45:45.659561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.313653ms","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-05T00:45:45.659576Z","caller":"traceutil/trace.go:171","msg":"trace[1001295852] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:707; }","duration":"779.357244ms","start":"2026-02-05T00:45:44.880215Z","end":"2026-02-05T00:45:45.659572Z","steps":["trace[1001295852] 'agreement among raft nodes before linearized reading' (duration: 779.331944ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:45:45.65959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:45:44.880198Z","time spent":"779.388425ms","remote":"127.0.0.1:52542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-05T00:45:45.659657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.503215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T00:45:45.659668Z","caller":"traceutil/trace.go:171","msg":"trace[830065677] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:707; }","duration":"327.535617ms","start":"2026-02-05T00:45:45.332129Z","end":"2026-02-05T00:45:45.659665Z","steps":["trace[830065677] 'agreement among raft nodes before linearized reading' (duration: 327.518266ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:45:45.65968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:45:45.332116Z","time spent":"327.562327ms","remote":"127.0.0.1:40152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-05T00:45:51.583607Z","caller":"traceutil/trace.go:171","msg":"trace[15578512] linearizableReadLoop","detail":"{readStateIndex:744; appliedIndex:743; }","duration":"254.273083ms","start":"2026-02-05T00:45:51.329318Z","end":"2026-02-05T00:45:51.583591Z","steps":["trace[15578512] 'read index received' (duration: 254.13762ms)","trace[15578512] 'applied index is now lower than readState.Index' (duration: 134.943µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T00:45:51.583704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.371355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T00:45:51.583724Z","caller":"traceutil/trace.go:171","msg":"trace[1660447451] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:721; }","duration":"254.404256ms","start":"2026-02-05T00:45:51.329314Z","end":"2026-02-05T00:45:51.583718Z","steps":["trace[1660447451] 'agreement among raft nodes before linearized reading' (duration: 254.339164ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:45:51.583904Z","caller":"traceutil/trace.go:171","msg":"trace[583298646] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"790.522821ms","start":"2026-02-05T00:45:50.793375Z","end":"2026-02-05T00:45:51.583897Z","steps":["trace[583298646] 'process raft request' (duration: 790.119712ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:45:51.58396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:45:50.793366Z","time spent":"790.554231ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T00:45:51.89266Z","caller":"traceutil/trace.go:171","msg":"trace[1132492444] linearizableReadLoop","detail":"{readStateIndex:745; appliedIndex:744; }","duration":"166.812193ms","start":"2026-02-05T00:45:51.725836Z","end":"2026-02-05T00:45:51.892648Z","steps":["trace[1132492444] 'read index received' (duration: 108.785413ms)","trace[1132492444] 'applied index is now lower than readState.Index' (duration: 58.02495ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T00:45:51.892767Z","caller":"traceutil/trace.go:171","msg":"trace[1866919466] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"245.175974ms","start":"2026-02-05T00:45:51.647586Z","end":"2026-02-05T00:45:51.892762Z","steps":["trace[1866919466] 'process raft request' (duration: 187.075472ms)","trace[1866919466] 'compare' (duration: 57.889647ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T00:45:51.892909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.069399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-05T00:45:51.892923Z","caller":"traceutil/trace.go:171","msg":"trace[758551826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:722; }","duration":"167.127761ms","start":"2026-02-05T00:45:51.725791Z","end":"2026-02-05T00:45:51.892919Z","steps":["trace[758551826] 'agreement among raft nodes before linearized reading' (duration: 167.057549ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:45:51.893001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.691739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-05T00:45:51.893012Z","caller":"traceutil/trace.go:171","msg":"trace[2098732561] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:722; }","duration":"162.726519ms","start":"2026-02-05T00:45:51.730283Z","end":"2026-02-05T00:45:51.893009Z","steps":["trace[2098732561] 'agreement among raft nodes before linearized reading' (duration: 162.691858ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:45:59.923319Z","caller":"traceutil/trace.go:171","msg":"trace[393135287] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"314.526505ms","start":"2026-02-05T00:45:59.60878Z","end":"2026-02-05T00:45:59.923306Z","steps":["trace[393135287] 'process raft request' (duration: 314.241959ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:45:59.923408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:45:59.608771Z","time spent":"314.591737ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T00:46:03.872359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:46:03.571846Z","time spent":"300.491501ms","remote":"127.0.0.1:40186","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-05T00:46:04.80543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.273045ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T00:46:04.806567Z","caller":"traceutil/trace.go:171","msg":"trace[1090649773] linearizableReadLoop","detail":"{readStateIndex:784; appliedIndex:781; }","duration":"301.950845ms","start":"2026-02-05T00:46:04.504599Z","end":"2026-02-05T00:46:04.806549Z","steps":["trace[1090649773] 'read index received' (duration: 286.066678ms)","trace[1090649773] 'applied index is now lower than readState.Index' (duration: 15.883257ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T00:46:04.806874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.270513ms","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-05T00:46:04.80695Z","caller":"traceutil/trace.go:171","msg":"trace[1284239252] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:758; }","duration":"302.383135ms","start":"2026-02-05T00:46:04.504553Z","end":"2026-02-05T00:46:04.806936Z","steps":["trace[1284239252] 'agreement among raft nodes before linearized reading' (duration: 302.108028ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:46:04.80698Z","caller":"traceutil/trace.go:171","msg":"trace[6802851] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"827.580549ms","start":"2026-02-05T00:46:03.979389Z","end":"2026-02-05T00:46:04.80697Z","steps":["trace[6802851] 'process raft request' (duration: 827.061457ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:46:04.807066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:46:04.504532Z","time spent":"302.521458ms","remote":"127.0.0.1:40426","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-05T00:46:04.807313Z","caller":"traceutil/trace.go:171","msg":"trace[37829239] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"826.397632ms","start":"2026-02-05T00:46:03.980901Z","end":"2026-02-05T00:46:04.807298Z","steps":["trace[37829239] 'process raft request' (duration: 825.611014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:46:04.807426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:46:03.980888Z","time spent":"826.498734ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T00:46:04.806876Z","caller":"traceutil/trace.go:171","msg":"trace[796012770] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"829.815662ms","start":"2026-02-05T00:46:03.977032Z","end":"2026-02-05T00:46:04.806848Z","steps":["trace[796012770] 'process raft request' (duration: 335.051912ms)","trace[796012770] 'compare' (duration: 492.926967ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T00:46:04.808635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:46:03.977017Z","time spent":"831.561092ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T00:46:04.807107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:46:03.979376Z","time spent":"827.650771ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T00:46:05.80211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.58731ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T00:46:05.802201Z","caller":"traceutil/trace.go:171","msg":"trace[442283215] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"1.258321348s","start":"2026-02-05T00:46:04.543862Z","end":"2026-02-05T00:46:05.802184Z","steps":["trace[442283215] 'process raft request' (duration: 1.003584044s)","trace[442283215] 'compare' (duration: 254.497558ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T00:46:05.802257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:46:04.543842Z","time spent":"1.25838817s","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T00:46:05.997274Z","caller":"traceutil/trace.go:171","msg":"trace[917004400] linearizableReadLoop","detail":"{readStateIndex:786; appliedIndex:784; }","duration":"664.636813ms","start":"2026-02-05T00:46:05.332613Z","end":"2026-02-05T00:46:05.99725Z","steps":["trace[917004400] 'read index received' (duration: 214.855213ms)","trace[917004400] 'applied index is now lower than readState.Index' (duration: 449.7806ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T00:46:05.997563Z","caller":"traceutil/trace.go:171","msg":"trace[178734879] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"1.185125257s","start":"2026-02-05T00:46:04.812424Z","end":"2026-02-05T00:46:05.997549Z","steps":["trace[178734879] 'process raft request' (duration: 1.125848407s)","trace[178734879] 'compare' (duration: 58.885251ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T00:46:05.997663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:46:04.812409Z","time spent":"1.185191959s","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T00:46:05.997884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.267127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T00:46:05.997924Z","caller":"traceutil/trace.go:171","msg":"trace[994957978] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:760; }","duration":"665.339289ms","start":"2026-02-05T00:46:05.332574Z","end":"2026-02-05T00:46:05.997914Z","steps":["trace[994957978] 'agreement among raft nodes before linearized reading' (duration: 665.279068ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:46:05.997954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:46:05.332555Z","time spent":"665.393011ms","remote":"127.0.0.1:40152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-05T00:46:17.178228Z","caller":"traceutil/trace.go:171","msg":"trace[1945330983] linearizableReadLoop","detail":"{readStateIndex:871; appliedIndex:870; }","duration":"256.899885ms","start":"2026-02-05T00:46:16.921314Z","end":"2026-02-05T00:46:17.178214Z","steps":["trace[1945330983] 'read index received' (duration: 256.783182ms)","trace[1945330983] 'applied index is now lower than readState.Index' (duration: 115.823µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T00:46:17.178816Z","caller":"traceutil/trace.go:171","msg":"trace[1435300692] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"269.488676ms","start":"2026-02-05T00:46:16.90932Z","end":"2026-02-05T00:46:17.178808Z","steps":["trace[1435300692] 'process raft request' (duration: 268.81396ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:46:17.178937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.609951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-05T00:46:17.178952Z","caller":"traceutil/trace.go:171","msg":"trace[923826968] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:841; }","duration":"257.646892ms","start":"2026-02-05T00:46:16.921301Z","end":"2026-02-05T00:46:17.178948Z","steps":["trace[923826968] 'agreement among raft nodes before linearized reading' (duration: 257.57827ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:46:17.179184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.842616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-05T00:46:17.179203Z","caller":"traceutil/trace.go:171","msg":"trace[267714609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:841; }","duration":"257.867398ms","start":"2026-02-05T00:46:16.921329Z","end":"2026-02-05T00:46:17.179196Z","steps":["trace[267714609] 'agreement among raft nodes before linearized reading' (duration: 257.433647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:46:19.404188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.814917ms","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-05T00:46:19.404239Z","caller":"traceutil/trace.go:171","msg":"trace[1688406996] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:846; }","duration":"209.887759ms","start":"2026-02-05T00:46:19.194338Z","end":"2026-02-05T00:46:19.404226Z","steps":["trace[1688406996] 'range keys from in-memory index tree' (duration: 209.729625ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:46:19.404412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.193075761s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T00:46:19.404425Z","caller":"traceutil/trace.go:171","msg":"trace[1853578535] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:846; }","duration":"1.193089582s","start":"2026-02-05T00:46:18.211331Z","end":"2026-02-05T00:46:19.404421Z","steps":["trace[1853578535] 'range keys from in-memory index tree' (duration: 1.193037171s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:46:19.40444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:46:18.211295Z","time spent":"1.193140423s","remote":"127.0.0.1:40152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T00:46:19.404613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.075363362s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T00:46:19.404632Z","caller":"traceutil/trace.go:171","msg":"trace[1336874916] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:846; }","duration":"1.075377522s","start":"2026-02-05T00:46:18.329246Z","end":"2026-02-05T00:46:19.404623Z","steps":["trace[1336874916] 'range keys from in-memory index tree' (duration: 1.075321752s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:46:19.404644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:46:18.32921Z","time spent":"1.075430673s","remote":"127.0.0.1:40154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T00:46:19.404825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.757547ms","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-05T00:46:19.404858Z","caller":"traceutil/trace.go:171","msg":"trace[1767461001] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:846; }","duration":"580.792507ms","start":"2026-02-05T00:46:18.824061Z","end":"2026-02-05T00:46:19.404854Z","steps":["trace[1767461001] 'range keys from in-memory index tree' (duration: 580.752917ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:46:19.405238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.27169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-05T00:46:19.405253Z","caller":"traceutil/trace.go:171","msg":"trace[1932776880] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:846; }","duration":"214.298761ms","start":"2026-02-05T00:46:19.19095Z","end":"2026-02-05T00:46:19.405249Z","steps":["trace[1932776880] 'range keys from in-memory index tree' (duration: 214.226729ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:46:19.405409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.491565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-05T00:46:19.405423Z","caller":"traceutil/trace.go:171","msg":"trace[721181210] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:846; }","duration":"214.527705ms","start":"2026-02-05T00:46:19.190891Z","end":"2026-02-05T00:46:19.405419Z","steps":["trace[721181210] 'range keys from in-memory index tree' (duration: 214.396593ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:46:19.845915Z","caller":"traceutil/trace.go:171","msg":"trace[950708671] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"437.725813ms","start":"2026-02-05T00:46:19.408175Z","end":"2026-02-05T00:46:19.8459Z","steps":["trace[950708671] 'process raft request' (duration: 437.60652ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:46:19.846021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:46:19.408147Z","time spent":"437.823364ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T00:46:19.846164Z","caller":"traceutil/trace.go:171","msg":"trace[499479337] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"433.887503ms","start":"2026-02-05T00:46:19.412262Z","end":"2026-02-05T00:46:19.846149Z","steps":["trace[499479337] 'process raft request' (duration: 433.612237ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:46:19.846255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:46:19.412255Z","time spent":"433.946455ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":420,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T00:46:19.846323Z","caller":"traceutil/trace.go:171","msg":"trace[982831818] linearizableReadLoop","detail":"{readStateIndex:878; appliedIndex:876; }","duration":"434.468317ms","start":"2026-02-05T00:46:19.411849Z","end":"2026-02-05T00:46:19.846317Z","steps":["trace[982831818] 'read index received' (duration: 407.515455ms)","trace[982831818] 'applied index is now lower than readState.Index' (duration: 26.952402ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T00:46:19.846361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.510368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T00:46:19.846377Z","caller":"traceutil/trace.go:171","msg":"trace[1546271894] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:849; }","duration":"434.537509ms","start":"2026-02-05T00:46:19.411834Z","end":"2026-02-05T00:46:19.846372Z","steps":["trace[1546271894] 'agreement among raft nodes before linearized reading' (duration: 434.504368ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:46:19.84639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:46:19.411827Z","time spent":"434.560059ms","remote":"127.0.0.1:40152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-05T00:46:19.84644Z","caller":"traceutil/trace.go:171","msg":"trace[1648763127] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"432.44908ms","start":"2026-02-05T00:46:19.413986Z","end":"2026-02-05T00:46:19.846435Z","steps":["trace[1648763127] 'process raft request' (duration: 432.120982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:46:19.84647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:46:19.41398Z","time spent":"432.473581ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T00:46:20.220569Z","caller":"traceutil/trace.go:171","msg":"trace[2091035318] linearizableReadLoop","detail":"{readStateIndex:881; appliedIndex:880; }","duration":"114.168048ms","start":"2026-02-05T00:46:20.106384Z","end":"2026-02-05T00:46:20.220552Z","steps":["trace[2091035318] 'read index received' (duration: 114.020464ms)","trace[2091035318] 'applied index is now lower than readState.Index' (duration: 146.824µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T00:46:20.220733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.334301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-05T00:46:20.22075Z","caller":"traceutil/trace.go:171","msg":"trace[1461469856] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:850; }","duration":"114.385652ms","start":"2026-02-05T00:46:20.10636Z","end":"2026-02-05T00:46:20.220745Z","steps":["trace[1461469856] 'agreement among raft nodes before linearized reading' (duration: 114.251239ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:46:20.220964Z","caller":"traceutil/trace.go:171","msg":"trace[1721336451] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"130.029984ms","start":"2026-02-05T00:46:20.090926Z","end":"2026-02-05T00:46:20.220956Z","steps":["trace[1721336451] 'process raft request' (duration: 129.532713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:46:28.237072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.790795ms","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-05T00:46:28.237122Z","caller":"traceutil/trace.go:171","msg":"trace[1827240752] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:869; }","duration":"360.875306ms","start":"2026-02-05T00:46:27.876236Z","end":"2026-02-05T00:46:28.237111Z","steps":["trace[1827240752] 'range keys from in-memory index tree' (duration: 360.690252ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:46:28.237123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.533701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-05T00:46:28.23718Z","caller":"traceutil/trace.go:171","msg":"trace[821747979] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:869; }","duration":"251.637724ms","start":"2026-02-05T00:46:27.98553Z","end":"2026-02-05T00:46:28.237167Z","steps":["trace[821747979] 'range keys from in-memory index tree' (duration: 251.438918ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:46:28.237191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.034246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-05T00:46:28.237207Z","caller":"traceutil/trace.go:171","msg":"trace[933364330] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:869; }","duration":"334.076157ms","start":"2026-02-05T00:46:27.903125Z","end":"2026-02-05T00:46:28.237201Z","steps":["trace[933364330] 'range keys from in-memory index tree' (duration: 333.975684ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:46:28.237219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:46:27.903115Z","time spent":"334.100918ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T00:46:28.237144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:46:27.87622Z","time spent":"360.919198ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-05T00:46:32.84862Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548129549145521,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T00:46:33.348807Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548129549145521,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T00:46:33.359861Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.012447859s","expected-duration":"1s"} {"level":"info","ts":"2026-02-05T00:46:33.360062Z","caller":"traceutil/trace.go:171","msg":"trace[477932017] linearizableReadLoop","detail":"{readStateIndex:913; appliedIndex:912; }","duration":"1.012275364s","start":"2026-02-05T00:46:32.347776Z","end":"2026-02-05T00:46:33.360052Z","steps":["trace[477932017] 'read index received' (duration: 1.012182692s)","trace[477932017] 'applied index is now lower than readState.Index' (duration: 92.012µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T00:46:33.360201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.012390667s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-05T00:46:33.360216Z","caller":"traceutil/trace.go:171","msg":"trace[616582677] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:880; }","duration":"1.012444898s","start":"2026-02-05T00:46:32.347767Z","end":"2026-02-05T00:46:33.360212Z","steps":["trace[616582677] 'agreement among raft nodes before linearized reading' (duration: 1.012345686s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:46:33.360232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:46:32.347753Z","time spent":"1.012475179s","remote":"127.0.0.1:40426","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-05T00:46:33.36025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.012450988s","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-05T00:46:33.360283Z","caller":"traceutil/trace.go:171","msg":"trace[1715511322] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:880; }","duration":"1.01251566s","start":"2026-02-05T00:46:32.347754Z","end":"2026-02-05T00:46:33.360269Z","steps":["trace[1715511322] 'agreement among raft nodes before linearized reading' (duration: 1.012355727s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:46:33.36031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:46:32.347745Z","time spent":"1.012560991s","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-05T00:46:33.360417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.366924ms","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-05T00:46:33.360435Z","caller":"traceutil/trace.go:171","msg":"trace[1074847293] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:880; }","duration":"192.406565ms","start":"2026-02-05T00:46:33.168022Z","end":"2026-02-05T00:46:33.360428Z","steps":["trace[1074847293] 'agreement among raft nodes before linearized reading' (duration: 192.381354ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:46:33.360508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.426702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T00:46:33.360522Z","caller":"traceutil/trace.go:171","msg":"trace[239669729] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:880; }","duration":"455.442912ms","start":"2026-02-05T00:46:32.905075Z","end":"2026-02-05T00:46:33.360518Z","steps":["trace[239669729] 'agreement among raft nodes before linearized reading' (duration: 455.415501ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:46:33.360536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:46:32.90504Z","time spent":"455.492642ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":4,"response size":31,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"info","ts":"2026-02-05T00:46:33.36052Z","caller":"traceutil/trace.go:171","msg":"trace[2127316502] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"1.013220476s","start":"2026-02-05T00:46:32.347292Z","end":"2026-02-05T00:46:33.360513Z","steps":["trace[2127316502] 'process raft request' (duration: 1.012679093s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:46:33.360644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.00300859s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-05T00:46:33.360647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:46:32.347275Z","time spent":"1.013339039s","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T00:46:33.360661Z","caller":"traceutil/trace.go:171","msg":"trace[2130798024] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:880; }","duration":"1.003035471s","start":"2026-02-05T00:46:32.357621Z","end":"2026-02-05T00:46:33.360656Z","steps":["trace[2130798024] 'agreement among raft nodes before linearized reading' (duration: 1.00298957s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:46:33.360675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:46:32.357615Z","time spent":"1.003056402s","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T00:46:33.36076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.00776363s","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-05T00:46:33.360774Z","caller":"traceutil/trace.go:171","msg":"trace[388035809] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:880; }","duration":"1.007784931s","start":"2026-02-05T00:46:32.352983Z","end":"2026-02-05T00:46:33.360768Z","steps":["trace[388035809] 'agreement among raft nodes before linearized reading' (duration: 1.00774743s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:46:33.360786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:46:32.352978Z","time spent":"1.007804611s","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-05T00:46:33.689174Z","caller":"traceutil/trace.go:171","msg":"trace[1995755538] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"324.877425ms","start":"2026-02-05T00:46:33.364276Z","end":"2026-02-05T00:46:33.689154Z","steps":["trace[1995755538] 'process raft request' (duration: 270.839866ms)","trace[1995755538] 'compare' (duration: 53.808484ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T00:46:33.689255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:46:33.364266Z","time spent":"324.946116ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T00:46:33.727153Z","caller":"traceutil/trace.go:171","msg":"trace[897172819] linearizableReadLoop","detail":"{readStateIndex:917; appliedIndex:913; }","duration":"149.460673ms","start":"2026-02-05T00:46:33.577663Z","end":"2026-02-05T00:46:33.727124Z","steps":["trace[897172819] 'read index received' (duration: 57.492928ms)","trace[897172819] 'applied index is now lower than readState.Index' (duration: 91.967105ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T00:46:33.727175Z","caller":"traceutil/trace.go:171","msg":"trace[1834599280] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"361.502321ms","start":"2026-02-05T00:46:33.365657Z","end":"2026-02-05T00:46:33.727159Z","steps":["trace[1834599280] 'process raft request' (duration: 361.337827ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:46:33.727258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:46:33.365644Z","time spent":"361.570993ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T00:46:33.727301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.619487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.212\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-05T00:46:33.727321Z","caller":"traceutil/trace.go:171","msg":"trace[261572738] range","detail":"{range_begin:/registry/masterleases/162.253.55.212; range_end:; response_count:1; response_revision:884; }","duration":"149.655948ms","start":"2026-02-05T00:46:33.577659Z","end":"2026-02-05T00:46:33.727315Z","steps":["trace[261572738] 'agreement among raft nodes before linearized reading' (duration: 149.538705ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:46:33.727475Z","caller":"traceutil/trace.go:171","msg":"trace[318565760] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"361.726836ms","start":"2026-02-05T00:46:33.365742Z","end":"2026-02-05T00:46:33.727469Z","steps":["trace[318565760] 'process raft request' (duration: 361.340987ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:46:33.727524Z","caller":"traceutil/trace.go:171","msg":"trace[1987533003] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"360.455877ms","start":"2026-02-05T00:46:33.367062Z","end":"2026-02-05T00:46:33.727518Z","steps":["trace[1987533003] 'process raft request' (duration: 360.039068ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:46:33.727528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:46:33.365738Z","time spent":"361.749937ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T00:46:33.727557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:46:33.367055Z","time spent":"360.482978ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T00:46:34.062129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:46:33.728415Z","time spent":"333.708719ms","remote":"127.0.0.1:40186","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-05T00:46:34.407838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.389351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-05T00:46:34.407936Z","caller":"traceutil/trace.go:171","msg":"trace[1472375190] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:885; }","duration":"243.525765ms","start":"2026-02-05T00:46:34.164391Z","end":"2026-02-05T00:46:34.407917Z","steps":["trace[1472375190] 'range keys from in-memory index tree' (duration: 243.30715ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:46:36.481968Z","caller":"traceutil/trace.go:171","msg":"trace[1720308892] linearizableReadLoop","detail":"{readStateIndex:925; appliedIndex:924; }","duration":"152.979064ms","start":"2026-02-05T00:46:36.328966Z","end":"2026-02-05T00:46:36.481945Z","steps":["trace[1720308892] 'read index received' (duration: 152.789909ms)","trace[1720308892] 'applied index is now lower than readState.Index' (duration: 188.355µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T00:46:36.482115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.131978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T00:46:36.482152Z","caller":"traceutil/trace.go:171","msg":"trace[784492781] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:891; }","duration":"153.209219ms","start":"2026-02-05T00:46:36.328933Z","end":"2026-02-05T00:46:36.482142Z","steps":["trace[784492781] 'agreement among raft nodes before linearized reading' (duration: 153.100177ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:46:36.482515Z","caller":"traceutil/trace.go:171","msg":"trace[69664107] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"203.538431ms","start":"2026-02-05T00:46:36.278956Z","end":"2026-02-05T00:46:36.482495Z","steps":["trace[69664107] 'process raft request' (duration: 202.871726ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:46:37.580781Z","caller":"traceutil/trace.go:171","msg":"trace[490116725] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"199.756055ms","start":"2026-02-05T00:46:37.381004Z","end":"2026-02-05T00:46:37.58076Z","steps":["trace[490116725] 'process raft request' (duration: 199.657643ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:46:38.014204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.609393ms","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-05T00:46:38.01426Z","caller":"traceutil/trace.go:171","msg":"trace[2053035710] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:892; }","duration":"316.712294ms","start":"2026-02-05T00:46:37.697534Z","end":"2026-02-05T00:46:38.014246Z","steps":["trace[2053035710] 'range keys from in-memory index tree' (duration: 316.407177ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:46:38.014288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:46:37.697512Z","time spent":"316.769616ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-05T00:46:38.014425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.491983ms","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-05T00:46:38.014444Z","caller":"traceutil/trace.go:171","msg":"trace[2038689604] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:892; }","duration":"272.532335ms","start":"2026-02-05T00:46:37.741904Z","end":"2026-02-05T00:46:38.014436Z","steps":["trace[2038689604] 'range keys from in-memory index tree' (duration: 272.426202ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:46:38.014538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.277295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-05T00:46:38.014553Z","caller":"traceutil/trace.go:171","msg":"trace[1424321434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:892; }","duration":"271.312886ms","start":"2026-02-05T00:46:37.743236Z","end":"2026-02-05T00:46:38.014549Z","steps":["trace[1424321434] 'range keys from in-memory index tree' (duration: 271.204743ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:46:38.014671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.474677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-05T00:46:38.014686Z","caller":"traceutil/trace.go:171","msg":"trace[1668506802] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:892; }","duration":"270.510477ms","start":"2026-02-05T00:46:37.744171Z","end":"2026-02-05T00:46:38.014682Z","steps":["trace[1668506802] 'range keys from in-memory index tree' (duration: 270.405846ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:46:38.233268Z","caller":"traceutil/trace.go:171","msg":"trace[1788641313] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"210.796859ms","start":"2026-02-05T00:46:38.022452Z","end":"2026-02-05T00:46:38.233249Z","steps":["trace[1788641313] 'process raft request' (duration: 210.659885ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:46:38.261092Z","caller":"traceutil/trace.go:171","msg":"trace[1141610643] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"237.473484ms","start":"2026-02-05T00:46:38.023599Z","end":"2026-02-05T00:46:38.261072Z","steps":["trace[1141610643] 'process raft request' (duration: 235.738464ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:46:38.261376Z","caller":"traceutil/trace.go:171","msg":"trace[2026067790] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"237.639198ms","start":"2026-02-05T00:46:38.023723Z","end":"2026-02-05T00:46:38.261363Z","steps":["trace[2026067790] 'process raft request' (duration: 235.701683ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:46:38.261605Z","caller":"traceutil/trace.go:171","msg":"trace[1568600350] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"236.136473ms","start":"2026-02-05T00:46:38.025455Z","end":"2026-02-05T00:46:38.261591Z","steps":["trace[1568600350] 'process raft request' (duration: 234.012724ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:46:38.261923Z","caller":"traceutil/trace.go:171","msg":"trace[1392823459] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"109.99492ms","start":"2026-02-05T00:46:38.151915Z","end":"2026-02-05T00:46:38.26191Z","steps":["trace[1392823459] 'process raft request' (duration: 107.606975ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:46:38.62877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.237287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T00:46:38.628844Z","caller":"traceutil/trace.go:171","msg":"trace[394671459] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:897; }","duration":"297.34574ms","start":"2026-02-05T00:46:38.33148Z","end":"2026-02-05T00:46:38.628825Z","steps":["trace[394671459] 'range keys from in-memory index tree' (duration: 297.159125ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:46:40.124429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.392155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T00:46:40.124484Z","caller":"traceutil/trace.go:171","msg":"trace[352438286] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:903; }","duration":"389.485566ms","start":"2026-02-05T00:46:39.734985Z","end":"2026-02-05T00:46:40.124471Z","steps":["trace[352438286] 'range keys from in-memory index tree' (duration: 389.344504ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:46:40.124511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:46:39.73497Z","time spent":"389.534568ms","remote":"127.0.0.1:52408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":29,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" "} {"level":"info","ts":"2026-02-05T00:46:40.608715Z","caller":"traceutil/trace.go:171","msg":"trace[239828592] linearizableReadLoop","detail":"{readStateIndex:939; appliedIndex:938; }","duration":"337.364571ms","start":"2026-02-05T00:46:40.271334Z","end":"2026-02-05T00:46:40.608698Z","steps":["trace[239828592] 'read index received' (duration: 337.244389ms)","trace[239828592] 'applied index is now lower than readState.Index' (duration: 119.352µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T00:46:40.608796Z","caller":"traceutil/trace.go:171","msg":"trace[159363872] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"365.165803ms","start":"2026-02-05T00:46:40.243623Z","end":"2026-02-05T00:46:40.608788Z","steps":["trace[159363872] 'process raft request' (duration: 364.978099ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:46:40.608879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:46:40.243592Z","time spent":"365.221195ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T00:46:40.608934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.533725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-05T00:46:40.608978Z","caller":"traceutil/trace.go:171","msg":"trace[430091042] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:904; }","duration":"337.598447ms","start":"2026-02-05T00:46:40.271368Z","end":"2026-02-05T00:46:40.608967Z","steps":["trace[430091042] 'agreement among raft nodes before linearized reading' (duration: 337.487114ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:46:40.608999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:46:40.271363Z","time spent":"337.631637ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T00:46:40.609091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.68853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T00:46:40.60912Z","caller":"traceutil/trace.go:171","msg":"trace[1383080002] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:904; }","duration":"276.731151ms","start":"2026-02-05T00:46:40.332381Z","end":"2026-02-05T00:46:40.609112Z","steps":["trace[1383080002] 'agreement among raft nodes before linearized reading' (duration: 276.68651ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:46:40.60913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.721239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-05T00:46:40.609144Z","caller":"traceutil/trace.go:171","msg":"trace[2127078168] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:904; }","duration":"337.745891ms","start":"2026-02-05T00:46:40.271394Z","end":"2026-02-05T00:46:40.60914Z","steps":["trace[2127078168] 'agreement among raft nodes before linearized reading' (duration: 337.70574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:46:40.609158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:46:40.271389Z","time spent":"337.766191ms","remote":"127.0.0.1:40426","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-05T00:46:40.609357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.019497ms","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-05T00:46:40.609377Z","caller":"traceutil/trace.go:171","msg":"trace[677720469] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:904; }","duration":"338.064608ms","start":"2026-02-05T00:46:40.271308Z","end":"2026-02-05T00:46:40.609372Z","steps":["trace[677720469] 'agreement among raft nodes before linearized reading' (duration: 337.975486ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:46:40.6094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:46:40.271295Z","time spent":"338.101639ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-05T00:46:56.458628Z","caller":"traceutil/trace.go:171","msg":"trace[938605649] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"106.077703ms","start":"2026-02-05T00:46:56.352519Z","end":"2026-02-05T00:46:56.458597Z","steps":["trace[938605649] 'process raft request' (duration: 105.873538ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:46:56.852584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.96779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-05T00:46:56.852681Z","caller":"traceutil/trace.go:171","msg":"trace[1578741404] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1011; }","duration":"109.097433ms","start":"2026-02-05T00:46:56.74356Z","end":"2026-02-05T00:46:56.852657Z","steps":["trace[1578741404] 'range keys from in-memory index tree' (duration: 106.140584ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:46:56.852717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.736207ms","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-05T00:46:56.852827Z","caller":"traceutil/trace.go:171","msg":"trace[594520680] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1011; }","duration":"144.915562ms","start":"2026-02-05T00:46:56.707881Z","end":"2026-02-05T00:46:56.852797Z","steps":["trace[594520680] 'range keys from in-memory index tree' (duration: 143.57789ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:46:56.853156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.065085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-05T00:46:56.853189Z","caller":"traceutil/trace.go:171","msg":"trace[1296072052] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1011; }","duration":"110.138947ms","start":"2026-02-05T00:46:56.743041Z","end":"2026-02-05T00:46:56.85318Z","steps":["trace[1296072052] 'range keys from in-memory index tree' (duration: 109.898812ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:46:56.854117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.053595ms","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-05T00:46:56.854294Z","caller":"traceutil/trace.go:171","msg":"trace[1808344864] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1011; }","duration":"113.972436ms","start":"2026-02-05T00:46:56.740197Z","end":"2026-02-05T00:46:56.85417Z","steps":["trace[1808344864] 'range keys from in-memory index tree' (duration: 112.945482ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T00:49:55.828441Z","caller":"traceutil/trace.go:171","msg":"trace[620496802] transaction","detail":"{read_only:false; response_revision:1769; number_of_response:1; }","duration":"115.94205ms","start":"2026-02-05T00:49:55.712483Z","end":"2026-02-05T00:49:55.828425Z","steps":["trace[620496802] 'process raft request' (duration: 43.454208ms)","trace[620496802] 'compare' (duration: 72.199885ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T00:50:02.257253Z","caller":"traceutil/trace.go:171","msg":"trace[1965532122] linearizableReadLoop","detail":"{readStateIndex:1874; appliedIndex:1873; }","duration":"398.164013ms","start":"2026-02-05T00:50:01.85906Z","end":"2026-02-05T00:50:02.257224Z","steps":["trace[1965532122] 'read index received' (duration: 396.510934ms)","trace[1965532122] 'applied index is now lower than readState.Index' (duration: 1.651709ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T00:50:02.257794Z","caller":"traceutil/trace.go:171","msg":"trace[1614169525] transaction","detail":"{read_only:false; response_revision:1798; number_of_response:1; }","duration":"406.523798ms","start":"2026-02-05T00:50:01.851245Z","end":"2026-02-05T00:50:02.257769Z","steps":["trace[1614169525] 'process raft request' (duration: 404.398378ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:50:02.257955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:50:01.851227Z","time spent":"406.64298ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T00:50:02.258603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.534995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-02-05T00:50:02.258641Z","caller":"traceutil/trace.go:171","msg":"trace[1087382048] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1798; }","duration":"399.583066ms","start":"2026-02-05T00:50:01.859045Z","end":"2026-02-05T00:50:02.258628Z","steps":["trace[1087382048] 'agreement among raft nodes before linearized reading' (duration: 399.474983ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:50:02.258671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:50:01.859038Z","time spent":"399.625997ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":551,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-05T00:50:02.261409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.5007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-02-05T00:50:02.261448Z","caller":"traceutil/trace.go:171","msg":"trace[1603025078] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1799; }","duration":"373.570532ms","start":"2026-02-05T00:50:01.887866Z","end":"2026-02-05T00:50:02.261436Z","steps":["trace[1603025078] 'agreement among raft nodes before linearized reading' (duration: 373.419738ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:50:02.261473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:50:01.887851Z","time spent":"373.616663ms","remote":"127.0.0.1:40426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":534,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-05T00:50:02.261565Z","caller":"traceutil/trace.go:171","msg":"trace[917693822] transaction","detail":"{read_only:false; response_revision:1799; number_of_response:1; }","duration":"121.291429ms","start":"2026-02-05T00:50:02.140268Z","end":"2026-02-05T00:50:02.261559Z","steps":["trace[917693822] 'process raft request' (duration: 120.950711ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:50:02.261745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.888282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-05T00:50:02.261778Z","caller":"traceutil/trace.go:171","msg":"trace[949904102] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1799; }","duration":"238.932383ms","start":"2026-02-05T00:50:02.022825Z","end":"2026-02-05T00:50:02.261757Z","steps":["trace[949904102] 'agreement among raft nodes before linearized reading' (duration: 238.883232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:50:02.261851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.38059ms","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-05T00:50:02.261865Z","caller":"traceutil/trace.go:171","msg":"trace[1990889406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1799; }","duration":"340.415482ms","start":"2026-02-05T00:50:01.921445Z","end":"2026-02-05T00:50:02.261861Z","steps":["trace[1990889406] 'agreement among raft nodes before linearized reading' (duration: 340.371131ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T00:50:02.261883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T00:50:01.921431Z","time spent":"340.447652ms","remote":"127.0.0.1:40426","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\" "}