{"level":"warn","ts":"2026-02-25T22:05:29.865212Z","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-25T22:05:29.865298Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.222:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.222:2380","--initial-cluster=instance=https://199.204.45.222:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.222:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.222: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-25T22:05:29.865362Z","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-25T22:05:29.865372Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.222:2380"]} {"level":"info","ts":"2026-02-25T22:05:29.86539Z","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-25T22:05:29.865933Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"]} {"level":"info","ts":"2026-02-25T22:05:29.86601Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.222:2380"],"listen-peer-urls":["https://199.204.45.222:2380"],"advertise-client-urls":["https://199.204.45.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.222: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-25T22:05:29.869963Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.746972ms"} {"level":"info","ts":"2026-02-25T22:05:29.877705Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"380f6760faea5f9e","cluster-id":"b87e8aca83a17eab"} {"level":"info","ts":"2026-02-25T22:05:29.877799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T22:05:29.877837Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became follower at term 0"} {"level":"info","ts":"2026-02-25T22:05:29.877856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 380f6760faea5f9e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T22:05:29.877877Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became follower at term 1"} {"level":"info","ts":"2026-02-25T22:05:29.877933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=(4039561056998809502)"} {"level":"warn","ts":"2026-02-25T22:05:29.885343Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T22:05:29.888108Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T22:05:29.889869Z","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-25T22:05:29.893033Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"380f6760faea5f9e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T22:05:29.895346Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"380f6760faea5f9e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T22:05:29.895667Z","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-25T22:05:29.895774Z","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-25T22:05:29.895857Z","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-25T22:05:29.896312Z","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-25T22:05:29.896378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=(4039561056998809502)"} {"level":"info","ts":"2026-02-25T22:05:29.896517Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b87e8aca83a17eab","local-member-id":"380f6760faea5f9e","added-peer-id":"380f6760faea5f9e","added-peer-peer-urls":["https://199.204.45.222:2380"]} {"level":"info","ts":"2026-02-25T22:05:29.896583Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.222:2380"} {"level":"info","ts":"2026-02-25T22:05:29.896607Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.222:2380"} {"level":"info","ts":"2026-02-25T22:05:29.896777Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"380f6760faea5f9e","initial-advertise-peer-urls":["https://199.204.45.222:2380"],"listen-peer-urls":["https://199.204.45.222:2380"],"advertise-client-urls":["https://199.204.45.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T22:05:29.896828Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T22:05:30.678783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T22:05:30.67885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T22:05:30.678889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e received MsgPreVoteResp from 380f6760faea5f9e at term 1"} {"level":"info","ts":"2026-02-25T22:05:30.678943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became candidate at term 2"} {"level":"info","ts":"2026-02-25T22:05:30.678965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e received MsgVoteResp from 380f6760faea5f9e at term 2"} {"level":"info","ts":"2026-02-25T22:05:30.678989Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became leader at term 2"} {"level":"info","ts":"2026-02-25T22:05:30.67901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 380f6760faea5f9e elected leader 380f6760faea5f9e at term 2"} {"level":"info","ts":"2026-02-25T22:05:30.680014Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T22:05:30.680995Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T22:05:30.681008Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"380f6760faea5f9e","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.222:2379]}","request-path":"/0/members/380f6760faea5f9e/attributes","cluster-id":"b87e8aca83a17eab","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T22:05:30.681268Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T22:05:30.681484Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b87e8aca83a17eab","local-member-id":"380f6760faea5f9e","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T22:05:30.681632Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T22:05:30.681662Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T22:05:30.681661Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T22:05:30.6817Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T22:05:30.684081Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.222:2379"} {"level":"info","ts":"2026-02-25T22:05:30.685009Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-25T22:05:59.639288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.54576ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T22:05:59.639442Z","caller":"traceutil/trace.go:171","msg":"trace[1343799666] linearizableReadLoop","detail":"{readStateIndex:528; appliedIndex:527; }","duration":"611.288717ms","start":"2026-02-25T22:05:59.02813Z","end":"2026-02-25T22:05:59.639418Z","steps":["trace[1343799666] 'read index received' (duration: 73.782µs)","trace[1343799666] 'applied index is now lower than readState.Index' (duration: 611.212785ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:05:59.639667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.976371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4375"} {"level":"info","ts":"2026-02-25T22:05:59.639708Z","caller":"traceutil/trace.go:171","msg":"trace[169322256] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:510; }","duration":"340.058512ms","start":"2026-02-25T22:05:59.29964Z","end":"2026-02-25T22:05:59.639698Z","steps":["trace[169322256] 'agreement among raft nodes before linearized reading' (duration: 339.920289ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:05:59.639778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:05:59.299624Z","time spent":"340.144664ms","remote":"127.0.0.1:48480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4398,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-25T22:05:59.639819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.640405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T22:05:59.639931Z","caller":"traceutil/trace.go:171","msg":"trace[1356484407] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:510; }","duration":"611.8262ms","start":"2026-02-25T22:05:59.028078Z","end":"2026-02-25T22:05:59.639904Z","steps":["trace[1356484407] 'agreement among raft nodes before linearized reading' (duration: 611.466721ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:05:59.639988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:05:59.028057Z","time spent":"611.922381ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T22:05:59.956461Z","caller":"traceutil/trace.go:171","msg":"trace[1046782884] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"310.186682ms","start":"2026-02-25T22:05:59.646243Z","end":"2026-02-25T22:05:59.95643Z","steps":["trace[1046782884] 'process raft request' (duration: 309.891046ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:05:59.956894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:05:59.646233Z","time spent":"310.294714ms","remote":"127.0.0.1:48480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4567,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:06:00.180877Z","caller":"traceutil/trace.go:171","msg":"trace[1168073019] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"220.527558ms","start":"2026-02-25T22:05:59.960309Z","end":"2026-02-25T22:06:00.180837Z","steps":["trace[1168073019] 'process raft request' (duration: 134.32165ms)","trace[1168073019] 'compare' (duration: 86.008154ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:06:06.224466Z","caller":"traceutil/trace.go:171","msg":"trace[1308962374] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:544; }","duration":"459.773019ms","start":"2026-02-25T22:06:05.764673Z","end":"2026-02-25T22:06:06.224446Z","steps":["trace[1308962374] 'read index received' (duration: 459.633196ms)","trace[1308962374] 'applied index is now lower than readState.Index' (duration: 138.513µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:06:06.224543Z","caller":"traceutil/trace.go:171","msg":"trace[1878905934] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"482.193449ms","start":"2026-02-25T22:06:05.742311Z","end":"2026-02-25T22:06:06.224504Z","steps":["trace[1878905934] 'process raft request' (duration: 482.012375ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:06:06.224679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.980553ms","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-25T22:06:06.22472Z","caller":"traceutil/trace.go:171","msg":"trace[354254890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:526; }","duration":"460.096815ms","start":"2026-02-25T22:06:05.764613Z","end":"2026-02-25T22:06:06.22471Z","steps":["trace[354254890] 'agreement among raft nodes before linearized reading' (duration: 459.997323ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:06:06.224757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:06:05.764572Z","time spent":"460.177207ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T22:06:06.224751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:06:05.742292Z","time spent":"482.334612ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:06:06.672614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.364323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:06:06.672728Z","caller":"traceutil/trace.go:171","msg":"trace[1640808304] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:533; }","duration":"142.533296ms","start":"2026-02-25T22:06:06.530167Z","end":"2026-02-25T22:06:06.6727Z","steps":["trace[1640808304] 'range keys from in-memory index tree' (duration: 142.24427ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:06:07.813543Z","caller":"traceutil/trace.go:171","msg":"trace[1853555349] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"501.525744ms","start":"2026-02-25T22:06:07.31199Z","end":"2026-02-25T22:06:07.813515Z","steps":["trace[1853555349] 'process raft request' (duration: 501.30602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:06:07.813717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:06:07.31197Z","time spent":"501.651347ms","remote":"127.0.0.1:48488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:06:08.588396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.184007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-25T22:06:08.588488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.52384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-25T22:06:08.588482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.000514ms","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-25T22:06:08.588506Z","caller":"traceutil/trace.go:171","msg":"trace[1199950188] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:534; }","duration":"266.354991ms","start":"2026-02-25T22:06:08.322128Z","end":"2026-02-25T22:06:08.588483Z","steps":["trace[1199950188] 'range keys from in-memory index tree' (duration: 265.994334ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:06:08.588561Z","caller":"traceutil/trace.go:171","msg":"trace[669565213] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:534; }","duration":"200.629632ms","start":"2026-02-25T22:06:08.387913Z","end":"2026-02-25T22:06:08.588542Z","steps":["trace[669565213] 'range keys from in-memory index tree' (duration: 200.341086ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:06:08.588574Z","caller":"traceutil/trace.go:171","msg":"trace[257223921] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:534; }","duration":"266.115556ms","start":"2026-02-25T22:06:08.322434Z","end":"2026-02-25T22:06:08.58855Z","steps":["trace[257223921] 'range keys from in-memory index tree' (duration: 265.726118ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:06:08.588421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.351126ms","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-25T22:06:08.588654Z","caller":"traceutil/trace.go:171","msg":"trace[1224368649] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:534; }","duration":"262.601381ms","start":"2026-02-25T22:06:08.326035Z","end":"2026-02-25T22:06:08.588636Z","steps":["trace[1224368649] 'range keys from in-memory index tree' (duration: 262.126341ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:06:08.931923Z","caller":"traceutil/trace.go:171","msg":"trace[2105548995] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"339.768656ms","start":"2026-02-25T22:06:08.592129Z","end":"2026-02-25T22:06:08.931898Z","steps":["trace[2105548995] 'process raft request' (duration: 339.650713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:06:08.932086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:06:08.592109Z","time spent":"339.877859ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:06:09.093172Z","caller":"traceutil/trace.go:171","msg":"trace[416392306] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"500.245156ms","start":"2026-02-25T22:06:08.5929Z","end":"2026-02-25T22:06:09.093145Z","steps":["trace[416392306] 'process raft request' (duration: 500.165574ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:06:09.093202Z","caller":"traceutil/trace.go:171","msg":"trace[1815389626] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"500.87672ms","start":"2026-02-25T22:06:08.592288Z","end":"2026-02-25T22:06:09.093164Z","steps":["trace[1815389626] 'process raft request' (duration: 500.648155ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:06:09.093534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:06:08.592883Z","time spent":"500.46674ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:06:09.093812Z","caller":"traceutil/trace.go:171","msg":"trace[123798512] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"498.169891ms","start":"2026-02-25T22:06:08.595584Z","end":"2026-02-25T22:06:09.093754Z","steps":["trace[123798512] 'process raft request' (duration: 497.521267ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:06:09.094037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:06:08.595569Z","time spent":"498.357655ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:06:09.094226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:06:08.592271Z","time spent":"501.33604ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:06:10.751392Z","caller":"traceutil/trace.go:171","msg":"trace[494032762] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:579; }","duration":"221.01296ms","start":"2026-02-25T22:06:10.530349Z","end":"2026-02-25T22:06:10.751362Z","steps":["trace[494032762] 'read index received' (duration: 220.919628ms)","trace[494032762] 'applied index is now lower than readState.Index' (duration: 92.132µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:06:10.751511Z","caller":"traceutil/trace.go:171","msg":"trace[1842045004] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"290.471099ms","start":"2026-02-25T22:06:10.46103Z","end":"2026-02-25T22:06:10.751501Z","steps":["trace[1842045004] 'process raft request' (duration: 290.161292ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:06:10.751714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.353217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:06:10.751765Z","caller":"traceutil/trace.go:171","msg":"trace[1854984370] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:560; }","duration":"221.460529ms","start":"2026-02-25T22:06:10.530294Z","end":"2026-02-25T22:06:10.751755Z","steps":["trace[1854984370] 'agreement among raft nodes before linearized reading' (duration: 221.375667ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:06:10.754076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.322599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:06:10.754145Z","caller":"traceutil/trace.go:171","msg":"trace[1774271744] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:561; }","duration":"113.449572ms","start":"2026-02-25T22:06:10.640682Z","end":"2026-02-25T22:06:10.754131Z","steps":["trace[1774271744] 'agreement among raft nodes before linearized reading' (duration: 113.33431ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:06:10.754381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.491893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:06:10.754438Z","caller":"traceutil/trace.go:171","msg":"trace[1808259784] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:561; }","duration":"113.570035ms","start":"2026-02-25T22:06:10.640851Z","end":"2026-02-25T22:06:10.754421Z","steps":["trace[1808259784] 'agreement among raft nodes before linearized reading' (duration: 113.471773ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:06:10.754449Z","caller":"traceutil/trace.go:171","msg":"trace[512971944] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"118.150004ms","start":"2026-02-25T22:06:10.63629Z","end":"2026-02-25T22:06:10.754441Z","steps":["trace[512971944] 'process raft request' (duration: 117.654303ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:06:10.75446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.645606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:06:10.754562Z","caller":"traceutil/trace.go:171","msg":"trace[275380631] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:561; }","duration":"113.758119ms","start":"2026-02-25T22:06:10.640773Z","end":"2026-02-25T22:06:10.754531Z","steps":["trace[275380631] 'agreement among raft nodes before linearized reading' (duration: 113.570575ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:06:29.213404Z","caller":"traceutil/trace.go:171","msg":"trace[1462724636] linearizableReadLoop","detail":"{readStateIndex:750; appliedIndex:749; }","duration":"199.211172ms","start":"2026-02-25T22:06:29.014168Z","end":"2026-02-25T22:06:29.213379Z","steps":["trace[1462724636] 'read index received' (duration: 99.986724ms)","trace[1462724636] 'applied index is now lower than readState.Index' (duration: 99.223038ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:06:29.213605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.430446ms","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-25T22:06:29.213665Z","caller":"traceutil/trace.go:171","msg":"trace[175159608] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:725; }","duration":"199.556979ms","start":"2026-02-25T22:06:29.014093Z","end":"2026-02-25T22:06:29.21365Z","steps":["trace[175159608] 'agreement among raft nodes before linearized reading' (duration: 199.390685ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:06:29.523608Z","caller":"traceutil/trace.go:171","msg":"trace[1255374282] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"305.848238ms","start":"2026-02-25T22:06:29.217736Z","end":"2026-02-25T22:06:29.523584Z","steps":["trace[1255374282] 'process raft request' (duration: 305.804127ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:06:29.523779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:06:29.217725Z","time spent":"305.965181ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:06:29.523892Z","caller":"traceutil/trace.go:171","msg":"trace[1813763390] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"306.224556ms","start":"2026-02-25T22:06:29.217652Z","end":"2026-02-25T22:06:29.523877Z","steps":["trace[1813763390] 'process raft request' (duration: 305.696995ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:06:29.52398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:06:29.217629Z","time spent":"306.311518ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:06:29.523989Z","caller":"traceutil/trace.go:171","msg":"trace[365141297] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"306.303968ms","start":"2026-02-25T22:06:29.217675Z","end":"2026-02-25T22:06:29.523979Z","steps":["trace[365141297] 'process raft request' (duration: 305.822978ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:06:29.524128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:06:29.217651Z","time spent":"306.4472ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:06:52.024196Z","caller":"traceutil/trace.go:171","msg":"trace[842085056] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:823; }","duration":"390.743867ms","start":"2026-02-25T22:06:51.633431Z","end":"2026-02-25T22:06:52.024175Z","steps":["trace[842085056] 'read index received' (duration: 390.547093ms)","trace[842085056] 'applied index is now lower than readState.Index' (duration: 195.784µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:06:52.024236Z","caller":"traceutil/trace.go:171","msg":"trace[688022075] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"408.089372ms","start":"2026-02-25T22:06:51.616128Z","end":"2026-02-25T22:06:52.024217Z","steps":["trace[688022075] 'process raft request' (duration: 407.889168ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:06:52.024364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:06:51.616107Z","time spent":"408.183783ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:06:52.024549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.393306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T22:06:52.024589Z","caller":"traceutil/trace.go:171","msg":"trace[1372876927] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:794; }","duration":"292.537359ms","start":"2026-02-25T22:06:51.73204Z","end":"2026-02-25T22:06:52.024577Z","steps":["trace[1372876927] 'agreement among raft nodes before linearized reading' (duration: 292.296143ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:06:52.024582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.060383ms","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-25T22:06:52.024768Z","caller":"traceutil/trace.go:171","msg":"trace[1069963516] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:794; }","duration":"391.37573ms","start":"2026-02-25T22:06:51.633374Z","end":"2026-02-25T22:06:52.02475Z","steps":["trace[1069963516] 'agreement among raft nodes before linearized reading' (duration: 390.89642ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:06:52.02482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:06:51.633354Z","time spent":"391.455261ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T22:06:52.799981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.895123ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T22:06:52.800262Z","caller":"traceutil/trace.go:171","msg":"trace[450010848] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"771.122252ms","start":"2026-02-25T22:06:52.029118Z","end":"2026-02-25T22:06:52.80024Z","steps":["trace[450010848] 'process raft request' (duration: 53.890852ms)","trace[450010848] 'compare' (duration: 716.532395ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:06:52.80036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:06:52.029094Z","time spent":"771.222625ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:06:52.800336Z","caller":"traceutil/trace.go:171","msg":"trace[1458591871] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"770.815046ms","start":"2026-02-25T22:06:52.029444Z","end":"2026-02-25T22:06:52.800259Z","steps":["trace[1458591871] 'process raft request' (duration: 770.629972ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:06:52.800528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:06:52.029421Z","time spent":"771.023711ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:06:53.039885Z","caller":"traceutil/trace.go:171","msg":"trace[208364795] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"236.054712ms","start":"2026-02-25T22:06:52.8038Z","end":"2026-02-25T22:06:53.039855Z","steps":["trace[208364795] 'process raft request' (duration: 235.870908ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:06:54.248495Z","caller":"traceutil/trace.go:171","msg":"trace[823337833] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"215.641211ms","start":"2026-02-25T22:06:54.032827Z","end":"2026-02-25T22:06:54.248468Z","steps":["trace[823337833] 'process raft request' (duration: 215.497328ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:06:55.252064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.43164ms","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-25T22:06:55.252255Z","caller":"traceutil/trace.go:171","msg":"trace[1715935287] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:801; }","duration":"447.697366ms","start":"2026-02-25T22:06:54.804537Z","end":"2026-02-25T22:06:55.252235Z","steps":["trace[1715935287] 'range keys from in-memory index tree' (duration: 447.252166ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:06:55.252328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:06:54.804518Z","time spent":"447.796648ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T22:06:55.25238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.291134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-25T22:06:55.25208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.374619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T22:06:55.252491Z","caller":"traceutil/trace.go:171","msg":"trace[1413358176] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:801; }","duration":"208.448437ms","start":"2026-02-25T22:06:55.044018Z","end":"2026-02-25T22:06:55.252467Z","steps":["trace[1413358176] 'range keys from in-memory index tree' (duration: 208.098869ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:06:55.252536Z","caller":"traceutil/trace.go:171","msg":"trace[1711849255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:801; }","duration":"447.86795ms","start":"2026-02-25T22:06:54.804642Z","end":"2026-02-25T22:06:55.25251Z","steps":["trace[1711849255] 'range keys from in-memory index tree' (duration: 447.212226ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:06:55.252615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:06:54.804575Z","time spent":"448.020333ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T22:06:55.42489Z","caller":"traceutil/trace.go:171","msg":"trace[1664311853] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"167.954073ms","start":"2026-02-25T22:06:55.256908Z","end":"2026-02-25T22:06:55.424862Z","steps":["trace[1664311853] 'process raft request' (duration: 167.679707ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:06:55.947796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.789813ms","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-25T22:06:55.947981Z","caller":"traceutil/trace.go:171","msg":"trace[1298072606] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:802; }","duration":"298.055549ms","start":"2026-02-25T22:06:55.649896Z","end":"2026-02-25T22:06:55.947952Z","steps":["trace[1298072606] 'range keys from in-memory index tree' (duration: 297.769353ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:06:56.102397Z","caller":"traceutil/trace.go:171","msg":"trace[299156770] linearizableReadLoop","detail":"{readStateIndex:835; appliedIndex:832; }","duration":"678.146616ms","start":"2026-02-25T22:06:55.424234Z","end":"2026-02-25T22:06:56.102381Z","steps":["trace[299156770] 'read index received' (duration: 438.46µs)","trace[299156770] 'applied index is now lower than readState.Index' (duration: 677.707436ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:06:56.102644Z","caller":"traceutil/trace.go:171","msg":"trace[1057281005] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"845.657539ms","start":"2026-02-25T22:06:55.256979Z","end":"2026-02-25T22:06:56.102636Z","steps":["trace[1057281005] 'process raft request' (duration: 845.24478ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:06:56.102723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:06:55.256964Z","time spent":"845.70283ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:06:56.102818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.345826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:06:56.102941Z","caller":"traceutil/trace.go:171","msg":"trace[1538881438] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:804; }","duration":"573.513279ms","start":"2026-02-25T22:06:55.529399Z","end":"2026-02-25T22:06:56.102913Z","steps":["trace[1538881438] 'agreement among raft nodes before linearized reading' (duration: 573.329345ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:06:56.10294Z","caller":"traceutil/trace.go:171","msg":"trace[1085309946] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"845.880763ms","start":"2026-02-25T22:06:55.257002Z","end":"2026-02-25T22:06:56.102882Z","steps":["trace[1085309946] 'process raft request' (duration: 845.338832ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:06:56.103011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:06:55.529382Z","time spent":"573.616362ms","remote":"127.0.0.1:48324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T22:06:56.102989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.748228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-25T22:06:56.103288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:06:55.256979Z","time spent":"846.082408ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:06:56.103326Z","caller":"traceutil/trace.go:171","msg":"trace[1867153395] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:804; }","duration":"679.120186ms","start":"2026-02-25T22:06:55.424191Z","end":"2026-02-25T22:06:56.103311Z","steps":["trace[1867153395] 'agreement among raft nodes before linearized reading' (duration: 678.682627ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:06:56.103379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:06:55.424173Z","time spent":"679.185457ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T22:06:56.334124Z","caller":"traceutil/trace.go:171","msg":"trace[1527224774] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"226.004584ms","start":"2026-02-25T22:06:56.108088Z","end":"2026-02-25T22:06:56.334093Z","steps":["trace[1527224774] 'process raft request' (duration: 194.194128ms)","trace[1527224774] 'compare' (duration: 31.414228ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:07:06.586499Z","caller":"traceutil/trace.go:171","msg":"trace[1224196165] linearizableReadLoop","detail":"{readStateIndex:918; appliedIndex:917; }","duration":"426.614261ms","start":"2026-02-25T22:07:06.159851Z","end":"2026-02-25T22:07:06.586465Z","steps":["trace[1224196165] 'read index received' (duration: 426.397846ms)","trace[1224196165] 'applied index is now lower than readState.Index' (duration: 214.695µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:07:06.586616Z","caller":"traceutil/trace.go:171","msg":"trace[1760134695] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"430.366881ms","start":"2026-02-25T22:07:06.156217Z","end":"2026-02-25T22:07:06.586584Z","steps":["trace[1760134695] 'process raft request' (duration: 430.028994ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:07:06.586758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.887488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-02-25T22:07:06.58679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:07:06.156188Z","time spent":"430.506306ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:07:06.58682Z","caller":"traceutil/trace.go:171","msg":"trace[883757231] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:883; }","duration":"427.007891ms","start":"2026-02-25T22:07:06.159797Z","end":"2026-02-25T22:07:06.586805Z","steps":["trace[883757231] 'agreement among raft nodes before linearized reading' (duration: 426.813646ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:07:06.586853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.497687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T22:07:06.58685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:07:06.159777Z","time spent":"427.067312ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T22:07:06.586908Z","caller":"traceutil/trace.go:171","msg":"trace[1167171569] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:883; }","duration":"137.628329ms","start":"2026-02-25T22:07:06.449264Z","end":"2026-02-25T22:07:06.586892Z","steps":["trace[1167171569] 'agreement among raft nodes before linearized reading' (duration: 137.464675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:07:06.586917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.487126ms","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-25T22:07:06.586999Z","caller":"traceutil/trace.go:171","msg":"trace[443817005] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:883; }","duration":"105.621459ms","start":"2026-02-25T22:07:06.481356Z","end":"2026-02-25T22:07:06.586977Z","steps":["trace[443817005] 'agreement among raft nodes before linearized reading' (duration: 105.376053ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:07:06.587518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.267514ms","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-25T22:07:06.587557Z","caller":"traceutil/trace.go:171","msg":"trace[2020005137] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:883; }","duration":"209.326065ms","start":"2026-02-25T22:07:06.378219Z","end":"2026-02-25T22:07:06.587545Z","steps":["trace[2020005137] 'agreement among raft nodes before linearized reading' (duration: 208.457615ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:07:08.146247Z","caller":"traceutil/trace.go:171","msg":"trace[960399405] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"668.541709ms","start":"2026-02-25T22:07:07.477682Z","end":"2026-02-25T22:07:08.146224Z","steps":["trace[960399405] 'process raft request' (duration: 668.417746ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:07:08.146415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:07:07.477664Z","time spent":"668.648331ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:07:08.644424Z","caller":"traceutil/trace.go:171","msg":"trace[2048304027] linearizableReadLoop","detail":"{readStateIndex:923; appliedIndex:922; }","duration":"325.259265ms","start":"2026-02-25T22:07:08.319146Z","end":"2026-02-25T22:07:08.644405Z","steps":["trace[2048304027] 'read index received' (duration: 325.179394ms)","trace[2048304027] 'applied index is now lower than readState.Index' (duration: 79.011µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:07:08.644482Z","caller":"traceutil/trace.go:171","msg":"trace[1747599040] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"905.677294ms","start":"2026-02-25T22:07:07.738779Z","end":"2026-02-25T22:07:08.644456Z","steps":["trace[1747599040] 'process raft request' (duration: 905.505651ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:07:08.644585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.418819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T22:07:08.644615Z","caller":"traceutil/trace.go:171","msg":"trace[1848201086] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:888; }","duration":"325.494261ms","start":"2026-02-25T22:07:08.319113Z","end":"2026-02-25T22:07:08.644608Z","steps":["trace[1848201086] 'agreement among raft nodes before linearized reading' (duration: 325.389898ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:07:08.644627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:07:07.738756Z","time spent":"905.788026ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:07:08.644641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:07:08.3191Z","time spent":"325.534291ms","remote":"127.0.0.1:48640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":68,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-02-25T22:07:08.644639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.071033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T22:07:08.644711Z","caller":"traceutil/trace.go:171","msg":"trace[1399049334] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:888; }","duration":"116.182815ms","start":"2026-02-25T22:07:08.528513Z","end":"2026-02-25T22:07:08.644695Z","steps":["trace[1399049334] 'agreement among raft nodes before linearized reading' (duration: 116.049723ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:07:08.644923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.512831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:07:08.644961Z","caller":"traceutil/trace.go:171","msg":"trace[1578682802] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:888; }","duration":"115.569032ms","start":"2026-02-25T22:07:08.529383Z","end":"2026-02-25T22:07:08.644952Z","steps":["trace[1578682802] 'agreement among raft nodes before linearized reading' (duration: 115.512261ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:07:22.325373Z","caller":"traceutil/trace.go:171","msg":"trace[1024568078] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"103.236877ms","start":"2026-02-25T22:07:22.222112Z","end":"2026-02-25T22:07:22.325348Z","steps":["trace[1024568078] 'process raft request' (duration: 103.080814ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:07:24.652872Z","caller":"traceutil/trace.go:171","msg":"trace[590878130] linearizableReadLoop","detail":"{readStateIndex:986; appliedIndex:985; }","duration":"308.046075ms","start":"2026-02-25T22:07:24.344802Z","end":"2026-02-25T22:07:24.652848Z","steps":["trace[590878130] 'read index received' (duration: 307.84585ms)","trace[590878130] 'applied index is now lower than readState.Index' (duration: 199.335µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:07:24.653148Z","caller":"traceutil/trace.go:171","msg":"trace[1883300233] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"319.776857ms","start":"2026-02-25T22:07:24.33336Z","end":"2026-02-25T22:07:24.653137Z","steps":["trace[1883300233] 'process raft request' (duration: 319.342657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:07:24.653242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:07:24.333319Z","time spent":"319.85601ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:07:24.653527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.722179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5184"} {"level":"info","ts":"2026-02-25T22:07:24.653561Z","caller":"traceutil/trace.go:171","msg":"trace[321167062] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:947; }","duration":"308.77828ms","start":"2026-02-25T22:07:24.344771Z","end":"2026-02-25T22:07:24.65355Z","steps":["trace[321167062] 'agreement among raft nodes before linearized reading' (duration: 308.668137ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:07:24.653588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:07:24.344757Z","time spent":"308.826071ms","remote":"127.0.0.1:48480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5207,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-25T22:07:24.653681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.237711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:07:24.653737Z","caller":"traceutil/trace.go:171","msg":"trace[1112385146] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:947; }","duration":"124.349233ms","start":"2026-02-25T22:07:24.529374Z","end":"2026-02-25T22:07:24.653724Z","steps":["trace[1112385146] 'agreement among raft nodes before linearized reading' (duration: 124.2158ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:07:24.822827Z","caller":"traceutil/trace.go:171","msg":"trace[5315229] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"157.62551ms","start":"2026-02-25T22:07:24.665173Z","end":"2026-02-25T22:07:24.822798Z","steps":["trace[5315229] 'process raft request' (duration: 156.846073ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:07:25.019748Z","caller":"traceutil/trace.go:171","msg":"trace[559256991] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:988; }","duration":"160.727178ms","start":"2026-02-25T22:07:24.858994Z","end":"2026-02-25T22:07:25.019721Z","steps":["trace[559256991] 'read index received' (duration: 37.037369ms)","trace[559256991] 'applied index is now lower than readState.Index' (duration: 123.678548ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:07:25.020068Z","caller":"traceutil/trace.go:171","msg":"trace[1367051031] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"191.181405ms","start":"2026-02-25T22:07:24.82887Z","end":"2026-02-25T22:07:25.020051Z","steps":["trace[1367051031] 'process raft request' (duration: 190.488689ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:07:25.020403Z","caller":"traceutil/trace.go:171","msg":"trace[745482319] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"191.370668ms","start":"2026-02-25T22:07:24.82902Z","end":"2026-02-25T22:07:25.020391Z","steps":["trace[745482319] 'process raft request' (duration: 190.545279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:07:25.02327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.14078ms","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-25T22:07:25.023308Z","caller":"traceutil/trace.go:171","msg":"trace[77823690] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:954; }","duration":"164.251133ms","start":"2026-02-25T22:07:24.859047Z","end":"2026-02-25T22:07:25.023298Z","steps":["trace[77823690] 'agreement among raft nodes before linearized reading' (duration: 164.10595ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:07:25.02341Z","caller":"traceutil/trace.go:171","msg":"trace[1079864256] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"194.281471ms","start":"2026-02-25T22:07:24.829122Z","end":"2026-02-25T22:07:25.023403Z","steps":["trace[1079864256] 'process raft request' (duration: 190.493398ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:07:25.023789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.795315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T22:07:25.023825Z","caller":"traceutil/trace.go:171","msg":"trace[1788325566] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:954; }","duration":"164.871536ms","start":"2026-02-25T22:07:24.858945Z","end":"2026-02-25T22:07:25.023816Z","steps":["trace[1788325566] 'agreement among raft nodes before linearized reading' (duration: 164.796075ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:07:25.023752Z","caller":"traceutil/trace.go:171","msg":"trace[740124844] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"190.987419ms","start":"2026-02-25T22:07:24.832711Z","end":"2026-02-25T22:07:25.023699Z","steps":["trace[740124844] 'process raft request' (duration: 186.950041ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:07:25.023782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.603424ms","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-25T22:07:25.024037Z","caller":"traceutil/trace.go:171","msg":"trace[1934479916] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:954; }","duration":"160.89263ms","start":"2026-02-25T22:07:24.86312Z","end":"2026-02-25T22:07:25.024012Z","steps":["trace[1934479916] 'agreement among raft nodes before linearized reading' (duration: 160.489611ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:07:25.188794Z","caller":"traceutil/trace.go:171","msg":"trace[918005159] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"157.727532ms","start":"2026-02-25T22:07:25.031028Z","end":"2026-02-25T22:07:25.188756Z","steps":["trace[918005159] 'process raft request' (duration: 76.448839ms)","trace[918005159] 'compare' (duration: 81.070749ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:07:25.221006Z","caller":"traceutil/trace.go:171","msg":"trace[1521609936] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"189.714361ms","start":"2026-02-25T22:07:25.031274Z","end":"2026-02-25T22:07:25.220988Z","steps":["trace[1521609936] 'process raft request' (duration: 189.557277ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:07:25.221124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.221173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T22:07:25.221097Z","caller":"traceutil/trace.go:171","msg":"trace[1800114284] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"189.062837ms","start":"2026-02-25T22:07:25.031982Z","end":"2026-02-25T22:07:25.221044Z","steps":["trace[1800114284] 'process raft request' (duration: 188.962295ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:07:25.22116Z","caller":"traceutil/trace.go:171","msg":"trace[190609240] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:957; }","duration":"184.325925ms","start":"2026-02-25T22:07:25.036825Z","end":"2026-02-25T22:07:25.221151Z","steps":["trace[190609240] 'agreement among raft nodes before linearized reading' (duration: 184.224483ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:07:25.221007Z","caller":"traceutil/trace.go:171","msg":"trace[1427799193] linearizableReadLoop","detail":"{readStateIndex:996; appliedIndex:993; }","duration":"184.10749ms","start":"2026-02-25T22:07:25.036878Z","end":"2026-02-25T22:07:25.220985Z","steps":["trace[1427799193] 'read index received' (duration: 70.709595ms)","trace[1427799193] 'applied index is now lower than readState.Index' (duration: 113.396845ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:07:27.241367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.629191ms","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-25T22:07:27.241442Z","caller":"traceutil/trace.go:171","msg":"trace[2085555968] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:958; }","duration":"209.748834ms","start":"2026-02-25T22:07:27.031674Z","end":"2026-02-25T22:07:27.241423Z","steps":["trace[2085555968] 'range keys from in-memory index tree' (duration: 209.499908ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:07:27.602966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.873118ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T22:07:27.603423Z","caller":"traceutil/trace.go:171","msg":"trace[1540144203] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"357.334128ms","start":"2026-02-25T22:07:27.246071Z","end":"2026-02-25T22:07:27.603405Z","steps":["trace[1540144203] 'process raft request' (duration: 357.288447ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:07:27.603781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:07:27.24605Z","time spent":"357.691325ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:07:27.60342Z","caller":"traceutil/trace.go:171","msg":"trace[718611670] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"358.007022ms","start":"2026-02-25T22:07:27.245373Z","end":"2026-02-25T22:07:27.60338Z","steps":["trace[718611670] 'process raft request' (duration: 166.634033ms)","trace[718611670] 'compare' (duration: 190.770896ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:07:27.604082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:07:27.245357Z","time spent":"358.692057ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:07:27.603515Z","caller":"traceutil/trace.go:171","msg":"trace[1680470884] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"357.767057ms","start":"2026-02-25T22:07:27.245732Z","end":"2026-02-25T22:07:27.603499Z","steps":["trace[1680470884] 'process raft request' (duration: 357.572833ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:07:27.60462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:07:27.245722Z","time spent":"358.809789ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:07:27.612908Z","caller":"traceutil/trace.go:171","msg":"trace[1308685046] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"165.00355ms","start":"2026-02-25T22:07:27.447894Z","end":"2026-02-25T22:07:27.612897Z","steps":["trace[1308685046] 'process raft request' (duration: 164.785695ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:07:28.519313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.748524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T22:07:28.519391Z","caller":"traceutil/trace.go:171","msg":"trace[2053236674] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:964; }","duration":"100.852016ms","start":"2026-02-25T22:07:28.418522Z","end":"2026-02-25T22:07:28.519374Z","steps":["trace[2053236674] 'count revisions from in-memory index tree' (duration: 100.670922ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:07:28.671411Z","caller":"traceutil/trace.go:171","msg":"trace[132505372] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"134.011931ms","start":"2026-02-25T22:07:28.537375Z","end":"2026-02-25T22:07:28.671387Z","steps":["trace[132505372] 'process raft request' (duration: 85.595207ms)","trace[132505372] 'compare' (duration: 47.633827ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:07:29.31427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.165386ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T22:07:29.314578Z","caller":"traceutil/trace.go:171","msg":"trace[924367373] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"217.386899ms","start":"2026-02-25T22:07:29.09718Z","end":"2026-02-25T22:07:29.314567Z","steps":["trace[924367373] 'process raft request' (duration: 217.159424ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:07:29.44189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.725308ms","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-25T22:07:29.441972Z","caller":"traceutil/trace.go:171","msg":"trace[295221011] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:971; }","duration":"167.8494ms","start":"2026-02-25T22:07:29.274102Z","end":"2026-02-25T22:07:29.441952Z","steps":["trace[295221011] 'agreement among raft nodes before linearized reading' (duration: 40.450943ms)","trace[295221011] 'range keys from in-memory index tree' (duration: 127.205953ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:07:29.936436Z","caller":"traceutil/trace.go:171","msg":"trace[1244273652] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"490.335127ms","start":"2026-02-25T22:07:29.446069Z","end":"2026-02-25T22:07:29.936404Z","steps":["trace[1244273652] 'process raft request' (duration: 490.059361ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:07:29.936419Z","caller":"traceutil/trace.go:171","msg":"trace[1061254484] linearizableReadLoop","detail":"{readStateIndex:1012; appliedIndex:1011; }","duration":"408.607433ms","start":"2026-02-25T22:07:29.527777Z","end":"2026-02-25T22:07:29.936384Z","steps":["trace[1061254484] 'read index received' (duration: 408.314016ms)","trace[1061254484] 'applied index is now lower than readState.Index' (duration: 291.957µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:07:29.93653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.730566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:07:29.936566Z","caller":"traceutil/trace.go:171","msg":"trace[318378066] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:972; }","duration":"408.805548ms","start":"2026-02-25T22:07:29.527751Z","end":"2026-02-25T22:07:29.936556Z","steps":["trace[318378066] 'agreement among raft nodes before linearized reading' (duration: 408.712306ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:07:29.936573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:07:29.446051Z","time spent":"490.457539ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:07:29.936593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:07:29.527738Z","time spent":"408.849798ms","remote":"127.0.0.1:48324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T22:07:29.936869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.062127ms","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-25T22:07:29.936927Z","caller":"traceutil/trace.go:171","msg":"trace[1075853590] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:972; }","duration":"329.18687ms","start":"2026-02-25T22:07:29.607727Z","end":"2026-02-25T22:07:29.936914Z","steps":["trace[1075853590] 'agreement among raft nodes before linearized reading' (duration: 328.968536ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:07:29.936992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:07:29.607708Z","time spent":"329.269403ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T22:07:29.936936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.272391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T22:07:29.937048Z","caller":"traceutil/trace.go:171","msg":"trace[1545705128] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:972; }","duration":"328.393653ms","start":"2026-02-25T22:07:29.608642Z","end":"2026-02-25T22:07:29.937036Z","steps":["trace[1545705128] 'agreement among raft nodes before linearized reading' (duration: 328.188419ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:07:29.937079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:07:29.608635Z","time spent":"328.435634ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T22:07:29.936936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.68795ms","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-25T22:07:29.937138Z","caller":"traceutil/trace.go:171","msg":"trace[538361988] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:972; }","duration":"328.907055ms","start":"2026-02-25T22:07:29.608224Z","end":"2026-02-25T22:07:29.937131Z","steps":["trace[538361988] 'agreement among raft nodes before linearized reading' (duration: 328.613379ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:07:29.937158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:07:29.608218Z","time spent":"328.934125ms","remote":"127.0.0.1:48562","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-25T22:07:30.44445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.596014ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T22:07:30.444542Z","caller":"traceutil/trace.go:171","msg":"trace[1149291717] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"502.787143ms","start":"2026-02-25T22:07:29.941738Z","end":"2026-02-25T22:07:30.444525Z","steps":["trace[1149291717] 'process raft request' (duration: 48.049416ms)","trace[1149291717] 'compare' (duration: 454.464472ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:07:30.444596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:07:29.941724Z","time spent":"502.845745ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:07:30.449672Z","caller":"traceutil/trace.go:171","msg":"trace[1583527514] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"506.144237ms","start":"2026-02-25T22:07:29.943511Z","end":"2026-02-25T22:07:30.449656Z","steps":["trace[1583527514] 'process raft request' (duration: 506.105626ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:07:30.449762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:07:29.943504Z","time spent":"506.216419ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:07:30.44977Z","caller":"traceutil/trace.go:171","msg":"trace[2094234729] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"507.770292ms","start":"2026-02-25T22:07:29.94196Z","end":"2026-02-25T22:07:30.44973Z","steps":["trace[2094234729] 'process raft request' (duration: 507.562727ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:07:30.450316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:07:29.941952Z","time spent":"508.024837ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:07:30.720202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.161196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:07:30.720294Z","caller":"traceutil/trace.go:171","msg":"trace[299891534] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:975; }","duration":"142.290018ms","start":"2026-02-25T22:07:30.577979Z","end":"2026-02-25T22:07:30.720269Z","steps":["trace[299891534] 'count revisions from in-memory index tree' (duration: 142.035422ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:07:32.455286Z","caller":"traceutil/trace.go:171","msg":"trace[354015002] linearizableReadLoop","detail":"{readStateIndex:1017; appliedIndex:1016; }","duration":"321.377113ms","start":"2026-02-25T22:07:32.133869Z","end":"2026-02-25T22:07:32.455246Z","steps":["trace[354015002] 'read index received' (duration: 321.217749ms)","trace[354015002] 'applied index is now lower than readState.Index' (duration: 158.634µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:07:32.455522Z","caller":"traceutil/trace.go:171","msg":"trace[777988136] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"509.708634ms","start":"2026-02-25T22:07:31.945806Z","end":"2026-02-25T22:07:32.455514Z","steps":["trace[777988136] 'process raft request' (duration: 509.336816ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:07:32.455601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:07:31.945771Z","time spent":"509.769725ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:07:32.455745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.875393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T22:07:32.455774Z","caller":"traceutil/trace.go:171","msg":"trace[1473802832] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:977; }","duration":"321.904674ms","start":"2026-02-25T22:07:32.133861Z","end":"2026-02-25T22:07:32.455766Z","steps":["trace[1473802832] 'agreement among raft nodes before linearized reading' (duration: 321.847122ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:07:32.455793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:07:32.133791Z","time spent":"321.997165ms","remote":"127.0.0.1:48694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":30,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-02-25T22:07:32.455909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.394913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:07:32.455935Z","caller":"traceutil/trace.go:171","msg":"trace[969015080] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:977; }","duration":"302.468054ms","start":"2026-02-25T22:07:32.153459Z","end":"2026-02-25T22:07:32.455927Z","steps":["trace[969015080] 'agreement among raft nodes before linearized reading' (duration: 302.428193ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:07:32.455955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:07:32.153436Z","time spent":"302.511674ms","remote":"127.0.0.1:58866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-02-25T22:07:32.925926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.686323ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T22:07:32.926277Z","caller":"traceutil/trace.go:171","msg":"trace[1286107955] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"377.181196ms","start":"2026-02-25T22:07:32.549049Z","end":"2026-02-25T22:07:32.92623Z","steps":["trace[1286107955] 'process raft request' (duration: 377.015242ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:07:32.926296Z","caller":"traceutil/trace.go:171","msg":"trace[133588077] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"377.318959ms","start":"2026-02-25T22:07:32.548949Z","end":"2026-02-25T22:07:32.926268Z","steps":["trace[133588077] 'process raft request' (duration: 193.172986ms)","trace[133588077] 'compare' (duration: 183.53003ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:07:32.92648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:07:32.548929Z","time spent":"377.450392ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:07:32.926486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:07:32.549015Z","time spent":"377.36429ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:07:33.276809Z","caller":"traceutil/trace.go:171","msg":"trace[1639584426] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"345.762718ms","start":"2026-02-25T22:07:32.931014Z","end":"2026-02-25T22:07:33.276776Z","steps":["trace[1639584426] 'process raft request' (duration: 319.566533ms)","trace[1639584426] 'compare' (duration: 26.068602ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:07:33.27703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:07:32.930988Z","time spent":"345.90185ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:07:38.624021Z","caller":"traceutil/trace.go:171","msg":"trace[300722193] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"139.283215ms","start":"2026-02-25T22:07:38.484693Z","end":"2026-02-25T22:07:38.623976Z","steps":["trace[300722193] 'process raft request' (duration: 138.921057ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:07:39.537711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.100775ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T22:07:39.537821Z","caller":"traceutil/trace.go:171","msg":"trace[78159000] linearizableReadLoop","detail":"{readStateIndex:1071; appliedIndex:1070; }","duration":"775.519287ms","start":"2026-02-25T22:07:38.762284Z","end":"2026-02-25T22:07:39.537803Z","steps":["trace[78159000] 'read index received' (duration: 2.145207ms)","trace[78159000] 'applied index is now lower than readState.Index' (duration: 773.37292ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:07:39.537892Z","caller":"traceutil/trace.go:171","msg":"trace[396486367] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"901.438813ms","start":"2026-02-25T22:07:38.636408Z","end":"2026-02-25T22:07:39.537847Z","steps":["trace[396486367] 'process raft request' (duration: 128.091473ms)","trace[396486367] 'compare' (duration: 772.958402ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:07:39.538003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.714581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-25T22:07:39.538038Z","caller":"traceutil/trace.go:171","msg":"trace[1210425623] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1030; }","duration":"775.779072ms","start":"2026-02-25T22:07:38.762247Z","end":"2026-02-25T22:07:39.538026Z","steps":["trace[1210425623] 'agreement among raft nodes before linearized reading' (duration: 775.628259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:07:39.538077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:07:38.76223Z","time spent":"775.838604ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-25T22:07:39.538079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:07:38.636379Z","time spent":"901.596646ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:07:39.758301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.598452ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T22:07:39.758498Z","caller":"traceutil/trace.go:171","msg":"trace[107383094] linearizableReadLoop","detail":"{readStateIndex:1072; appliedIndex:1071; }","duration":"220.634849ms","start":"2026-02-25T22:07:39.537847Z","end":"2026-02-25T22:07:39.758481Z","steps":["trace[107383094] 'read index received' (duration: 48.998978ms)","trace[107383094] 'applied index is now lower than readState.Index' (duration: 171.634601ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:07:39.758554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.839989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T22:07:39.758841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.072177ms","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-25T22:07:39.758878Z","caller":"traceutil/trace.go:171","msg":"trace[1580354811] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1030; }","duration":"371.219117ms","start":"2026-02-25T22:07:39.387649Z","end":"2026-02-25T22:07:39.758868Z","steps":["trace[1580354811] 'agreement among raft nodes before linearized reading' (duration: 370.843179ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:07:39.75865Z","caller":"traceutil/trace.go:171","msg":"trace[1597768593] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"216.067071ms","start":"2026-02-25T22:07:39.542569Z","end":"2026-02-25T22:07:39.758636Z","steps":["trace[1597768593] 'process raft request' (duration: 215.844206ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:07:39.75893Z","caller":"traceutil/trace.go:171","msg":"trace[1377081240] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1031; }","duration":"807.22691ms","start":"2026-02-25T22:07:38.951679Z","end":"2026-02-25T22:07:39.758906Z","steps":["trace[1377081240] 'agreement among raft nodes before linearized reading' (duration: 806.880683ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:07:39.758968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:07:39.387627Z","time spent":"371.328999ms","remote":"127.0.0.1:48710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-02-25T22:07:39.759034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:07:38.951643Z","time spent":"807.378154ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T22:07:39.75871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.805124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-25T22:07:39.759079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.394196ms","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-25T22:07:39.759107Z","caller":"traceutil/trace.go:171","msg":"trace[528187518] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1030; }","duration":"426.230223ms","start":"2026-02-25T22:07:39.332852Z","end":"2026-02-25T22:07:39.759082Z","steps":["trace[528187518] 'agreement among raft nodes before linearized reading' (duration: 425.681261ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:07:39.759146Z","caller":"traceutil/trace.go:171","msg":"trace[2018224966] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1031; }","duration":"808.514569ms","start":"2026-02-25T22:07:38.950615Z","end":"2026-02-25T22:07:39.75913Z","steps":["trace[2018224966] 'agreement among raft nodes before linearized reading' (duration: 808.138171ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:07:39.759168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:07:39.332828Z","time spent":"426.322205ms","remote":"127.0.0.1:48562","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-25T22:07:39.75918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:07:38.950592Z","time spent":"808.5822ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T22:07:39.758763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.997322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-25T22:07:39.759273Z","caller":"traceutil/trace.go:171","msg":"trace[138059783] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1030; }","duration":"307.574064ms","start":"2026-02-25T22:07:39.451684Z","end":"2026-02-25T22:07:39.759258Z","steps":["trace[138059783] 'agreement among raft nodes before linearized reading' (duration: 306.836128ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:07:39.759324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:07:39.451651Z","time spent":"307.661406ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-02-25T22:07:39.769372Z","caller":"traceutil/trace.go:171","msg":"trace[1116867864] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"134.724076ms","start":"2026-02-25T22:07:39.634629Z","end":"2026-02-25T22:07:39.769353Z","steps":["trace[1116867864] 'process raft request' (duration: 134.627284ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:07:39.876483Z","caller":"traceutil/trace.go:171","msg":"trace[1768750719] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"113.54027ms","start":"2026-02-25T22:07:39.762917Z","end":"2026-02-25T22:07:39.876457Z","steps":["trace[1768750719] 'process raft request' (duration: 113.364426ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:07:39.876978Z","caller":"traceutil/trace.go:171","msg":"trace[1593711378] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"114.04232ms","start":"2026-02-25T22:07:39.762916Z","end":"2026-02-25T22:07:39.876959Z","steps":["trace[1593711378] 'process raft request' (duration: 113.501259ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:07:39.877174Z","caller":"traceutil/trace.go:171","msg":"trace[1758503723] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"113.915998ms","start":"2026-02-25T22:07:39.76325Z","end":"2026-02-25T22:07:39.877166Z","steps":["trace[1758503723] 'process raft request' (duration: 113.653662ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:07:39.877395Z","caller":"traceutil/trace.go:171","msg":"trace[1529235292] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"113.539578ms","start":"2026-02-25T22:07:39.763847Z","end":"2026-02-25T22:07:39.877387Z","steps":["trace[1529235292] 'process raft request' (duration: 113.247712ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:07:39.877482Z","caller":"traceutil/trace.go:171","msg":"trace[829181846] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"104.684607ms","start":"2026-02-25T22:07:39.77278Z","end":"2026-02-25T22:07:39.877465Z","steps":["trace[829181846] 'process raft request' (duration: 104.572095ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:10:39.664603Z","caller":"traceutil/trace.go:171","msg":"trace[726983744] transaction","detail":"{read_only:false; response_revision:1798; number_of_response:1; }","duration":"144.364213ms","start":"2026-02-25T22:10:39.520211Z","end":"2026-02-25T22:10:39.664575Z","steps":["trace[726983744] 'process raft request' (duration: 144.21835ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:10:40.394111Z","caller":"traceutil/trace.go:171","msg":"trace[1001347493] transaction","detail":"{read_only:false; response_revision:1800; number_of_response:1; }","duration":"119.325464ms","start":"2026-02-25T22:10:40.27476Z","end":"2026-02-25T22:10:40.394085Z","steps":["trace[1001347493] 'process raft request' (duration: 119.191061ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:10:40.561107Z","caller":"traceutil/trace.go:171","msg":"trace[212291467] transaction","detail":"{read_only:false; response_revision:1801; number_of_response:1; }","duration":"127.909849ms","start":"2026-02-25T22:10:40.433165Z","end":"2026-02-25T22:10:40.561075Z","steps":["trace[212291467] 'process raft request' (duration: 120.606021ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:10:40.9381Z","caller":"traceutil/trace.go:171","msg":"trace[989637526] linearizableReadLoop","detail":"{readStateIndex:1883; appliedIndex:1880; }","duration":"151.234271ms","start":"2026-02-25T22:10:40.786842Z","end":"2026-02-25T22:10:40.938076Z","steps":["trace[989637526] 'read index received' (duration: 5.339356ms)","trace[989637526] 'applied index is now lower than readState.Index' (duration: 145.893835ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:10:40.938219Z","caller":"traceutil/trace.go:171","msg":"trace[448677462] transaction","detail":"{read_only:false; response_revision:1804; number_of_response:1; }","duration":"187.236908ms","start":"2026-02-25T22:10:40.750951Z","end":"2026-02-25T22:10:40.938188Z","steps":["trace[448677462] 'process raft request' (duration: 187.081274ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:10:40.938416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.558458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-25T22:10:40.938465Z","caller":"traceutil/trace.go:171","msg":"trace[197932840] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1804; }","duration":"151.639039ms","start":"2026-02-25T22:10:40.786813Z","end":"2026-02-25T22:10:40.938452Z","steps":["trace[197932840] 'agreement among raft nodes before linearized reading' (duration: 151.474026ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:10:40.938143Z","caller":"traceutil/trace.go:171","msg":"trace[858234287] transaction","detail":"{read_only:false; response_revision:1803; number_of_response:1; }","duration":"187.477582ms","start":"2026-02-25T22:10:40.750637Z","end":"2026-02-25T22:10:40.938114Z","steps":["trace[858234287] 'process raft request' (duration: 187.259648ms)"],"step_count":1}