{"level":"warn","ts":"2026-02-25T06:11:18.314463Z","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-25T06:11:18.314577Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.244: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.244:2380","--initial-cluster=instance=https://199.204.45.244:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.244:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.244: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-25T06:11:18.314657Z","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-25T06:11:18.314668Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.244:2380"]} {"level":"info","ts":"2026-02-25T06:11:18.314694Z","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-25T06:11:18.315368Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"]} {"level":"info","ts":"2026-02-25T06:11:18.315448Z","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.244:2380"],"listen-peer-urls":["https://199.204.45.244:2380"],"advertise-client-urls":["https://199.204.45.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.244: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-25T06:11:18.319343Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.662491ms"} {"level":"info","ts":"2026-02-25T06:11:18.328747Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1686e9b1ea717edf","cluster-id":"71c60fe001ca5b3c"} {"level":"info","ts":"2026-02-25T06:11:18.32888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T06:11:18.328912Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became follower at term 0"} {"level":"info","ts":"2026-02-25T06:11:18.32893Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1686e9b1ea717edf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T06:11:18.32894Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became follower at term 1"} {"level":"info","ts":"2026-02-25T06:11:18.328981Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=(1623241666065432287)"} {"level":"warn","ts":"2026-02-25T06:11:18.335769Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T06:11:18.338902Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T06:11:18.341302Z","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-25T06:11:18.343929Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1686e9b1ea717edf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T06:11:18.345641Z","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-25T06:11:18.345749Z","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-25T06:11:18.345784Z","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-25T06:11:18.345836Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1686e9b1ea717edf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T06:11:18.346513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=(1623241666065432287)"} {"level":"info","ts":"2026-02-25T06:11:18.346969Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"71c60fe001ca5b3c","local-member-id":"1686e9b1ea717edf","added-peer-id":"1686e9b1ea717edf","added-peer-peer-urls":["https://199.204.45.244:2380"]} {"level":"info","ts":"2026-02-25T06:11:18.348338Z","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-25T06:11:18.348495Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.244:2380"} {"level":"info","ts":"2026-02-25T06:11:18.348539Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.244:2380"} {"level":"info","ts":"2026-02-25T06:11:18.349281Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1686e9b1ea717edf","initial-advertise-peer-urls":["https://199.204.45.244:2380"],"listen-peer-urls":["https://199.204.45.244:2380"],"advertise-client-urls":["https://199.204.45.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T06:11:18.34933Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T06:11:18.730162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T06:11:18.73022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T06:11:18.730236Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf received MsgPreVoteResp from 1686e9b1ea717edf at term 1"} {"level":"info","ts":"2026-02-25T06:11:18.730277Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became candidate at term 2"} {"level":"info","ts":"2026-02-25T06:11:18.730288Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf received MsgVoteResp from 1686e9b1ea717edf at term 2"} {"level":"info","ts":"2026-02-25T06:11:18.730296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became leader at term 2"} {"level":"info","ts":"2026-02-25T06:11:18.730308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1686e9b1ea717edf elected leader 1686e9b1ea717edf at term 2"} {"level":"info","ts":"2026-02-25T06:11:18.732491Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T06:11:18.73418Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"71c60fe001ca5b3c","local-member-id":"1686e9b1ea717edf","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T06:11:18.734246Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T06:11:18.734265Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T06:11:18.734326Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1686e9b1ea717edf","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.244:2379]}","request-path":"/0/members/1686e9b1ea717edf/attributes","cluster-id":"71c60fe001ca5b3c","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T06:11:18.734387Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T06:11:18.734707Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T06:11:18.737088Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T06:11:18.737116Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T06:11:18.737411Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T06:11:18.738523Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.244:2379"} {"level":"info","ts":"2026-02-25T06:11:44.406065Z","caller":"traceutil/trace.go:171","msg":"trace[1917532411] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"326.181474ms","start":"2026-02-25T06:11:44.079849Z","end":"2026-02-25T06:11:44.40603Z","steps":["trace[1917532411] 'process raft request' (duration: 325.917195ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:44.406851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:44.079823Z","time spent":"326.39778ms","remote":"127.0.0.1:52360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:11:47.568461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.608061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"warn","ts":"2026-02-25T06:11:47.568461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.082534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-02-25T06:11:47.568554Z","caller":"traceutil/trace.go:171","msg":"trace[60522562] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:465; }","duration":"138.768316ms","start":"2026-02-25T06:11:47.429771Z","end":"2026-02-25T06:11:47.56854Z","steps":["trace[60522562] 'range keys from in-memory index tree' (duration: 138.522928ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:47.568572Z","caller":"traceutil/trace.go:171","msg":"trace[1462725503] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:465; }","duration":"138.23452ms","start":"2026-02-25T06:11:47.430319Z","end":"2026-02-25T06:11:47.568554Z","steps":["trace[1462725503] 'range keys from in-memory index tree' (duration: 137.919839ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:47.568836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.617597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329936"} {"level":"info","ts":"2026-02-25T06:11:47.568936Z","caller":"traceutil/trace.go:171","msg":"trace[456267814] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:465; }","duration":"217.803273ms","start":"2026-02-25T06:11:47.35111Z","end":"2026-02-25T06:11:47.568914Z","steps":["trace[456267814] 'range keys from in-memory index tree' (duration: 217.130102ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:48.561226Z","caller":"traceutil/trace.go:171","msg":"trace[239425467] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"127.497532ms","start":"2026-02-25T06:11:48.433702Z","end":"2026-02-25T06:11:48.561199Z","steps":["trace[239425467] 'process raft request' (duration: 55.337641ms)","trace[239425467] 'compare' (duration: 72.032967ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:11:49.303975Z","caller":"traceutil/trace.go:171","msg":"trace[231535360] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"293.65487ms","start":"2026-02-25T06:11:49.010299Z","end":"2026-02-25T06:11:49.303954Z","steps":["trace[231535360] 'process raft request' (duration: 293.526996ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:50.937898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.178545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:11:50.937994Z","caller":"traceutil/trace.go:171","msg":"trace[1473357453] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:473; }","duration":"239.335431ms","start":"2026-02-25T06:11:50.698641Z","end":"2026-02-25T06:11:50.937977Z","steps":["trace[1473357453] 'range keys from in-memory index tree' (duration: 239.045532ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:50.938093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.202551ms","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-25T06:11:50.938163Z","caller":"traceutil/trace.go:171","msg":"trace[1720735940] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:473; }","duration":"372.344195ms","start":"2026-02-25T06:11:50.565799Z","end":"2026-02-25T06:11:50.938143Z","steps":["trace[1720735940] 'range keys from in-memory index tree' (duration: 371.975933ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:50.938213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:50.565779Z","time spent":"372.420368ms","remote":"127.0.0.1:52360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T06:11:50.942643Z","caller":"traceutil/trace.go:171","msg":"trace[22055051] linearizableReadLoop","detail":"{readStateIndex:491; appliedIndex:490; }","duration":"103.2729ms","start":"2026-02-25T06:11:50.839348Z","end":"2026-02-25T06:11:50.942621Z","steps":["trace[22055051] 'read index received' (duration: 103.215288ms)","trace[22055051] 'applied index is now lower than readState.Index' (duration: 56.572µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:11:50.942687Z","caller":"traceutil/trace.go:171","msg":"trace[982718837] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"104.204269ms","start":"2026-02-25T06:11:50.838442Z","end":"2026-02-25T06:11:50.942646Z","steps":["trace[982718837] 'process raft request' (duration: 104.058264ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:50.943064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.537288ms","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-25T06:11:50.943109Z","caller":"traceutil/trace.go:171","msg":"trace[228401902] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:474; }","duration":"103.803196ms","start":"2026-02-25T06:11:50.839295Z","end":"2026-02-25T06:11:50.943098Z","steps":["trace[228401902] 'agreement among raft nodes before linearized reading' (duration: 103.478716ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:52.315419Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142197925577538867,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T06:11:52.417186Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.107452134s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T06:11:52.417524Z","caller":"traceutil/trace.go:171","msg":"trace[1205884015] linearizableReadLoop","detail":"{readStateIndex:494; appliedIndex:493; }","duration":"606.977098ms","start":"2026-02-25T06:11:51.810522Z","end":"2026-02-25T06:11:52.4175Z","steps":["trace[1205884015] 'read index received' (duration: 606.796692ms)","trace[1205884015] 'applied index is now lower than readState.Index' (duration: 179.566µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:11:52.41765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.106822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.244\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T06:11:52.417691Z","caller":"traceutil/trace.go:171","msg":"trace[961793311] range","detail":"{range_begin:/registry/masterleases/199.204.45.244; range_end:; response_count:1; response_revision:477; }","duration":"607.162983ms","start":"2026-02-25T06:11:51.810516Z","end":"2026-02-25T06:11:52.417679Z","steps":["trace[961793311] 'agreement among raft nodes before linearized reading' (duration: 607.05279ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:52.417673Z","caller":"traceutil/trace.go:171","msg":"trace[1383609765] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"1.10798308s","start":"2026-02-25T06:11:51.309659Z","end":"2026-02-25T06:11:52.417642Z","steps":["trace[1383609765] 'process raft request' (duration: 1.107714012s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:52.417737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:51.810479Z","time spent":"607.250077ms","remote":"127.0.0.1:52114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.244\" "} {"level":"warn","ts":"2026-02-25T06:11:52.417831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:51.309641Z","time spent":"1.108102934s","remote":"127.0.0.1:52360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:11:56.668998Z","caller":"traceutil/trace.go:171","msg":"trace[1542845266] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"126.370186ms","start":"2026-02-25T06:11:56.542602Z","end":"2026-02-25T06:11:56.668972Z","steps":["trace[1542845266] 'process raft request' (duration: 126.244742ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:57.394891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.635846ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:11:57.395004Z","caller":"traceutil/trace.go:171","msg":"trace[890752727] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"395.555016ms","start":"2026-02-25T06:11:56.99943Z","end":"2026-02-25T06:11:57.394985Z","steps":["trace[890752727] 'process raft request' (duration: 131.593011ms)","trace[890752727] 'compare' (duration: 263.521281ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:11:57.395072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:56.999409Z","time spent":"395.631428ms","remote":"127.0.0.1:52360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:11:59.017951Z","caller":"traceutil/trace.go:171","msg":"trace[1626983455] linearizableReadLoop","detail":"{readStateIndex:514; appliedIndex:513; }","duration":"345.428188ms","start":"2026-02-25T06:11:58.672504Z","end":"2026-02-25T06:11:59.017933Z","steps":["trace[1626983455] 'read index received' (duration: 345.217631ms)","trace[1626983455] 'applied index is now lower than readState.Index' (duration: 209.467µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:11:59.018012Z","caller":"traceutil/trace.go:171","msg":"trace[1435794645] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"576.842558ms","start":"2026-02-25T06:11:58.44114Z","end":"2026-02-25T06:11:59.017983Z","steps":["trace[1435794645] 'process raft request' (duration: 576.647692ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:59.018151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.637594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-25T06:11:59.018172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:58.441115Z","time spent":"576.973192ms","remote":"127.0.0.1:52360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:11:59.018183Z","caller":"traceutil/trace.go:171","msg":"trace[657430000] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:495; }","duration":"345.723636ms","start":"2026-02-25T06:11:58.67245Z","end":"2026-02-25T06:11:59.018174Z","steps":["trace[657430000] 'agreement among raft nodes before linearized reading' (duration: 345.602772ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:59.01822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:58.672421Z","time spent":"345.791298ms","remote":"127.0.0.1:52360","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-25T06:12:01.420384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.880778ms","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-25T06:12:01.420503Z","caller":"traceutil/trace.go:171","msg":"trace[927783298] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:523; }","duration":"343.029283ms","start":"2026-02-25T06:12:01.077433Z","end":"2026-02-25T06:12:01.420463Z","steps":["trace[927783298] 'range keys from in-memory index tree' (duration: 342.686542ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:01.420558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:01.077412Z","time spent":"343.132136ms","remote":"127.0.0.1:52360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T06:12:01.420646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.732776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T06:12:01.420717Z","caller":"traceutil/trace.go:171","msg":"trace[585543297] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:523; }","duration":"398.84876ms","start":"2026-02-25T06:12:01.02185Z","end":"2026-02-25T06:12:01.420699Z","steps":["trace[585543297] 'range keys from in-memory index tree' (duration: 398.430487ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:01.420769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:01.021819Z","time spent":"398.937552ms","remote":"127.0.0.1:52360","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-25T06:12:01.420865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.358543ms","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-25T06:12:01.421006Z","caller":"traceutil/trace.go:171","msg":"trace[237237143] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:523; }","duration":"343.530569ms","start":"2026-02-25T06:12:01.077455Z","end":"2026-02-25T06:12:01.420986Z","steps":["trace[237237143] 'range keys from in-memory index tree' (duration: 343.139397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:01.42105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:01.077444Z","time spent":"343.594771ms","remote":"127.0.0.1:52360","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-25T06:12:01.421134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.193127ms","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-25T06:12:01.421567Z","caller":"traceutil/trace.go:171","msg":"trace[1366380147] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:523; }","duration":"131.490487ms","start":"2026-02-25T06:12:01.289856Z","end":"2026-02-25T06:12:01.421347Z","steps":["trace[1366380147] 'range keys from in-memory index tree' (duration: 130.179725ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:01.43213Z","caller":"traceutil/trace.go:171","msg":"trace[664092460] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"345.571503ms","start":"2026-02-25T06:12:01.086547Z","end":"2026-02-25T06:12:01.432118Z","steps":["trace[664092460] 'process raft request' (duration: 345.495491ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:01.432351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:01.086533Z","time spent":"345.639195ms","remote":"127.0.0.1:52134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T06:12:01.436876Z","caller":"traceutil/trace.go:171","msg":"trace[2131165175] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"241.148877ms","start":"2026-02-25T06:12:01.195712Z","end":"2026-02-25T06:12:01.436861Z","steps":["trace[2131165175] 'process raft request' (duration: 241.024783ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:01.436882Z","caller":"traceutil/trace.go:171","msg":"trace[704994388] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"150.079002ms","start":"2026-02-25T06:12:01.28679Z","end":"2026-02-25T06:12:01.436869Z","steps":["trace[704994388] 'process raft request' (duration: 150.03244ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:08.924946Z","caller":"traceutil/trace.go:171","msg":"trace[782222550] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:630; }","duration":"172.570479ms","start":"2026-02-25T06:12:08.752352Z","end":"2026-02-25T06:12:08.924922Z","steps":["trace[782222550] 'read index received' (duration: 172.414965ms)","trace[782222550] 'applied index is now lower than readState.Index' (duration: 154.814µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:08.925169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.794487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T06:12:08.925205Z","caller":"traceutil/trace.go:171","msg":"trace[1375351684] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:609; }","duration":"172.914261ms","start":"2026-02-25T06:12:08.752282Z","end":"2026-02-25T06:12:08.925196Z","steps":["trace[1375351684] 'agreement among raft nodes before linearized reading' (duration: 172.720275ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:08.925486Z","caller":"traceutil/trace.go:171","msg":"trace[1544562686] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"224.227055ms","start":"2026-02-25T06:12:08.701252Z","end":"2026-02-25T06:12:08.925479Z","steps":["trace[1544562686] 'process raft request' (duration: 223.562174ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:09.583848Z","caller":"traceutil/trace.go:171","msg":"trace[1710733378] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"104.945741ms","start":"2026-02-25T06:12:09.478884Z","end":"2026-02-25T06:12:09.58383Z","steps":["trace[1710733378] 'process raft request' (duration: 85.096326ms)","trace[1710733378] 'compare' (duration: 19.730071ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:12:09.584083Z","caller":"traceutil/trace.go:171","msg":"trace[886954661] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"104.456145ms","start":"2026-02-25T06:12:09.479606Z","end":"2026-02-25T06:12:09.584062Z","steps":["trace[886954661] 'process raft request' (duration: 104.382623ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:09.584111Z","caller":"traceutil/trace.go:171","msg":"trace[1154592986] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"105.194769ms","start":"2026-02-25T06:12:09.478904Z","end":"2026-02-25T06:12:09.584099Z","steps":["trace[1154592986] 'process raft request' (duration: 104.899849ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:10.053483Z","caller":"traceutil/trace.go:171","msg":"trace[311481578] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"126.286053ms","start":"2026-02-25T06:12:09.927167Z","end":"2026-02-25T06:12:10.053453Z","steps":["trace[311481578] 'process raft request' (duration: 126.035495ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:11.560028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.327715ms","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-25T06:12:11.56016Z","caller":"traceutil/trace.go:171","msg":"trace[1113548244] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:625; }","duration":"270.472081ms","start":"2026-02-25T06:12:11.289668Z","end":"2026-02-25T06:12:11.560141Z","steps":["trace[1113548244] 'range keys from in-memory index tree' (duration: 270.300595ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:11.56008Z","caller":"traceutil/trace.go:171","msg":"trace[1391099647] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"489.77709ms","start":"2026-02-25T06:12:11.07028Z","end":"2026-02-25T06:12:11.560057Z","steps":["trace[1391099647] 'process raft request' (duration: 480.642953ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:11.560271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:11.070258Z","time spent":"489.968086ms","remote":"127.0.0.1:52158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T06:12:12.359937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.650653ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:12:12.360062Z","caller":"traceutil/trace.go:171","msg":"trace[1312868789] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"711.811796ms","start":"2026-02-25T06:12:11.648238Z","end":"2026-02-25T06:12:12.360049Z","steps":["trace[1312868789] 'process raft request' (duration: 711.768165ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:12.360118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:11.648222Z","time spent":"711.875478ms","remote":"127.0.0.1:52360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:12:12.360143Z","caller":"traceutil/trace.go:171","msg":"trace[893632058] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"772.407952ms","start":"2026-02-25T06:12:11.587707Z","end":"2026-02-25T06:12:12.360115Z","steps":["trace[893632058] 'read index received' (duration: 72.495271ms)","trace[893632058] 'applied index is now lower than readState.Index' (duration: 699.909621ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:12:12.360273Z","caller":"traceutil/trace.go:171","msg":"trace[478933290] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"796.445689ms","start":"2026-02-25T06:12:11.563807Z","end":"2026-02-25T06:12:12.360253Z","steps":["trace[478933290] 'process raft request' (duration: 96.2968ms)","trace[478933290] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/kube-scheduler; req_size:461; } (duration: 699.2294ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:12.360381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:11.563787Z","time spent":"796.52035ms","remote":"127.0.0.1:52360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:12:12.360452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.743233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T06:12:12.360489Z","caller":"traceutil/trace.go:171","msg":"trace[685370025] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:628; }","duration":"772.808265ms","start":"2026-02-25T06:12:11.587674Z","end":"2026-02-25T06:12:12.360482Z","steps":["trace[685370025] 'agreement among raft nodes before linearized reading' (duration: 772.713592ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:12.360515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:11.587661Z","time spent":"772.848457ms","remote":"127.0.0.1:52360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T06:12:12.368641Z","caller":"traceutil/trace.go:171","msg":"trace[1159342457] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"613.974528ms","start":"2026-02-25T06:12:11.754645Z","end":"2026-02-25T06:12:12.36862Z","steps":["trace[1159342457] 'process raft request' (duration: 613.899575ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:12.368701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:11.754624Z","time spent":"614.05073ms","remote":"127.0.0.1:52158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T06:12:12.368811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.697025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.244\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T06:12:12.368863Z","caller":"traceutil/trace.go:171","msg":"trace[1227491799] range","detail":"{range_begin:/registry/masterleases/199.204.45.244; range_end:; response_count:1; response_revision:629; }","duration":"556.776768ms","start":"2026-02-25T06:12:11.812074Z","end":"2026-02-25T06:12:12.368851Z","steps":["trace[1227491799] 'agreement among raft nodes before linearized reading' (duration: 556.643964ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:12.368894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:11.812057Z","time spent":"556.829119ms","remote":"127.0.0.1:52114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.244\" "} {"level":"warn","ts":"2026-02-25T06:12:12.368916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.164197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:12:12.368941Z","caller":"traceutil/trace.go:171","msg":"trace[1984957427] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:629; }","duration":"671.204528ms","start":"2026-02-25T06:12:11.697729Z","end":"2026-02-25T06:12:12.368934Z","steps":["trace[1984957427] 'agreement among raft nodes before linearized reading' (duration: 671.160446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:12.368962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:11.697719Z","time spent":"671.236739ms","remote":"127.0.0.1:52074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T06:12:12.369069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.766613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-fs2th\" ","response":"range_response_count:1 size:4697"} {"level":"info","ts":"2026-02-25T06:12:12.369144Z","caller":"traceutil/trace.go:171","msg":"trace[128741978] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-fs2th; range_end:; response_count:1; response_revision:629; }","duration":"512.892147ms","start":"2026-02-25T06:12:11.856232Z","end":"2026-02-25T06:12:12.369124Z","steps":["trace[128741978] 'agreement among raft nodes before linearized reading' (duration: 512.638729ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:12.369187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:11.85621Z","time spent":"512.969269ms","remote":"127.0.0.1:52270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4719,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-fs2th\" "} {"level":"warn","ts":"2026-02-25T06:12:12.369351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"781.196389ms","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-25T06:12:12.369382Z","caller":"traceutil/trace.go:171","msg":"trace[1690966200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:629; }","duration":"781.250551ms","start":"2026-02-25T06:12:11.588123Z","end":"2026-02-25T06:12:12.369374Z","steps":["trace[1690966200] 'agreement among raft nodes before linearized reading' (duration: 780.92111ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:12.369409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:11.588111Z","time spent":"781.291922ms","remote":"127.0.0.1:52360","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-25T06:12:12.369666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"781.532219ms","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-25T06:12:12.369744Z","caller":"traceutil/trace.go:171","msg":"trace[457778865] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:629; }","duration":"781.620372ms","start":"2026-02-25T06:12:11.588084Z","end":"2026-02-25T06:12:12.369705Z","steps":["trace[457778865] 'agreement among raft nodes before linearized reading' (duration: 780.576659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:12.369788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:11.588066Z","time spent":"781.714475ms","remote":"127.0.0.1:52360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T06:12:12.700624Z","caller":"traceutil/trace.go:171","msg":"trace[1352030357] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"167.631434ms","start":"2026-02-25T06:12:12.532968Z","end":"2026-02-25T06:12:12.700599Z","steps":["trace[1352030357] 'process raft request' (duration: 167.543542ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:12.700795Z","caller":"traceutil/trace.go:171","msg":"trace[964910226] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"167.387187ms","start":"2026-02-25T06:12:12.533395Z","end":"2026-02-25T06:12:12.700782Z","steps":["trace[964910226] 'process raft request' (duration: 167.16673ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:12.700889Z","caller":"traceutil/trace.go:171","msg":"trace[366433135] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"170.638399ms","start":"2026-02-25T06:12:12.53024Z","end":"2026-02-25T06:12:12.700878Z","steps":["trace[366433135] 'process raft request' (duration: 170.086652ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:12.825303Z","caller":"traceutil/trace.go:171","msg":"trace[1076724507] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"118.732216ms","start":"2026-02-25T06:12:12.706548Z","end":"2026-02-25T06:12:12.82528Z","steps":["trace[1076724507] 'process raft request' (duration: 118.605882ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:12.852315Z","caller":"traceutil/trace.go:171","msg":"trace[104770809] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"143.047311ms","start":"2026-02-25T06:12:12.709255Z","end":"2026-02-25T06:12:12.852303Z","steps":["trace[104770809] 'process raft request' (duration: 142.964799ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:13.239877Z","caller":"traceutil/trace.go:171","msg":"trace[1306789047] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"165.471226ms","start":"2026-02-25T06:12:13.074388Z","end":"2026-02-25T06:12:13.23986Z","steps":["trace[1306789047] 'process raft request' (duration: 165.405114ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:13.239926Z","caller":"traceutil/trace.go:171","msg":"trace[246547587] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"165.578849ms","start":"2026-02-25T06:12:13.074316Z","end":"2026-02-25T06:12:13.239895Z","steps":["trace[246547587] 'process raft request' (duration: 93.847802ms)","trace[246547587] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/services/endpoints/kube-system/kube-dns; req_size:1014; } (duration: 71.4925ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:12:13.250133Z","caller":"traceutil/trace.go:171","msg":"trace[660201566] linearizableReadLoop","detail":"{readStateIndex:687; appliedIndex:682; }","duration":"115.587107ms","start":"2026-02-25T06:12:13.134536Z","end":"2026-02-25T06:12:13.250123Z","steps":["trace[660201566] 'read index received' (duration: 33.6707ms)","trace[660201566] 'applied index is now lower than readState.Index' (duration: 81.915747ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:12:13.250186Z","caller":"traceutil/trace.go:171","msg":"trace[1893642250] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"119.963825ms","start":"2026-02-25T06:12:13.130199Z","end":"2026-02-25T06:12:13.250163Z","steps":["trace[1893642250] 'process raft request' (duration: 119.81519ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:13.250218Z","caller":"traceutil/trace.go:171","msg":"trace[2141647043] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"118.355084ms","start":"2026-02-25T06:12:13.131854Z","end":"2026-02-25T06:12:13.250209Z","steps":["trace[2141647043] 'process raft request' (duration: 118.206589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:13.250227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.67725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4206"} {"level":"info","ts":"2026-02-25T06:12:13.25025Z","caller":"traceutil/trace.go:171","msg":"trace[63984661] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:664; }","duration":"115.731122ms","start":"2026-02-25T06:12:13.134512Z","end":"2026-02-25T06:12:13.250243Z","steps":["trace[63984661] 'agreement among raft nodes before linearized reading' (duration: 115.647309ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:13.250253Z","caller":"traceutil/trace.go:171","msg":"trace[1566356198] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"117.25696ms","start":"2026-02-25T06:12:13.132972Z","end":"2026-02-25T06:12:13.250229Z","steps":["trace[1566356198] 'process raft request' (duration: 117.123455ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:18.965713Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142197925577539262,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T06:12:19.46649Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142197925577539262,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T06:12:19.476566Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.065366412s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T06:12:19.47696Z","caller":"traceutil/trace.go:171","msg":"trace[527117446] linearizableReadLoop","detail":"{readStateIndex:706; appliedIndex:705; }","duration":"1.011852081s","start":"2026-02-25T06:12:18.465089Z","end":"2026-02-25T06:12:19.476941Z","steps":["trace[527117446] 'read index received' (duration: 1.011699937s)","trace[527117446] 'applied index is now lower than readState.Index' (duration: 150.944µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:12:19.477045Z","caller":"traceutil/trace.go:171","msg":"trace[354222820] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"1.065895139s","start":"2026-02-25T06:12:18.41111Z","end":"2026-02-25T06:12:19.477005Z","steps":["trace[354222820] 'process raft request' (duration: 1.065666242s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:19.477176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.732099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T06:12:19.477203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"943.306677ms","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-25T06:12:19.477228Z","caller":"traceutil/trace.go:171","msg":"trace[1570587251] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:682; }","duration":"943.349109ms","start":"2026-02-25T06:12:18.533872Z","end":"2026-02-25T06:12:19.477221Z","steps":["trace[1570587251] 'agreement among raft nodes before linearized reading' (duration: 943.250676ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:19.47723Z","caller":"traceutil/trace.go:171","msg":"trace[690739065] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:682; }","duration":"779.837722ms","start":"2026-02-25T06:12:18.697376Z","end":"2026-02-25T06:12:19.477214Z","steps":["trace[690739065] 'agreement among raft nodes before linearized reading' (duration: 779.731139ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:19.477234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.889532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-25T06:12:19.477256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:18.533864Z","time spent":"943.38672ms","remote":"127.0.0.1:52360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T06:12:19.477237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"946.201449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-25T06:12:19.477232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.012120319s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T06:12:19.477292Z","caller":"traceutil/trace.go:171","msg":"trace[1313103233] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:682; }","duration":"454.976995ms","start":"2026-02-25T06:12:19.022296Z","end":"2026-02-25T06:12:19.477273Z","steps":["trace[1313103233] 'agreement among raft nodes before linearized reading' (duration: 454.83532ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:19.477296Z","caller":"traceutil/trace.go:171","msg":"trace[12140490] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:682; }","duration":"946.302952ms","start":"2026-02-25T06:12:18.530979Z","end":"2026-02-25T06:12:19.477281Z","steps":["trace[12140490] 'agreement among raft nodes before linearized reading' (duration: 946.114306ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:19.477277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:18.411097Z","time spent":"1.066006812s","remote":"127.0.0.1:52360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:12:19.477332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:18.697354Z","time spent":"779.903173ms","remote":"127.0.0.1:52074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T06:12:19.47733Z","caller":"traceutil/trace.go:171","msg":"trace[187205909] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:682; }","duration":"1.012266905s","start":"2026-02-25T06:12:18.46503Z","end":"2026-02-25T06:12:19.477296Z","steps":["trace[187205909] 'agreement among raft nodes before linearized reading' (duration: 1.011982136s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:19.477349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:19.02228Z","time spent":"455.059418ms","remote":"127.0.0.1:52360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T06:12:19.477352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:18.53096Z","time spent":"946.384054ms","remote":"127.0.0.1:52360","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-25T06:12:19.477381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:18.464999Z","time spent":"1.012372138s","remote":"127.0.0.1:52360","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-25T06:12:22.049949Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142197925577539275,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T06:12:22.550817Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142197925577539275,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T06:12:22.705356Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.219523606s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T06:12:22.705697Z","caller":"traceutil/trace.go:171","msg":"trace[1901078643] linearizableReadLoop","detail":"{readStateIndex:711; appliedIndex:710; }","duration":"1.156438433s","start":"2026-02-25T06:12:21.549231Z","end":"2026-02-25T06:12:22.70567Z","steps":["trace[1901078643] 'read index received' (duration: 1.156381912s)","trace[1901078643] 'applied index is now lower than readState.Index' (duration: 55.481µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:12:22.705809Z","caller":"traceutil/trace.go:171","msg":"trace[26303265] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"1.220105784s","start":"2026-02-25T06:12:21.485692Z","end":"2026-02-25T06:12:22.705798Z","steps":["trace[26303265] 'process raft request' (duration: 1.219855617s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:22.705968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:21.485665Z","time spent":"1.220183627s","remote":"127.0.0.1:52360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:12:22.706212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.156957101s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-25T06:12:22.706274Z","caller":"traceutil/trace.go:171","msg":"trace[1889371185] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:687; }","duration":"1.157055814s","start":"2026-02-25T06:12:21.549197Z","end":"2026-02-25T06:12:22.706253Z","steps":["trace[1889371185] 'agreement among raft nodes before linearized reading' (duration: 1.156873988s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:22.706322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:21.549176Z","time spent":"1.157134006s","remote":"127.0.0.1:52360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-25T06:12:23.002714Z","caller":"traceutil/trace.go:171","msg":"trace[1208932878] linearizableReadLoop","detail":"{readStateIndex:712; appliedIndex:711; }","duration":"296.919099ms","start":"2026-02-25T06:12:22.705766Z","end":"2026-02-25T06:12:23.002685Z","steps":["trace[1208932878] 'read index received' (duration: 296.721043ms)","trace[1208932878] 'applied index is now lower than readState.Index' (duration: 196.786µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:23.00289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.146309856s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T06:12:23.002918Z","caller":"traceutil/trace.go:171","msg":"trace[1863370071] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"372.294037ms","start":"2026-02-25T06:12:22.630595Z","end":"2026-02-25T06:12:23.002889Z","steps":["trace[1863370071] 'process raft request' (duration: 371.901014ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:23.00296Z","caller":"traceutil/trace.go:171","msg":"trace[103709672] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:688; }","duration":"1.146415038s","start":"2026-02-25T06:12:21.856528Z","end":"2026-02-25T06:12:23.002943Z","steps":["trace[103709672] 'agreement among raft nodes before linearized reading' (duration: 1.146231833s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:23.00297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.453640821s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-25T06:12:23.003029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.927139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:12:23.003034Z","caller":"traceutil/trace.go:171","msg":"trace[394593168] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:688; }","duration":"1.453752185s","start":"2026-02-25T06:12:21.549266Z","end":"2026-02-25T06:12:23.003018Z","steps":["trace[394593168] 'agreement among raft nodes before linearized reading' (duration: 1.453530388s)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:23.003052Z","caller":"traceutil/trace.go:171","msg":"trace[1486240275] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:688; }","duration":"303.989311ms","start":"2026-02-25T06:12:22.699057Z","end":"2026-02-25T06:12:23.003046Z","steps":["trace[1486240275] 'agreement among raft nodes before linearized reading' (duration: 303.9554ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:23.003071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:22.699034Z","time spent":"304.032882ms","remote":"127.0.0.1:52074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T06:12:23.003088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:21.549248Z","time spent":"1.453825828s","remote":"127.0.0.1:52360","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-25T06:12:23.003083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:22.630565Z","time spent":"372.419081ms","remote":"127.0.0.1:52360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:12:23.002997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:21.85652Z","time spent":"1.146468951s","remote":"127.0.0.1:52446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":51,"response size":29,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-25T06:12:23.003384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.454031914s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T06:12:23.003428Z","caller":"traceutil/trace.go:171","msg":"trace[1703853392] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:688; }","duration":"1.454074385s","start":"2026-02-25T06:12:21.549343Z","end":"2026-02-25T06:12:23.003418Z","steps":["trace[1703853392] 'agreement among raft nodes before linearized reading' (duration: 1.453944271s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:23.003468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:21.549326Z","time spent":"1.454133037s","remote":"127.0.0.1:52360","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-25T06:12:23.003541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.190540985s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.244\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T06:12:23.003567Z","caller":"traceutil/trace.go:171","msg":"trace[1769639397] range","detail":"{range_begin:/registry/masterleases/199.204.45.244; range_end:; response_count:1; response_revision:688; }","duration":"1.190595687s","start":"2026-02-25T06:12:21.812965Z","end":"2026-02-25T06:12:23.003561Z","steps":["trace[1769639397] 'agreement among raft nodes before linearized reading' (duration: 1.190499854s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:23.003585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:21.812947Z","time spent":"1.190634339s","remote":"127.0.0.1:52114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.244\" "} {"level":"warn","ts":"2026-02-25T06:12:23.003603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.453928501s","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-25T06:12:23.003637Z","caller":"traceutil/trace.go:171","msg":"trace[1935997149] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:688; }","duration":"1.453971152s","start":"2026-02-25T06:12:21.549657Z","end":"2026-02-25T06:12:23.003628Z","steps":["trace[1935997149] 'agreement among raft nodes before linearized reading' (duration: 1.453880729s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:23.003669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:21.54965Z","time spent":"1.454012963s","remote":"127.0.0.1:52360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T06:12:23.004338Z","caller":"traceutil/trace.go:171","msg":"trace[1727598550] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"294.127181ms","start":"2026-02-25T06:12:22.710183Z","end":"2026-02-25T06:12:23.00431Z","steps":["trace[1727598550] 'process raft request' (duration: 293.820932ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:43.18598Z","caller":"traceutil/trace.go:171","msg":"trace[1648413850] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"381.524477ms","start":"2026-02-25T06:12:42.80442Z","end":"2026-02-25T06:12:43.185945Z","steps":["trace[1648413850] 'process raft request' (duration: 381.188866ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:43.186242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:42.804399Z","time spent":"381.731324ms","remote":"127.0.0.1:52360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:12:43.350672Z","caller":"traceutil/trace.go:171","msg":"trace[2047142737] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"159.722768ms","start":"2026-02-25T06:12:43.190917Z","end":"2026-02-25T06:12:43.35064Z","steps":["trace[2047142737] 'process raft request' (duration: 159.538523ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:43.350981Z","caller":"traceutil/trace.go:171","msg":"trace[629440280] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"159.379968ms","start":"2026-02-25T06:12:43.19159Z","end":"2026-02-25T06:12:43.35097Z","steps":["trace[629440280] 'process raft request' (duration: 159.010726ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:43.351216Z","caller":"traceutil/trace.go:171","msg":"trace[626237042] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"159.274724ms","start":"2026-02-25T06:12:43.191924Z","end":"2026-02-25T06:12:43.351199Z","steps":["trace[626237042] 'process raft request' (duration: 159.001265ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:43.874052Z","caller":"traceutil/trace.go:171","msg":"trace[709029551] linearizableReadLoop","detail":"{readStateIndex:785; appliedIndex:784; }","duration":"174.919196ms","start":"2026-02-25T06:12:43.69911Z","end":"2026-02-25T06:12:43.874029Z","steps":["trace[709029551] 'read index received' (duration: 174.669458ms)","trace[709029551] 'applied index is now lower than readState.Index' (duration: 248.348µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:43.874172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.04934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:12:43.874203Z","caller":"traceutil/trace.go:171","msg":"trace[320047122] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:755; }","duration":"175.141682ms","start":"2026-02-25T06:12:43.699052Z","end":"2026-02-25T06:12:43.874194Z","steps":["trace[320047122] 'agreement among raft nodes before linearized reading' (duration: 175.06868ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:43.874345Z","caller":"traceutil/trace.go:171","msg":"trace[1519716789] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"179.411777ms","start":"2026-02-25T06:12:43.694906Z","end":"2026-02-25T06:12:43.874318Z","steps":["trace[1519716789] 'process raft request' (duration: 178.938962ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:45.729533Z","caller":"traceutil/trace.go:171","msg":"trace[1210337038] linearizableReadLoop","detail":"{readStateIndex:787; appliedIndex:786; }","duration":"374.763124ms","start":"2026-02-25T06:12:45.354754Z","end":"2026-02-25T06:12:45.729518Z","steps":["trace[1210337038] 'read index received' (duration: 374.668881ms)","trace[1210337038] 'applied index is now lower than readState.Index' (duration: 93.483µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:12:45.729613Z","caller":"traceutil/trace.go:171","msg":"trace[1599782066] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"458.437102ms","start":"2026-02-25T06:12:45.271149Z","end":"2026-02-25T06:12:45.729586Z","steps":["trace[1599782066] 'process raft request' (duration: 458.285767ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:45.729662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.898089ms","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-25T06:12:45.729779Z","caller":"traceutil/trace.go:171","msg":"trace[1343125147] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:757; }","duration":"375.054813ms","start":"2026-02-25T06:12:45.354701Z","end":"2026-02-25T06:12:45.729756Z","steps":["trace[1343125147] 'agreement among raft nodes before linearized reading' (duration: 374.888328ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:45.729856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:45.354677Z","time spent":"375.153057ms","remote":"127.0.0.1:52360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T06:12:45.729881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:45.271133Z","time spent":"458.618838ms","remote":"127.0.0.1:52360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:12:45.729896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.588429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-25T06:12:45.730005Z","caller":"traceutil/trace.go:171","msg":"trace[1324526562] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:757; }","duration":"374.698422ms","start":"2026-02-25T06:12:45.355274Z","end":"2026-02-25T06:12:45.729972Z","steps":["trace[1324526562] 'agreement among raft nodes before linearized reading' (duration: 374.489076ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:45.730027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.140296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-25T06:12:45.73006Z","caller":"traceutil/trace.go:171","msg":"trace[1100821757] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:757; }","duration":"375.192698ms","start":"2026-02-25T06:12:45.354861Z","end":"2026-02-25T06:12:45.730053Z","steps":["trace[1100821757] 'agreement among raft nodes before linearized reading' (duration: 374.882918ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:45.730058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:45.355267Z","time spent":"374.779525ms","remote":"127.0.0.1:52360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T06:12:45.730133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:45.354848Z","time spent":"375.239259ms","remote":"127.0.0.1:52360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T06:12:46.153354Z","caller":"traceutil/trace.go:171","msg":"trace[68291963] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"418.257151ms","start":"2026-02-25T06:12:45.735075Z","end":"2026-02-25T06:12:46.153333Z","steps":["trace[68291963] 'process raft request' (duration: 418.153828ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:46.153409Z","caller":"traceutil/trace.go:171","msg":"trace[1554432731] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"418.707465ms","start":"2026-02-25T06:12:45.734668Z","end":"2026-02-25T06:12:46.153376Z","steps":["trace[1554432731] 'process raft request' (duration: 401.707531ms)","trace[1554432731] 'compare' (duration: 16.5375ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:46.153499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:45.735062Z","time spent":"418.361595ms","remote":"127.0.0.1:52360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:12:46.153565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:45.734648Z","time spent":"418.8347ms","remote":"127.0.0.1:52360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:12:46.15363Z","caller":"traceutil/trace.go:171","msg":"trace[176280974] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"418.168678ms","start":"2026-02-25T06:12:45.735452Z","end":"2026-02-25T06:12:46.153621Z","steps":["trace[176280974] 'process raft request' (duration: 417.848748ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:46.153688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:45.735447Z","time spent":"418.21394ms","remote":"127.0.0.1:52360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:12:59.980032Z","caller":"traceutil/trace.go:171","msg":"trace[340414766] linearizableReadLoop","detail":"{readStateIndex:885; appliedIndex:884; }","duration":"284.042885ms","start":"2026-02-25T06:12:59.69596Z","end":"2026-02-25T06:12:59.980003Z","steps":["trace[340414766] 'read index received' (duration: 283.800237ms)","trace[340414766] 'applied index is now lower than readState.Index' (duration: 241.188µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:12:59.980209Z","caller":"traceutil/trace.go:171","msg":"trace[1354543330] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"693.432336ms","start":"2026-02-25T06:12:59.286763Z","end":"2026-02-25T06:12:59.980195Z","steps":["trace[1354543330] 'process raft request' (duration: 693.033933ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:59.980344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:59.286747Z","time spent":"693.506558ms","remote":"127.0.0.1:52360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:12:59.980416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.386586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T06:12:59.980489Z","caller":"traceutil/trace.go:171","msg":"trace[1172851321] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:850; }","duration":"157.492558ms","start":"2026-02-25T06:12:59.822979Z","end":"2026-02-25T06:12:59.980471Z","steps":["trace[1172851321] 'agreement among raft nodes before linearized reading' (duration: 157.328293ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:59.980605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.650953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:12:59.980632Z","caller":"traceutil/trace.go:171","msg":"trace[1574064984] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:850; }","duration":"284.768057ms","start":"2026-02-25T06:12:59.695855Z","end":"2026-02-25T06:12:59.980623Z","steps":["trace[1574064984] 'agreement among raft nodes before linearized reading' (duration: 284.731766ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:13:20.54616Z","caller":"traceutil/trace.go:171","msg":"trace[230923625] linearizableReadLoop","detail":"{readStateIndex:962; appliedIndex:961; }","duration":"475.618174ms","start":"2026-02-25T06:13:20.07052Z","end":"2026-02-25T06:13:20.546138Z","steps":["trace[230923625] 'read index received' (duration: 475.428108ms)","trace[230923625] 'applied index is now lower than readState.Index' (duration: 189.436µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:13:20.546269Z","caller":"traceutil/trace.go:171","msg":"trace[8524733] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"475.752988ms","start":"2026-02-25T06:13:20.070499Z","end":"2026-02-25T06:13:20.546252Z","steps":["trace[8524733] 'process raft request' (duration: 475.518861ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:13:20.54636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.827401ms","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-25T06:13:20.54639Z","caller":"traceutil/trace.go:171","msg":"trace[923966976] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:923; }","duration":"475.890882ms","start":"2026-02-25T06:13:20.070491Z","end":"2026-02-25T06:13:20.546382Z","steps":["trace[923966976] 'agreement among raft nodes before linearized reading' (duration: 475.777129ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:13:20.546392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:13:20.070478Z","time spent":"475.839161ms","remote":"127.0.0.1:52360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:13:20.546412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:13:20.070473Z","time spent":"475.934204ms","remote":"127.0.0.1:52360","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":"warn","ts":"2026-02-25T06:13:20.546774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.536234ms","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-25T06:13:20.5468Z","caller":"traceutil/trace.go:171","msg":"trace[97805946] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:923; }","duration":"241.599286ms","start":"2026-02-25T06:13:20.305194Z","end":"2026-02-25T06:13:20.546794Z","steps":["trace[97805946] 'agreement among raft nodes before linearized reading' (duration: 241.532234ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:13:20.546921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.092236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T06:13:20.546942Z","caller":"traceutil/trace.go:171","msg":"trace[1163230877] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:923; }","duration":"238.136568ms","start":"2026-02-25T06:13:20.3088Z","end":"2026-02-25T06:13:20.546937Z","steps":["trace[1163230877] 'agreement among raft nodes before linearized reading' (duration: 238.081096ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:13:20.547166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.789733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T06:13:20.547191Z","caller":"traceutil/trace.go:171","msg":"trace[1304906907] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:923; }","duration":"363.843955ms","start":"2026-02-25T06:13:20.183342Z","end":"2026-02-25T06:13:20.547186Z","steps":["trace[1304906907] 'agreement among raft nodes before linearized reading' (duration: 363.790773ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:13:20.54721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:13:20.183327Z","time spent":"363.877425ms","remote":"127.0.0.1:52592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":29,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-02-25T06:13:20.547222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.026841ms","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-25T06:13:20.5473Z","caller":"traceutil/trace.go:171","msg":"trace[1870715717] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:923; }","duration":"243.150555ms","start":"2026-02-25T06:13:20.30413Z","end":"2026-02-25T06:13:20.547281Z","steps":["trace[1870715717] 'agreement among raft nodes before linearized reading' (duration: 242.920417ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:13:20.547156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.448648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-ptdd9\" ","response":"range_response_count:1 size:10951"} {"level":"info","ts":"2026-02-25T06:13:20.547835Z","caller":"traceutil/trace.go:171","msg":"trace[1496287832] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-ptdd9; range_end:; response_count:1; response_revision:923; }","duration":"433.491451ms","start":"2026-02-25T06:13:20.114315Z","end":"2026-02-25T06:13:20.547807Z","steps":["trace[1496287832] 'agreement among raft nodes before linearized reading' (duration: 432.222031ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:13:20.548188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:13:20.114304Z","time spent":"433.866913ms","remote":"127.0.0.1:52270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":10973,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-ptdd9\" "} {"level":"info","ts":"2026-02-25T06:13:20.834344Z","caller":"traceutil/trace.go:171","msg":"trace[1473217787] linearizableReadLoop","detail":"{readStateIndex:967; appliedIndex:962; }","duration":"135.307098ms","start":"2026-02-25T06:13:20.699017Z","end":"2026-02-25T06:13:20.834324Z","steps":["trace[1473217787] 'read index received' (duration: 46.965344ms)","trace[1473217787] 'applied index is now lower than readState.Index' (duration: 88.340794ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:13:20.834455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.430062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:13:20.834482Z","caller":"traceutil/trace.go:171","msg":"trace[1890571741] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:928; }","duration":"135.506044ms","start":"2026-02-25T06:13:20.698968Z","end":"2026-02-25T06:13:20.834474Z","steps":["trace[1890571741] 'agreement among raft nodes before linearized reading' (duration: 135.432652ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:13:20.834504Z","caller":"traceutil/trace.go:171","msg":"trace[597028423] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"281.198369ms","start":"2026-02-25T06:13:20.553275Z","end":"2026-02-25T06:13:20.834473Z","steps":["trace[597028423] 'process raft request' (duration: 280.958792ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:13:20.834657Z","caller":"traceutil/trace.go:171","msg":"trace[1582587906] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"281.187349ms","start":"2026-02-25T06:13:20.553462Z","end":"2026-02-25T06:13:20.834649Z","steps":["trace[1582587906] 'process raft request' (duration: 280.792966ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:13:20.834789Z","caller":"traceutil/trace.go:171","msg":"trace[183057266] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"276.802451ms","start":"2026-02-25T06:13:20.557957Z","end":"2026-02-25T06:13:20.83476Z","steps":["trace[183057266] 'process raft request' (duration: 276.326376ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:13:20.834878Z","caller":"traceutil/trace.go:171","msg":"trace[565750752] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"283.955266ms","start":"2026-02-25T06:13:20.550914Z","end":"2026-02-25T06:13:20.834869Z","steps":["trace[565750752] 'process raft request' (duration: 195.058744ms)","trace[565750752] 'compare' (duration: 88.047205ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:13:20.834933Z","caller":"traceutil/trace.go:171","msg":"trace[392807833] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"282.490459ms","start":"2026-02-25T06:13:20.552437Z","end":"2026-02-25T06:13:20.834927Z","steps":["trace[392807833] 'process raft request' (duration: 281.766367ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:13:21.024664Z","caller":"traceutil/trace.go:171","msg":"trace[1657525549] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"166.026643ms","start":"2026-02-25T06:13:20.858612Z","end":"2026-02-25T06:13:21.024639Z","steps":["trace[1657525549] 'process raft request' (duration: 165.948511ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:13:21.024915Z","caller":"traceutil/trace.go:171","msg":"trace[1191275371] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"180.89095ms","start":"2026-02-25T06:13:20.843995Z","end":"2026-02-25T06:13:21.024886Z","steps":["trace[1191275371] 'process raft request' (duration: 147.398628ms)","trace[1191275371] 'compare' (duration: 33.026767ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:13:22.43674Z","caller":"traceutil/trace.go:171","msg":"trace[251381523] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"601.663152ms","start":"2026-02-25T06:13:21.835046Z","end":"2026-02-25T06:13:22.436709Z","steps":["trace[251381523] 'process raft request' (duration: 512.260654ms)","trace[251381523] 'compare' (duration: 89.012935ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:13:22.436904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:13:21.83503Z","time spent":"601.790006ms","remote":"127.0.0.1:52114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:13:22.98927Z","caller":"traceutil/trace.go:171","msg":"trace[1380210760] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"146.383867ms","start":"2026-02-25T06:13:22.842851Z","end":"2026-02-25T06:13:22.989235Z","steps":["trace[1380210760] 'process raft request' (duration: 113.38376ms)","trace[1380210760] 'compare' (duration: 32.740068ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:13:22.989307Z","caller":"traceutil/trace.go:171","msg":"trace[916428242] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"146.072846ms","start":"2026-02-25T06:13:22.843212Z","end":"2026-02-25T06:13:22.989285Z","steps":["trace[916428242] 'process raft request' (duration: 145.905421ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:13:22.989302Z","caller":"traceutil/trace.go:171","msg":"trace[1346403577] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"145.53716ms","start":"2026-02-25T06:13:22.843741Z","end":"2026-02-25T06:13:22.989278Z","steps":["trace[1346403577] 'process raft request' (duration: 145.491919ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:13:22.989468Z","caller":"traceutil/trace.go:171","msg":"trace[2093203212] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"145.894091ms","start":"2026-02-25T06:13:22.843563Z","end":"2026-02-25T06:13:22.989457Z","steps":["trace[2093203212] 'process raft request' (duration: 145.617642ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:13:24.449515Z","caller":"traceutil/trace.go:171","msg":"trace[1637314545] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"100.877517ms","start":"2026-02-25T06:13:24.348611Z","end":"2026-02-25T06:13:24.449488Z","steps":["trace[1637314545] 'process raft request' (duration: 100.738203ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:13:24.673763Z","caller":"traceutil/trace.go:171","msg":"trace[1108884340] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"152.182327ms","start":"2026-02-25T06:13:24.521537Z","end":"2026-02-25T06:13:24.673719Z","steps":["trace[1108884340] 'process raft request' (duration: 82.378387ms)","trace[1108884340] 'compare' (duration: 69.257603ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:13:30.993525Z","caller":"traceutil/trace.go:171","msg":"trace[1349438693] linearizableReadLoop","detail":"{readStateIndex:1025; appliedIndex:1024; }","duration":"134.253715ms","start":"2026-02-25T06:13:30.859243Z","end":"2026-02-25T06:13:30.993496Z","steps":["trace[1349438693] 'read index received' (duration: 134.055349ms)","trace[1349438693] 'applied index is now lower than readState.Index' (duration: 196.636µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:13:30.99381Z","caller":"traceutil/trace.go:171","msg":"trace[881246412] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"208.792136ms","start":"2026-02-25T06:13:30.784938Z","end":"2026-02-25T06:13:30.99373Z","steps":["trace[881246412] 'process raft request' (duration: 208.384333ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:13:30.993862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.584636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T06:13:30.994161Z","caller":"traceutil/trace.go:171","msg":"trace[2103341953] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:984; }","duration":"134.734601ms","start":"2026-02-25T06:13:30.859219Z","end":"2026-02-25T06:13:30.993953Z","steps":["trace[2103341953] 'agreement among raft nodes before linearized reading' (duration: 134.541135ms)"],"step_count":1}