{"level":"warn","ts":"2026-02-27T20:26:26.469114Z","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-27T20:26:26.469198Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.53: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.53:2380","--initial-cluster=instance=https://199.204.45.53:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.53:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.53: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-27T20:26:26.469255Z","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-27T20:26:26.469268Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-02-27T20:26:26.469283Z","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-27T20:26:26.469807Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"]} {"level":"info","ts":"2026-02-27T20:26:26.469874Z","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.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.53: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-27T20:26:26.47469Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.637037ms"} {"level":"info","ts":"2026-02-27T20:26:26.487181Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c47ca1a7b1fe506b","cluster-id":"95bd21e158fabf07"} {"level":"info","ts":"2026-02-27T20:26:26.487283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=()"} {"level":"info","ts":"2026-02-27T20:26:26.487309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 0"} {"level":"info","ts":"2026-02-27T20:26:26.48732Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c47ca1a7b1fe506b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-27T20:26:26.487332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 1"} {"level":"info","ts":"2026-02-27T20:26:26.487371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"warn","ts":"2026-02-27T20:26:26.49368Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-27T20:26:26.495635Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-27T20:26:26.496952Z","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-27T20:26:26.499906Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c47ca1a7b1fe506b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-27T20:26:26.500102Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c47ca1a7b1fe506b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-27T20:26:26.500289Z","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-27T20:26:26.50127Z","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-27T20:26:26.501324Z","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-27T20:26:26.501515Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"info","ts":"2026-02-27T20:26:26.50185Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","added-peer-id":"c47ca1a7b1fe506b","added-peer-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-02-27T20:26:26.504888Z","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-27T20:26:26.504984Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-02-27T20:26:26.505052Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-02-27T20:26:26.505895Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c47ca1a7b1fe506b","initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-27T20:26:26.505938Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-27T20:26:26.588378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b is starting a new election at term 1"} {"level":"info","ts":"2026-02-27T20:26:26.58843Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became pre-candidate at term 1"} {"level":"info","ts":"2026-02-27T20:26:26.588506Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgPreVoteResp from c47ca1a7b1fe506b at term 1"} {"level":"info","ts":"2026-02-27T20:26:26.588534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became candidate at term 2"} {"level":"info","ts":"2026-02-27T20:26:26.588579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgVoteResp from c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-02-27T20:26:26.58863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became leader at term 2"} {"level":"info","ts":"2026-02-27T20:26:26.588651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c47ca1a7b1fe506b elected leader c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-02-27T20:26:26.589545Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T20:26:26.590228Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c47ca1a7b1fe506b","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.53:2379]}","request-path":"/0/members/c47ca1a7b1fe506b/attributes","cluster-id":"95bd21e158fabf07","publish-timeout":"7s"} {"level":"info","ts":"2026-02-27T20:26:26.590336Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T20:26:26.59061Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T20:26:26.590814Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T20:26:26.590854Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T20:26:26.590905Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T20:26:26.594143Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-27T20:26:26.594285Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.53:2379"} {"level":"info","ts":"2026-02-27T20:26:26.594457Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-27T20:26:26.594481Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-27T20:26:50.802212Z","caller":"traceutil/trace.go:171","msg":"trace[39041681] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"123.732045ms","start":"2026-02-27T20:26:50.678464Z","end":"2026-02-27T20:26:50.802196Z","steps":["trace[39041681] 'process raft request' (duration: 123.378477ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:26:55.821205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.846145ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-27T20:26:55.821335Z","caller":"traceutil/trace.go:171","msg":"trace[1281277000] linearizableReadLoop","detail":"{readStateIndex:523; appliedIndex:522; }","duration":"455.305419ms","start":"2026-02-27T20:26:55.366009Z","end":"2026-02-27T20:26:55.821315Z","steps":["trace[1281277000] 'read index received' (duration: 259.148277ms)","trace[1281277000] 'applied index is now lower than readState.Index' (duration: 196.155622ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:26:55.821464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.415362ms","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-27T20:26:55.821533Z","caller":"traceutil/trace.go:171","msg":"trace[1785418206] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:506; }","duration":"455.550795ms","start":"2026-02-27T20:26:55.365965Z","end":"2026-02-27T20:26:55.821516Z","steps":["trace[1785418206] 'agreement among raft nodes before linearized reading' (duration: 455.395791ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:26:55.821553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.968653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-27T20:26:55.821595Z","caller":"traceutil/trace.go:171","msg":"trace[468944288] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:506; }","duration":"372.050854ms","start":"2026-02-27T20:26:55.449531Z","end":"2026-02-27T20:26:55.821582Z","steps":["trace[468944288] 'agreement among raft nodes before linearized reading' (duration: 371.900591ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:26:55.821579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:26:55.365944Z","time spent":"455.622737ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-27T20:26:55.821644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:26:55.44951Z","time spent":"372.122587ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-27T20:26:58.795547Z","caller":"traceutil/trace.go:171","msg":"trace[1872282635] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"236.058452ms","start":"2026-02-27T20:26:58.55939Z","end":"2026-02-27T20:26:58.795449Z","steps":["trace[1872282635] 'process raft request' (duration: 235.649951ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:26:59.959072Z","caller":"traceutil/trace.go:171","msg":"trace[512895411] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"108.5059ms","start":"2026-02-27T20:26:59.85054Z","end":"2026-02-27T20:26:59.959045Z","steps":["trace[512895411] 'process raft request' (duration: 108.335216ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:00.658897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.453504ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T20:27:00.658997Z","caller":"traceutil/trace.go:171","msg":"trace[903072282] linearizableReadLoop","detail":"{readStateIndex:535; appliedIndex:533; }","duration":"712.972134ms","start":"2026-02-27T20:26:59.946007Z","end":"2026-02-27T20:27:00.658979Z","steps":["trace[903072282] 'read index received' (duration: 12.841943ms)","trace[903072282] 'applied index is now lower than readState.Index' (duration: 700.128621ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:27:00.659095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.087186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.53\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-27T20:27:00.659097Z","caller":"traceutil/trace.go:171","msg":"trace[976461904] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"808.332963ms","start":"2026-02-27T20:26:59.850733Z","end":"2026-02-27T20:27:00.659066Z","steps":["trace[976461904] 'process raft request' (duration: 661.114243ms)","trace[976461904] 'compare' (duration: 146.100964ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:27:00.659253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:26:59.850719Z","time spent":"808.452026ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:27:00.659128Z","caller":"traceutil/trace.go:171","msg":"trace[142586225] range","detail":"{range_begin:/registry/masterleases/199.204.45.53; range_end:; response_count:1; response_revision:518; }","duration":"713.144087ms","start":"2026-02-27T20:26:59.945975Z","end":"2026-02-27T20:27:00.659119Z","steps":["trace[142586225] 'agreement among raft nodes before linearized reading' (duration: 713.053435ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:00.659309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:26:59.945962Z","time spent":"713.336812ms","remote":"127.0.0.1:50608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.53\" "} {"level":"info","ts":"2026-02-27T20:27:04.263058Z","caller":"traceutil/trace.go:171","msg":"trace[32034439] linearizableReadLoop","detail":"{readStateIndex:548; appliedIndex:547; }","duration":"283.391898ms","start":"2026-02-27T20:27:03.979641Z","end":"2026-02-27T20:27:04.263033Z","steps":["trace[32034439] 'read index received' (duration: 283.175243ms)","trace[32034439] 'applied index is now lower than readState.Index' (duration: 215.625µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:27:04.263151Z","caller":"traceutil/trace.go:171","msg":"trace[1528417237] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"287.730266ms","start":"2026-02-27T20:27:03.975387Z","end":"2026-02-27T20:27:04.263118Z","steps":["trace[1528417237] 'process raft request' (duration: 287.501181ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:04.263283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.624573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mkbvd\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-02-27T20:27:04.263339Z","caller":"traceutil/trace.go:171","msg":"trace[1000082001] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mkbvd; range_end:; response_count:1; response_revision:530; }","duration":"283.700074ms","start":"2026-02-27T20:27:03.979619Z","end":"2026-02-27T20:27:04.263319Z","steps":["trace[1000082001] 'agreement among raft nodes before linearized reading' (duration: 283.51249ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:04.554751Z","caller":"traceutil/trace.go:171","msg":"trace[1620110633] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"278.024295ms","start":"2026-02-27T20:27:04.2767Z","end":"2026-02-27T20:27:04.554725Z","steps":["trace[1620110633] 'process raft request' (duration: 277.77106ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:05.454873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.760969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-27T20:27:05.454981Z","caller":"traceutil/trace.go:171","msg":"trace[1084906254] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:531; }","duration":"437.918073ms","start":"2026-02-27T20:27:05.017037Z","end":"2026-02-27T20:27:05.454955Z","steps":["trace[1084906254] 'range keys from in-memory index tree' (duration: 437.577926ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:05.455038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:05.017018Z","time spent":"438.004515ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-27T20:27:05.455206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.324183ms","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-27T20:27:05.455273Z","caller":"traceutil/trace.go:171","msg":"trace[6598633] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:531; }","duration":"783.445396ms","start":"2026-02-27T20:27:04.671809Z","end":"2026-02-27T20:27:05.455254Z","steps":["trace[6598633] 'range keys from in-memory index tree' (duration: 783.145669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:05.45531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:04.671784Z","time spent":"783.518257ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-27T20:27:05.455456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.511264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-27T20:27:05.455434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.800416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:27:05.455582Z","caller":"traceutil/trace.go:171","msg":"trace[1619449791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:531; }","duration":"640.620196ms","start":"2026-02-27T20:27:04.814889Z","end":"2026-02-27T20:27:05.45551Z","steps":["trace[1619449791] 'range keys from in-memory index tree' (duration: 640.367ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:05.455591Z","caller":"traceutil/trace.go:171","msg":"trace[1102734849] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:531; }","duration":"664.97476ms","start":"2026-02-27T20:27:04.790591Z","end":"2026-02-27T20:27:05.455565Z","steps":["trace[1102734849] 'range keys from in-memory index tree' (duration: 664.182811ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:05.455634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:04.814866Z","time spent":"640.754889ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-27T20:27:05.455653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:04.790575Z","time spent":"665.062711ms","remote":"127.0.0.1:50582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T20:27:06.03786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.238382ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-27T20:27:06.038054Z","caller":"traceutil/trace.go:171","msg":"trace[274446151] linearizableReadLoop","detail":"{readStateIndex:550; appliedIndex:549; }","duration":"580.892656ms","start":"2026-02-27T20:27:05.457142Z","end":"2026-02-27T20:27:06.038034Z","steps":["trace[274446151] 'read index received' (duration: 18.531941ms)","trace[274446151] 'applied index is now lower than readState.Index' (duration: 562.359705ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:27:06.038147Z","caller":"traceutil/trace.go:171","msg":"trace[732152225] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"578.128333ms","start":"2026-02-27T20:27:05.460008Z","end":"2026-02-27T20:27:06.038137Z","steps":["trace[732152225] 'process raft request' (duration: 577.946289ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:06.038239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:05.459994Z","time spent":"578.184814ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:27:06.042024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.864236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:27:06.042084Z","caller":"traceutil/trace.go:171","msg":"trace[1960023498] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:532; }","duration":"584.974698ms","start":"2026-02-27T20:27:05.457096Z","end":"2026-02-27T20:27:06.042071Z","steps":["trace[1960023498] 'agreement among raft nodes before linearized reading' (duration: 580.994008ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:06.042113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:05.457077Z","time spent":"585.030709ms","remote":"127.0.0.1:50590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-27T20:27:06.042264Z","caller":"traceutil/trace.go:171","msg":"trace[1582082918] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"581.926789ms","start":"2026-02-27T20:27:05.46032Z","end":"2026-02-27T20:27:06.042247Z","steps":["trace[1582082918] 'process raft request' (duration: 581.852208ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:06.04227Z","caller":"traceutil/trace.go:171","msg":"trace[57463838] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"581.948879ms","start":"2026-02-27T20:27:05.460297Z","end":"2026-02-27T20:27:06.042246Z","steps":["trace[57463838] 'process raft request' (duration: 577.832916ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:06.04235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:05.460303Z","time spent":"582.005422ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:27:06.042395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:05.460287Z","time spent":"582.041543ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:27:17.720818Z","caller":"traceutil/trace.go:171","msg":"trace[601099519] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"110.804601ms","start":"2026-02-27T20:27:17.609971Z","end":"2026-02-27T20:27:17.720775Z","steps":["trace[601099519] 'process raft request' (duration: 110.632577ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:17.868061Z","caller":"traceutil/trace.go:171","msg":"trace[455011568] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"141.288615ms","start":"2026-02-27T20:27:17.726749Z","end":"2026-02-27T20:27:17.868038Z","steps":["trace[455011568] 'process raft request' (duration: 138.692895ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:17.872371Z","caller":"traceutil/trace.go:171","msg":"trace[1773871134] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"138.002069ms","start":"2026-02-27T20:27:17.734354Z","end":"2026-02-27T20:27:17.872356Z","steps":["trace[1773871134] 'process raft request' (duration: 137.854576ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:17.872417Z","caller":"traceutil/trace.go:171","msg":"trace[545508109] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"140.561938ms","start":"2026-02-27T20:27:17.731819Z","end":"2026-02-27T20:27:17.872381Z","steps":["trace[545508109] 'process raft request' (duration: 140.289092ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:23.815711Z","caller":"traceutil/trace.go:171","msg":"trace[179462263] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"638.907057ms","start":"2026-02-27T20:27:23.176776Z","end":"2026-02-27T20:27:23.815683Z","steps":["trace[179462263] 'process raft request' (duration: 572.68758ms)","trace[179462263] 'compare' (duration: 66.000962ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:27:23.815873Z","caller":"traceutil/trace.go:171","msg":"trace[1905420167] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"617.12078ms","start":"2026-02-27T20:27:23.198723Z","end":"2026-02-27T20:27:23.815843Z","steps":["trace[1905420167] 'process raft request' (duration: 616.908106ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:23.815904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:23.176758Z","time spent":"639.05415ms","remote":"127.0.0.1:51074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4204,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:27:23.816041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:23.198704Z","time spent":"617.238734ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:27:25.225376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.50435ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T20:27:25.225633Z","caller":"traceutil/trace.go:171","msg":"trace[944915201] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"1.33175406s","start":"2026-02-27T20:27:23.893843Z","end":"2026-02-27T20:27:25.225597Z","steps":["trace[944915201] 'process raft request' (duration: 531.946463ms)","trace[944915201] 'compare' (duration: 798.891166ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:27:25.225717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:23.893827Z","time spent":"1.331852482s","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:27:25.225714Z","caller":"traceutil/trace.go:171","msg":"trace[1181147196] linearizableReadLoop","detail":"{readStateIndex:742; appliedIndex:741; }","duration":"1.075493859s","start":"2026-02-27T20:27:24.150178Z","end":"2026-02-27T20:27:25.225672Z","steps":["trace[1181147196] 'read index received' (duration: 274.757882ms)","trace[1181147196] 'applied index is now lower than readState.Index' (duration: 800.732157ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:27:25.820559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.670352695s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-27T20:27:25.82068Z","caller":"traceutil/trace.go:171","msg":"trace[903858318] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:719; }","duration":"1.670512848s","start":"2026-02-27T20:27:24.150141Z","end":"2026-02-27T20:27:25.820653Z","steps":["trace[903858318] 'agreement among raft nodes before linearized reading' (duration: 1.075724845s)","trace[903858318] 'range keys from in-memory index tree' (duration: 594.553089ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:27:25.820728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:24.150122Z","time spent":"1.6705968s","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-27T20:27:25.820684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.209934ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-27T20:27:25.820788Z","caller":"traceutil/trace.go:171","msg":"trace[1139566260] linearizableReadLoop","detail":"{readStateIndex:743; appliedIndex:742; }","duration":"594.963717ms","start":"2026-02-27T20:27:25.22581Z","end":"2026-02-27T20:27:25.820774Z","steps":["trace[1139566260] 'read index received' (duration: 72.461µs)","trace[1139566260] 'applied index is now lower than readState.Index' (duration: 594.890286ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:27:25.820913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.669120186s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-27T20:27:25.820933Z","caller":"traceutil/trace.go:171","msg":"trace[807933384] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:719; }","duration":"1.669158317s","start":"2026-02-27T20:27:24.151767Z","end":"2026-02-27T20:27:25.820925Z","steps":["trace[807933384] 'agreement among raft nodes before linearized reading' (duration: 1.669047855s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:25.820956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:24.15176Z","time spent":"1.669190958s","remote":"127.0.0.1:50876","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-27T20:27:25.820925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.028844768s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:27:25.821011Z","caller":"traceutil/trace.go:171","msg":"trace[1639798901] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:719; }","duration":"1.028982901s","start":"2026-02-27T20:27:24.792008Z","end":"2026-02-27T20:27:25.820991Z","steps":["trace[1639798901] 'agreement among raft nodes before linearized reading' (duration: 1.028784746s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:25.821011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.477564299s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-27T20:27:25.821058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.669182257s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-27T20:27:25.821055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:24.791986Z","time spent":"1.029061643s","remote":"127.0.0.1:50582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-27T20:27:25.821076Z","caller":"traceutil/trace.go:171","msg":"trace[707498348] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:719; }","duration":"1.669228659s","start":"2026-02-27T20:27:24.151842Z","end":"2026-02-27T20:27:25.82107Z","steps":["trace[707498348] 'agreement among raft nodes before linearized reading' (duration: 1.669178128s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:25.821092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:24.151824Z","time spent":"1.669264289s","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-27T20:27:25.82108Z","caller":"traceutil/trace.go:171","msg":"trace[431552137] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:719; }","duration":"1.477666941s","start":"2026-02-27T20:27:24.343394Z","end":"2026-02-27T20:27:25.821061Z","steps":["trace[431552137] 'agreement among raft nodes before linearized reading' (duration: 1.477394754s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:25.821128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:24.343379Z","time spent":"1.477741023s","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-27T20:27:25.994466Z","caller":"traceutil/trace.go:171","msg":"trace[999767344] linearizableReadLoop","detail":"{readStateIndex:747; appliedIndex:744; }","duration":"165.024445ms","start":"2026-02-27T20:27:25.829423Z","end":"2026-02-27T20:27:25.994448Z","steps":["trace[999767344] 'read index received' (duration: 5.493976ms)","trace[999767344] 'applied index is now lower than readState.Index' (duration: 159.529709ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:27:25.994561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.117287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:27:25.994545Z","caller":"traceutil/trace.go:171","msg":"trace[1030739959] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"169.14999ms","start":"2026-02-27T20:27:25.825353Z","end":"2026-02-27T20:27:25.994503Z","steps":["trace[1030739959] 'process raft request' (duration: 169.041867ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:25.994569Z","caller":"traceutil/trace.go:171","msg":"trace[918547130] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"169.385204ms","start":"2026-02-27T20:27:25.825153Z","end":"2026-02-27T20:27:25.994538Z","steps":["trace[918547130] 'process raft request' (duration: 143.768982ms)","trace[918547130] 'compare' (duration: 25.371397ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:27:25.994616Z","caller":"traceutil/trace.go:171","msg":"trace[1136582981] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"169.035667ms","start":"2026-02-27T20:27:25.825572Z","end":"2026-02-27T20:27:25.994608Z","steps":["trace[1136582981] 'process raft request' (duration: 168.848863ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:25.994586Z","caller":"traceutil/trace.go:171","msg":"trace[769103501] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:723; }","duration":"165.158148ms","start":"2026-02-27T20:27:25.829419Z","end":"2026-02-27T20:27:25.994577Z","steps":["trace[769103501] 'agreement among raft nodes before linearized reading' (duration: 165.100637ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:27.380573Z","caller":"traceutil/trace.go:171","msg":"trace[994012776] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"147.789582ms","start":"2026-02-27T20:27:27.232765Z","end":"2026-02-27T20:27:27.380555Z","steps":["trace[994012776] 'process raft request' (duration: 147.67935ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:46.232827Z","caller":"traceutil/trace.go:171","msg":"trace[325425835] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"253.247708ms","start":"2026-02-27T20:27:45.979548Z","end":"2026-02-27T20:27:46.232795Z","steps":["trace[325425835] 'process raft request' (duration: 253.035683ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:46.826043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.40677ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T20:27:46.826145Z","caller":"traceutil/trace.go:171","msg":"trace[1977673023] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"589.809816ms","start":"2026-02-27T20:27:46.236315Z","end":"2026-02-27T20:27:46.826125Z","steps":["trace[1977673023] 'process raft request' (duration: 416.199451ms)","trace[1977673023] 'compare' (duration: 173.286388ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:27:46.826202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:46.236297Z","time spent":"589.878177ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:27:47.304842Z","caller":"traceutil/trace.go:171","msg":"trace[1859830591] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"1.068128867s","start":"2026-02-27T20:27:46.236682Z","end":"2026-02-27T20:27:47.304811Z","steps":["trace[1859830591] 'process raft request' (duration: 1.067884172s)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:47.304987Z","caller":"traceutil/trace.go:171","msg":"trace[1149621645] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"1.067970724s","start":"2026-02-27T20:27:46.236958Z","end":"2026-02-27T20:27:47.304929Z","steps":["trace[1149621645] 'process raft request' (duration: 1.067750529s)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:47.304774Z","caller":"traceutil/trace.go:171","msg":"trace[1393853333] linearizableReadLoop","detail":"{readStateIndex:814; appliedIndex:811; }","duration":"1.035517732s","start":"2026-02-27T20:27:46.269234Z","end":"2026-02-27T20:27:47.304752Z","steps":["trace[1393853333] 'read index received' (duration: 383.380779ms)","trace[1393853333] 'applied index is now lower than readState.Index' (duration: 652.136093ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:27:47.304976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.035725176s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T20:27:47.30534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:46.236937Z","time spent":"1.06823233s","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:27:47.305339Z","caller":"traceutil/trace.go:171","msg":"trace[1923642734] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:785; }","duration":"1.036113524s","start":"2026-02-27T20:27:46.269196Z","end":"2026-02-27T20:27:47.30531Z","steps":["trace[1923642734] 'agreement among raft nodes before linearized reading' (duration: 1.035720496s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:47.305462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:46.269185Z","time spent":"1.036237268s","remote":"127.0.0.1:50992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-02-27T20:27:47.305594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:46.236663Z","time spent":"1.068403694s","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:27:47.306158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.892776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:27:47.306252Z","caller":"traceutil/trace.go:171","msg":"trace[834545091] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:785; }","duration":"514.03196ms","start":"2026-02-27T20:27:46.792206Z","end":"2026-02-27T20:27:47.306238Z","steps":["trace[834545091] 'agreement among raft nodes before linearized reading' (duration: 513.908728ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:47.306156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"983.614773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-27T20:27:47.30634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:46.792181Z","time spent":"514.147702ms","remote":"127.0.0.1:50582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-27T20:27:47.306449Z","caller":"traceutil/trace.go:171","msg":"trace[676789664] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:785; }","duration":"983.909589ms","start":"2026-02-27T20:27:46.322493Z","end":"2026-02-27T20:27:47.306403Z","steps":["trace[676789664] 'agreement among raft nodes before linearized reading' (duration: 983.604872ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:47.306607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:46.322478Z","time spent":"984.040872ms","remote":"127.0.0.1:50686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":12,"response size":31,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"info","ts":"2026-02-27T20:27:48.364088Z","caller":"traceutil/trace.go:171","msg":"trace[955149414] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"123.008617ms","start":"2026-02-27T20:27:48.241056Z","end":"2026-02-27T20:27:48.364064Z","steps":["trace[955149414] 'process raft request' (duration: 122.849724ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:49.246301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.064141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T20:27:49.246397Z","caller":"traceutil/trace.go:171","msg":"trace[1436294062] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:788; }","duration":"117.285616ms","start":"2026-02-27T20:27:49.129088Z","end":"2026-02-27T20:27:49.246374Z","steps":["trace[1436294062] 'count revisions from in-memory index tree' (duration: 116.695553ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:49.418Z","caller":"traceutil/trace.go:171","msg":"trace[1909973037] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"103.520311ms","start":"2026-02-27T20:27:49.314457Z","end":"2026-02-27T20:27:49.417978Z","steps":["trace[1909973037] 'process raft request' (duration: 103.352857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:49.618643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.236408ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T20:27:49.618809Z","caller":"traceutil/trace.go:171","msg":"trace[1824152057] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"304.317329ms","start":"2026-02-27T20:27:49.314463Z","end":"2026-02-27T20:27:49.618781Z","steps":["trace[1824152057] 'process raft request' (duration: 188.834465ms)","trace[1824152057] 'compare' (duration: 115.138826ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:27:49.618766Z","caller":"traceutil/trace.go:171","msg":"trace[1392043878] linearizableReadLoop","detail":"{readStateIndex:819; appliedIndex:818; }","duration":"111.326739ms","start":"2026-02-27T20:27:49.50742Z","end":"2026-02-27T20:27:49.618747Z","steps":["trace[1392043878] 'read index received' (duration: 41.821µs)","trace[1392043878] 'applied index is now lower than readState.Index' (duration: 111.283368ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:27:49.618956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:49.314448Z","time spent":"304.429051ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:27:49.619064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.640226ms","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-27T20:27:49.619134Z","caller":"traceutil/trace.go:171","msg":"trace[1299161411] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:790; }","duration":"111.740608ms","start":"2026-02-27T20:27:49.507363Z","end":"2026-02-27T20:27:49.619104Z","steps":["trace[1299161411] 'agreement among raft nodes before linearized reading' (duration: 111.557034ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:49.949011Z","caller":"traceutil/trace.go:171","msg":"trace[1670616437] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:819; }","duration":"156.992894ms","start":"2026-02-27T20:27:49.791972Z","end":"2026-02-27T20:27:49.948965Z","steps":["trace[1670616437] 'read index received' (duration: 125.528994ms)","trace[1670616437] 'applied index is now lower than readState.Index' (duration: 31.46231ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:27:49.949031Z","caller":"traceutil/trace.go:171","msg":"trace[2099458677] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"325.597265ms","start":"2026-02-27T20:27:49.62341Z","end":"2026-02-27T20:27:49.949007Z","steps":["trace[2099458677] 'process raft request' (duration: 294.181146ms)","trace[2099458677] 'compare' (duration: 31.181214ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:27:49.949203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:49.623349Z","time spent":"325.749878ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:27:49.94936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.283781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:27:49.949415Z","caller":"traceutil/trace.go:171","msg":"trace[578701519] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:791; }","duration":"157.481295ms","start":"2026-02-27T20:27:49.791917Z","end":"2026-02-27T20:27:49.949399Z","steps":["trace[578701519] 'agreement among raft nodes before linearized reading' (duration: 157.171578ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:28:01.836796Z","caller":"traceutil/trace.go:171","msg":"trace[252948727] linearizableReadLoop","detail":"{readStateIndex:909; appliedIndex:908; }","duration":"168.268713ms","start":"2026-02-27T20:28:01.668428Z","end":"2026-02-27T20:28:01.836696Z","steps":["trace[252948727] 'read index received' (duration: 168.13162ms)","trace[252948727] 'applied index is now lower than readState.Index' (duration: 136.443µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:28:01.836991Z","caller":"traceutil/trace.go:171","msg":"trace[276189233] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"371.362163ms","start":"2026-02-27T20:28:01.465622Z","end":"2026-02-27T20:28:01.836984Z","steps":["trace[276189233] 'process raft request' (duration: 370.990544ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:01.837136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:01.465611Z","time spent":"371.478745ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:28:01.837317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.888908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-27T20:28:01.837339Z","caller":"traceutil/trace.go:171","msg":"trace[749660634] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:875; }","duration":"168.94316ms","start":"2026-02-27T20:28:01.66839Z","end":"2026-02-27T20:28:01.837333Z","steps":["trace[749660634] 'agreement among raft nodes before linearized reading' (duration: 168.842706ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:03.291479Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794897559906107008,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T20:28:03.71146Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.251829034s","expected-duration":"1s"} {"level":"info","ts":"2026-02-27T20:28:03.711868Z","caller":"traceutil/trace.go:171","msg":"trace[171255407] linearizableReadLoop","detail":"{readStateIndex:912; appliedIndex:911; }","duration":"920.844835ms","start":"2026-02-27T20:28:02.790983Z","end":"2026-02-27T20:28:03.711828Z","steps":["trace[171255407] 'read index received' (duration: 920.649121ms)","trace[171255407] 'applied index is now lower than readState.Index' (duration: 194.824µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:28:03.711914Z","caller":"traceutil/trace.go:171","msg":"trace[283891519] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"1.252281274s","start":"2026-02-27T20:28:02.459583Z","end":"2026-02-27T20:28:03.711864Z","steps":["trace[283891519] 'process raft request' (duration: 1.25209372s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:03.71202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"921.009679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:28:03.712064Z","caller":"traceutil/trace.go:171","msg":"trace[1354347542] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:878; }","duration":"921.114821ms","start":"2026-02-27T20:28:02.790937Z","end":"2026-02-27T20:28:03.712051Z","steps":["trace[1354347542] 'agreement among raft nodes before linearized reading' (duration: 921.022399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:03.712084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:02.459575Z","time spent":"1.252421037s","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:28:03.712102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:02.79092Z","time spent":"921.174533ms","remote":"127.0.0.1:50582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T20:28:03.712175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.810557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-27T20:28:03.712258Z","caller":"traceutil/trace.go:171","msg":"trace[1399955924] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:878; }","duration":"738.94247ms","start":"2026-02-27T20:28:02.973295Z","end":"2026-02-27T20:28:03.712237Z","steps":["trace[1399955924] 'agreement among raft nodes before linearized reading' (duration: 738.716495ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:03.712306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:02.973252Z","time spent":"739.045753ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-27T20:28:04.447548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.463085ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T20:28:04.447665Z","caller":"traceutil/trace.go:171","msg":"trace[234351102] linearizableReadLoop","detail":"{readStateIndex:913; appliedIndex:912; }","duration":"656.264387ms","start":"2026-02-27T20:28:03.791384Z","end":"2026-02-27T20:28:04.447649Z","steps":["trace[234351102] 'read index received' (duration: 84.596107ms)","trace[234351102] 'applied index is now lower than readState.Index' (duration: 571.6672ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:28:04.447747Z","caller":"traceutil/trace.go:171","msg":"trace[523117911] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"732.40304ms","start":"2026-02-27T20:28:03.715334Z","end":"2026-02-27T20:28:04.447737Z","steps":["trace[523117911] 'process raft request' (duration: 160.645668ms)","trace[523117911] 'compare' (duration: 571.037226ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:28:04.447803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:03.715309Z","time spent":"732.462341ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:28:04.447877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.416419ms","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-27T20:28:04.447927Z","caller":"traceutil/trace.go:171","msg":"trace[102379726] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:879; }","duration":"604.506681ms","start":"2026-02-27T20:28:03.843406Z","end":"2026-02-27T20:28:04.447913Z","steps":["trace[102379726] 'agreement among raft nodes before linearized reading' (duration: 604.385218ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:04.44795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:03.843387Z","time spent":"604.559191ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-27T20:28:04.448018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.770266ms","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-27T20:28:04.448077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.688646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:28:04.448081Z","caller":"traceutil/trace.go:171","msg":"trace[1997460703] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:879; }","duration":"431.873998ms","start":"2026-02-27T20:28:04.016191Z","end":"2026-02-27T20:28:04.448065Z","steps":["trace[1997460703] 'agreement among raft nodes before linearized reading' (duration: 431.653123ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:04.448088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.696733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-27T20:28:04.448127Z","caller":"traceutil/trace.go:171","msg":"trace[162326964] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:879; }","duration":"656.759187ms","start":"2026-02-27T20:28:03.791353Z","end":"2026-02-27T20:28:04.448112Z","steps":["trace[162326964] 'agreement among raft nodes before linearized reading' (duration: 656.693406ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:04.448137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:04.016175Z","time spent":"431.953502ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-27T20:28:04.448146Z","caller":"traceutil/trace.go:171","msg":"trace[2040653388] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:879; }","duration":"596.781416ms","start":"2026-02-27T20:28:03.851347Z","end":"2026-02-27T20:28:04.448128Z","steps":["trace[2040653388] 'agreement among raft nodes before linearized reading' (duration: 596.601991ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:04.448159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:03.791339Z","time spent":"656.81343ms","remote":"127.0.0.1:50582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T20:28:04.448181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:03.851337Z","time spent":"596.838647ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-27T20:28:16.984085Z","caller":"traceutil/trace.go:171","msg":"trace[716977922] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"487.718157ms","start":"2026-02-27T20:28:16.496345Z","end":"2026-02-27T20:28:16.984063Z","steps":["trace[716977922] 'process raft request' (duration: 487.517793ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:16.984213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:16.496329Z","time spent":"487.81636ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:28:16.984062Z","caller":"traceutil/trace.go:171","msg":"trace[1463658487] linearizableReadLoop","detail":"{readStateIndex:950; appliedIndex:949; }","duration":"487.622125ms","start":"2026-02-27T20:28:16.49641Z","end":"2026-02-27T20:28:16.984032Z","steps":["trace[1463658487] 'read index received' (duration: 487.432991ms)","trace[1463658487] 'applied index is now lower than readState.Index' (duration: 187.974µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:28:16.984267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.83691ms","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-27T20:28:16.984331Z","caller":"traceutil/trace.go:171","msg":"trace[1810702528] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:913; }","duration":"487.939904ms","start":"2026-02-27T20:28:16.496375Z","end":"2026-02-27T20:28:16.984315Z","steps":["trace[1810702528] 'agreement among raft nodes before linearized reading' (duration: 487.751678ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:16.984353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.900897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T20:28:16.984364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:16.496358Z","time spent":"487.997255ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-27T20:28:16.984397Z","caller":"traceutil/trace.go:171","msg":"trace[334554135] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:913; }","duration":"192.969498ms","start":"2026-02-27T20:28:16.791416Z","end":"2026-02-27T20:28:16.984385Z","steps":["trace[334554135] 'agreement among raft nodes before linearized reading' (duration: 192.910387ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:16.984437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.492111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-27T20:28:16.984488Z","caller":"traceutil/trace.go:171","msg":"trace[1393420254] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:913; }","duration":"483.578753ms","start":"2026-02-27T20:28:16.500895Z","end":"2026-02-27T20:28:16.984474Z","steps":["trace[1393420254] 'agreement among raft nodes before linearized reading' (duration: 483.42365ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:16.984526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:16.500881Z","time spent":"483.638074ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-27T20:28:16.984488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.76039ms","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-27T20:28:16.984593Z","caller":"traceutil/trace.go:171","msg":"trace[1317703974] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:913; }","duration":"446.889514ms","start":"2026-02-27T20:28:16.537685Z","end":"2026-02-27T20:28:16.984574Z","steps":["trace[1317703974] 'agreement among raft nodes before linearized reading' (duration: 446.661898ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:16.984649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:16.537674Z","time spent":"446.962835ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-27T20:28:21.044448Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794897559906107168,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T20:28:21.545591Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794897559906107168,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-27T20:28:21.644916Z","caller":"traceutil/trace.go:171","msg":"trace[1519145107] linearizableReadLoop","detail":"{readStateIndex:982; appliedIndex:981; }","duration":"1.10147446s","start":"2026-02-27T20:28:20.543422Z","end":"2026-02-27T20:28:21.644896Z","steps":["trace[1519145107] 'read index received' (duration: 1.101376708s)","trace[1519145107] 'applied index is now lower than readState.Index' (duration: 97.112µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:28:21.645006Z","caller":"traceutil/trace.go:171","msg":"trace[574430199] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"1.131406106s","start":"2026-02-27T20:28:20.513569Z","end":"2026-02-27T20:28:21.644975Z","steps":["trace[574430199] 'process raft request' (duration: 1.131224692s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:21.645074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.773519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:28:21.645103Z","caller":"traceutil/trace.go:171","msg":"trace[439800812] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:944; }","duration":"853.817031ms","start":"2026-02-27T20:28:20.791271Z","end":"2026-02-27T20:28:21.645088Z","steps":["trace[439800812] 'agreement among raft nodes before linearized reading' (duration: 853.79172ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:21.645125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:20.791258Z","time spent":"853.862361ms","remote":"127.0.0.1:50582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T20:28:21.645154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:20.513552Z","time spent":"1.131524398s","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:28:21.645042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.101611044s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T20:28:21.645248Z","caller":"traceutil/trace.go:171","msg":"trace[2081160580] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:944; }","duration":"1.10186048s","start":"2026-02-27T20:28:20.543368Z","end":"2026-02-27T20:28:21.645228Z","steps":["trace[2081160580] 'agreement among raft nodes before linearized reading' (duration: 1.101624874s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:21.645288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T20:28:21.645307Z","caller":"traceutil/trace.go:171","msg":"trace[982288002] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:944; }","duration":"411.42355ms","start":"2026-02-27T20:28:21.233878Z","end":"2026-02-27T20:28:21.645302Z","steps":["trace[982288002] 'agreement among raft nodes before linearized reading' (duration: 411.38862ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:21.6453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:20.543351Z","time spent":"1.101932221s","remote":"127.0.0.1:51160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":23,"response size":31,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-02-27T20:28:21.645326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:21.233863Z","time spent":"411.459122ms","remote":"127.0.0.1:50774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":12,"response size":31,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-02-27T20:28:21.645462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.357766ms","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-27T20:28:21.645479Z","caller":"traceutil/trace.go:171","msg":"trace[568219384] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:944; }","duration":"641.395246ms","start":"2026-02-27T20:28:21.004078Z","end":"2026-02-27T20:28:21.645473Z","steps":["trace[568219384] 'agreement among raft nodes before linearized reading' (duration: 641.328005ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:21.645506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:21.004068Z","time spent":"641.422767ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-27T20:28:21.645566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.695823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-27T20:28:21.645597Z","caller":"traceutil/trace.go:171","msg":"trace[775054090] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:944; }","duration":"648.748824ms","start":"2026-02-27T20:28:20.996838Z","end":"2026-02-27T20:28:21.645587Z","steps":["trace[775054090] 'agreement among raft nodes before linearized reading' (duration: 648.655332ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:21.645625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:20.996827Z","time spent":"648.790574ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-27T20:28:21.645696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.495271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-27T20:28:21.645714Z","caller":"traceutil/trace.go:171","msg":"trace[1582749232] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:944; }","duration":"642.535523ms","start":"2026-02-27T20:28:21.003173Z","end":"2026-02-27T20:28:21.645708Z","steps":["trace[1582749232] 'agreement among raft nodes before linearized reading' (duration: 642.474411ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:21.645732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:21.003161Z","time spent":"642.566503ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-27T20:28:21.645792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.615245ms","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-27T20:28:21.64582Z","caller":"traceutil/trace.go:171","msg":"trace[2070203683] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:944; }","duration":"642.665826ms","start":"2026-02-27T20:28:21.003146Z","end":"2026-02-27T20:28:21.645812Z","steps":["trace[2070203683] 'agreement among raft nodes before linearized reading' (duration: 642.585713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:21.645849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:21.003136Z","time spent":"642.706657ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-27T20:28:22.214699Z","caller":"traceutil/trace.go:171","msg":"trace[2004060361] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"566.250217ms","start":"2026-02-27T20:28:21.648426Z","end":"2026-02-27T20:28:22.214677Z","steps":["trace[2004060361] 'process raft request' (duration: 507.568223ms)","trace[2004060361] 'compare' (duration: 58.302864ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:28:22.214853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:21.648402Z","time spent":"566.36061ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:28:22.235934Z","caller":"traceutil/trace.go:171","msg":"trace[830212294] linearizableReadLoop","detail":"{readStateIndex:986; appliedIndex:982; }","duration":"430.772163ms","start":"2026-02-27T20:28:21.805143Z","end":"2026-02-27T20:28:22.235916Z","steps":["trace[830212294] 'read index received' (duration: 350.870834ms)","trace[830212294] 'applied index is now lower than readState.Index' (duration: 79.900409ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:28:22.236142Z","caller":"traceutil/trace.go:171","msg":"trace[1326916064] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"587.451561ms","start":"2026-02-27T20:28:21.64868Z","end":"2026-02-27T20:28:22.236132Z","steps":["trace[1326916064] 'process raft request' (duration: 587.091133ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:22.236225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:21.648663Z","time spent":"587.513062ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:28:22.236278Z","caller":"traceutil/trace.go:171","msg":"trace[2025614227] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"586.52538ms","start":"2026-02-27T20:28:21.64972Z","end":"2026-02-27T20:28:22.236246Z","steps":["trace[2025614227] 'process raft request' (duration: 586.164451ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:22.23646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:21.649693Z","time spent":"586.677933ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:28:22.23648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.310086ms","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-27T20:28:22.236514Z","caller":"traceutil/trace.go:171","msg":"trace[2067144500] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:948; }","duration":"431.396317ms","start":"2026-02-27T20:28:21.805108Z","end":"2026-02-27T20:28:22.236505Z","steps":["trace[2067144500] 'agreement among raft nodes before linearized reading' (duration: 431.263344ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:22.236548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:21.80509Z","time spent":"431.451469ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-27T20:28:22.236662Z","caller":"traceutil/trace.go:171","msg":"trace[1294662102] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"587.216155ms","start":"2026-02-27T20:28:21.649437Z","end":"2026-02-27T20:28:22.236653Z","steps":["trace[1294662102] 'process raft request' (duration: 586.411527ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:22.236715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:21.64943Z","time spent":"587.256877ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:28:22.375653Z","caller":"traceutil/trace.go:171","msg":"trace[1068766183] linearizableReadLoop","detail":"{readStateIndex:987; appliedIndex:986; }","duration":"139.648627ms","start":"2026-02-27T20:28:22.235985Z","end":"2026-02-27T20:28:22.375633Z","steps":["trace[1068766183] 'read index received' (duration: 139.542735ms)","trace[1068766183] 'applied index is now lower than readState.Index' (duration: 104.892µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:28:22.375723Z","caller":"traceutil/trace.go:171","msg":"trace[1996284208] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"211.883332ms","start":"2026-02-27T20:28:22.163803Z","end":"2026-02-27T20:28:22.375686Z","steps":["trace[1996284208] 'process raft request' (duration: 211.722348ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:22.375819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.709101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:28:22.375867Z","caller":"traceutil/trace.go:171","msg":"trace[364470113] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:949; }","duration":"554.808613ms","start":"2026-02-27T20:28:21.821047Z","end":"2026-02-27T20:28:22.375855Z","steps":["trace[364470113] 'agreement among raft nodes before linearized reading' (duration: 554.688941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:22.375899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:21.821027Z","time spent":"554.867925ms","remote":"127.0.0.1:50582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-27T20:28:22.391755Z","caller":"traceutil/trace.go:171","msg":"trace[516612855] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"150.803834ms","start":"2026-02-27T20:28:22.240935Z","end":"2026-02-27T20:28:22.391739Z","steps":["trace[516612855] 'process raft request' (duration: 150.685811ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:23.264647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.789299ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-27T20:28:23.264765Z","caller":"traceutil/trace.go:171","msg":"trace[1332251240] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"233.701601ms","start":"2026-02-27T20:28:23.031045Z","end":"2026-02-27T20:28:23.264747Z","steps":["trace[1332251240] 'process raft request' (duration: 104.749028ms)","trace[1332251240] 'compare' (duration: 128.679307ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:28:24.380968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.190225ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-27T20:28:24.381233Z","caller":"traceutil/trace.go:171","msg":"trace[1264862980] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:992; }","duration":"590.696005ms","start":"2026-02-27T20:28:23.790514Z","end":"2026-02-27T20:28:24.38121Z","steps":["trace[1264862980] 'read index received' (duration: 24.34µs)","trace[1264862980] 'applied index is now lower than readState.Index' (duration: 590.670345ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:28:24.381353Z","caller":"traceutil/trace.go:171","msg":"trace[1954325678] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"1.324200781s","start":"2026-02-27T20:28:23.057139Z","end":"2026-02-27T20:28:24.381339Z","steps":["trace[1954325678] 'process raft request' (duration: 1.323955226s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:24.381459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.651172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-27T20:28:24.38149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:23.05713Z","time spent":"1.324296103s","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:28:24.381524Z","caller":"traceutil/trace.go:171","msg":"trace[1649103987] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:956; }","duration":"168.780525ms","start":"2026-02-27T20:28:24.212727Z","end":"2026-02-27T20:28:24.381508Z","steps":["trace[1649103987] 'agreement among raft nodes before linearized reading' (duration: 168.663072ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:24.381533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.274452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-27T20:28:24.38154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.019453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:28:24.381587Z","caller":"traceutil/trace.go:171","msg":"trace[1988454376] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:956; }","duration":"140.370244ms","start":"2026-02-27T20:28:24.241203Z","end":"2026-02-27T20:28:24.381573Z","steps":["trace[1988454376] 'agreement among raft nodes before linearized reading' (duration: 140.207101ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:28:24.381395Z","caller":"traceutil/trace.go:171","msg":"trace[1219178869] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"1.324412565s","start":"2026-02-27T20:28:23.056946Z","end":"2026-02-27T20:28:24.381358Z","steps":["trace[1219178869] 'process raft request' (duration: 581.749731ms)","trace[1219178869] 'compare' (duration: 742.03943ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:28:24.381712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.156309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-02-27T20:28:24.381603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.087854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-27T20:28:24.381758Z","caller":"traceutil/trace.go:171","msg":"trace[764320521] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:956; }","duration":"140.20529ms","start":"2026-02-27T20:28:24.241539Z","end":"2026-02-27T20:28:24.381744Z","steps":["trace[764320521] 'agreement among raft nodes before linearized reading' (duration: 140.106268ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:28:24.381788Z","caller":"traceutil/trace.go:171","msg":"trace[1229185480] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:956; }","duration":"163.30992ms","start":"2026-02-27T20:28:24.21846Z","end":"2026-02-27T20:28:24.38177Z","steps":["trace[1229185480] 'agreement among raft nodes before linearized reading' (duration: 163.012783ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:24.381605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.435916ms","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-27T20:28:24.381941Z","caller":"traceutil/trace.go:171","msg":"trace[270156195] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:956; }","duration":"140.792803ms","start":"2026-02-27T20:28:24.241133Z","end":"2026-02-27T20:28:24.381926Z","steps":["trace[270156195] 'agreement among raft nodes before linearized reading' (duration: 140.338943ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:28:24.38162Z","caller":"traceutil/trace.go:171","msg":"trace[967826846] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:956; }","duration":"591.129576ms","start":"2026-02-27T20:28:23.790468Z","end":"2026-02-27T20:28:24.381598Z","steps":["trace[967826846] 'agreement among raft nodes before linearized reading' (duration: 590.976992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:24.382067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:23.790453Z","time spent":"591.608436ms","remote":"127.0.0.1:50582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T20:28:24.382149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:23.056919Z","time spent":"1.324753384s","remote":"127.0.0.1:50652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":835,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T20:28:24.895289Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794897559906107194,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T20:28:25.32406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.671603ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T20:28:25.324239Z","caller":"traceutil/trace.go:171","msg":"trace[207654722] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"938.605972ms","start":"2026-02-27T20:28:24.385611Z","end":"2026-02-27T20:28:25.324216Z","steps":["trace[207654722] 'process raft request' (duration: 672.703863ms)","trace[207654722] 'compare' (duration: 265.57388ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:28:25.324304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:24.38559Z","time spent":"938.683963ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:28:26.196633Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.109560946s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-27T20:28:26.356582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.708857ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T20:28:26.356746Z","caller":"traceutil/trace.go:171","msg":"trace[2049483389] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"1.970262644s","start":"2026-02-27T20:28:24.38646Z","end":"2026-02-27T20:28:26.356723Z","steps":["trace[2049483389] 'process raft request' (duration: 1.810339802s)","trace[2049483389] 'compare' (duration: 159.610995ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:28:26.356807Z","caller":"traceutil/trace.go:171","msg":"trace[1393472528] linearizableReadLoop","detail":"{readStateIndex:998; appliedIndex:994; }","duration":"1.962169099s","start":"2026-02-27T20:28:24.394626Z","end":"2026-02-27T20:28:26.356795Z","steps":["trace[1393472528] 'read index received' (duration: 663.78065ms)","trace[1393472528] 'applied index is now lower than readState.Index' (duration: 1.298387429s)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:28:26.356838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:24.386434Z","time spent":"1.970365466s","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:28:26.356949Z","caller":"traceutil/trace.go:171","msg":"trace[1412399735] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"1.438238252s","start":"2026-02-27T20:28:24.918701Z","end":"2026-02-27T20:28:26.356939Z","steps":["trace[1412399735] 'process raft request' (duration: 1.438054188s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:26.356999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:24.918664Z","time spent":"1.438311293s","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:28:26.357117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.962484376s","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-27T20:28:26.35715Z","caller":"traceutil/trace.go:171","msg":"trace[1490526913] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"1.969745471s","start":"2026-02-27T20:28:24.387399Z","end":"2026-02-27T20:28:26.357144Z","steps":["trace[1490526913] 'process raft request' (duration: 1.969327132s)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:28:26.357156Z","caller":"traceutil/trace.go:171","msg":"trace[1354292986] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:961; }","duration":"1.962539026s","start":"2026-02-27T20:28:24.394608Z","end":"2026-02-27T20:28:26.357147Z","steps":["trace[1354292986] 'agreement among raft nodes before linearized reading' (duration: 1.962415294s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:26.357197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:24.387388Z","time spent":"1.969782823s","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:28:26.357471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:24.3946Z","time spent":"1.962579907s","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-27T20:28:26.357124Z","caller":"traceutil/trace.go:171","msg":"trace[1421483637] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"1.970502089s","start":"2026-02-27T20:28:24.386614Z","end":"2026-02-27T20:28:26.357116Z","steps":["trace[1421483637] 'process raft request' (duration: 1.970054859s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:26.357659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:24.386608Z","time spent":"1.970962979s","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:28:26.79236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000394013s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-27T20:28:26.792516Z","caller":"traceutil/trace.go:171","msg":"trace[1965870269] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000597198s","start":"2026-02-27T20:28:24.791899Z","end":"2026-02-27T20:28:26.792497Z","steps":["trace[1965870269] 'agreement among raft nodes before linearized reading' (duration: 2.000422884s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:26.792594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:24.791883Z","time spent":"2.00069865s","remote":"127.0.0.1:50582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T20:28:26.907684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.884331ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-27T20:28:26.907961Z","caller":"traceutil/trace.go:171","msg":"trace[1790670831] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"545.958962ms","start":"2026-02-27T20:28:26.361984Z","end":"2026-02-27T20:28:26.907943Z","steps":["trace[1790670831] 'process raft request' (duration: 545.813628ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:28:26.907946Z","caller":"traceutil/trace.go:171","msg":"trace[2008252266] linearizableReadLoop","detail":"{readStateIndex:1000; appliedIndex:999; }","duration":"551.076068ms","start":"2026-02-27T20:28:26.356841Z","end":"2026-02-27T20:28:26.907917Z","steps":["trace[2008252266] 'read index received' (duration: 21.959912ms)","trace[2008252266] 'applied index is now lower than readState.Index' (duration: 529.113046ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:28:26.908073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:26.361967Z","time spent":"546.039153ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:28:26.908136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.063272124s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-27T20:28:26.90825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.31391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:28:26.908364Z","caller":"traceutil/trace.go:171","msg":"trace[614391056] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:962; }","duration":"115.456984ms","start":"2026-02-27T20:28:26.792893Z","end":"2026-02-27T20:28:26.90835Z","steps":["trace[614391056] 'agreement among raft nodes before linearized reading' (duration: 115.313821ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:28:26.908284Z","caller":"traceutil/trace.go:171","msg":"trace[1395870217] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:962; }","duration":"2.063507559s","start":"2026-02-27T20:28:24.844759Z","end":"2026-02-27T20:28:26.908266Z","steps":["trace[1395870217] 'agreement among raft nodes before linearized reading' (duration: 2.063193672s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:26.908603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:24.844725Z","time spent":"2.063857417s","remote":"127.0.0.1:51040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-27T20:28:26.908314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.25092662s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T20:28:26.908792Z","caller":"traceutil/trace.go:171","msg":"trace[1061021536] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:962; }","duration":"2.251499112s","start":"2026-02-27T20:28:24.657274Z","end":"2026-02-27T20:28:26.908773Z","steps":["trace[1061021536] 'agreement among raft nodes before linearized reading' (duration: 2.250892579s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:26.908894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:24.657253Z","time spent":"2.251616946s","remote":"127.0.0.1:51034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-02-27T20:28:27.436218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.215626ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-27T20:28:27.436333Z","caller":"traceutil/trace.go:171","msg":"trace[738014536] linearizableReadLoop","detail":"{readStateIndex:1002; appliedIndex:1001; }","duration":"519.047695ms","start":"2026-02-27T20:28:26.917269Z","end":"2026-02-27T20:28:27.436317Z","steps":["trace[738014536] 'read index received' (duration: 348.638113ms)","trace[738014536] 'applied index is now lower than readState.Index' (duration: 170.408482ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:28:27.436453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.183878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:28:27.436402Z","caller":"traceutil/trace.go:171","msg":"trace[48231929] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"522.923954ms","start":"2026-02-27T20:28:26.913414Z","end":"2026-02-27T20:28:27.436338Z","steps":["trace[48231929] 'process raft request' (duration: 352.517782ms)","trace[48231929] 'compare' (duration: 170.002231ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:28:27.4365Z","caller":"traceutil/trace.go:171","msg":"trace[996731251] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:963; }","duration":"519.2516ms","start":"2026-02-27T20:28:26.917226Z","end":"2026-02-27T20:28:27.436477Z","steps":["trace[996731251] 'agreement among raft nodes before linearized reading' (duration: 519.170948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:27.436535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:26.917208Z","time spent":"519.319291ms","remote":"127.0.0.1:50582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T20:28:27.436614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:26.913391Z","time spent":"523.111857ms","remote":"127.0.0.1:50652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T20:28:27.436745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.947685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-27T20:28:27.436821Z","caller":"traceutil/trace.go:171","msg":"trace[1306921185] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:963; }","duration":"109.042107ms","start":"2026-02-27T20:28:27.327761Z","end":"2026-02-27T20:28:27.436804Z","steps":["trace[1306921185] 'agreement among raft nodes before linearized reading' (duration: 108.805262ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:28:28.292974Z","caller":"traceutil/trace.go:171","msg":"trace[1711581916] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"852.374027ms","start":"2026-02-27T20:28:27.440573Z","end":"2026-02-27T20:28:28.292947Z","steps":["trace[1711581916] 'process raft request' (duration: 852.206163ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:28.293194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:27.440551Z","time spent":"852.50631ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:28:29.412979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.74163ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T20:28:29.414969Z","caller":"traceutil/trace.go:171","msg":"trace[1735726626] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"1.048021836s","start":"2026-02-27T20:28:28.36691Z","end":"2026-02-27T20:28:29.414932Z","steps":["trace[1735726626] 'process raft request' (duration: 274.254889ms)","trace[1735726626] 'compare' (duration: 771.622038ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:28:29.415159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:28.366898Z","time spent":"1.04817447s","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:28:29.416186Z","caller":"traceutil/trace.go:171","msg":"trace[606149135] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"1.049222934s","start":"2026-02-27T20:28:28.366938Z","end":"2026-02-27T20:28:29.416161Z","steps":["trace[606149135] 'process raft request' (duration: 1.046125573s)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:28:29.416214Z","caller":"traceutil/trace.go:171","msg":"trace[1495891375] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"1.048859435s","start":"2026-02-27T20:28:28.367321Z","end":"2026-02-27T20:28:29.416181Z","steps":["trace[1495891375] 'process raft request' (duration: 1.045788355s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:29.416265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:28.366919Z","time spent":"1.049308395s","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:28:29.416306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:28.367315Z","time spent":"1.048939067s","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:28:29.416526Z","caller":"traceutil/trace.go:171","msg":"trace[886281540] linearizableReadLoop","detail":"{readStateIndex:1006; appliedIndex:1003; }","duration":"624.803556ms","start":"2026-02-27T20:28:28.791608Z","end":"2026-02-27T20:28:29.416412Z","steps":["trace[886281540] 'read index received' (duration: 127.238614ms)","trace[886281540] 'applied index is now lower than readState.Index' (duration: 497.404989ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:28:29.416673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.074132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:28:29.416723Z","caller":"traceutil/trace.go:171","msg":"trace[1326346481] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:967; }","duration":"625.156294ms","start":"2026-02-27T20:28:28.791543Z","end":"2026-02-27T20:28:29.416699Z","steps":["trace[1326346481] 'agreement among raft nodes before linearized reading' (duration: 625.048311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:29.416757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:28.791518Z","time spent":"625.231146ms","remote":"127.0.0.1:50582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T20:28:29.417153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.633868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:28:29.417201Z","caller":"traceutil/trace.go:171","msg":"trace[2084213546] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:967; }","duration":"622.699569ms","start":"2026-02-27T20:28:28.794479Z","end":"2026-02-27T20:28:29.417178Z","steps":["trace[2084213546] 'agreement among raft nodes before linearized reading' (duration: 622.611917ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:29.417242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:28.794466Z","time spent":"622.76389ms","remote":"127.0.0.1:50924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-27T20:28:29.417351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.11323ms","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-27T20:28:29.41743Z","caller":"traceutil/trace.go:171","msg":"trace[1415760434] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:967; }","duration":"503.195772ms","start":"2026-02-27T20:28:28.914189Z","end":"2026-02-27T20:28:29.417385Z","steps":["trace[1415760434] 'agreement among raft nodes before linearized reading' (duration: 503.072289ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:29.417467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:28.91417Z","time spent":"503.287625ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-27T20:28:29.987354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.013598ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T20:28:29.987541Z","caller":"traceutil/trace.go:171","msg":"trace[1061372732] linearizableReadLoop","detail":"{readStateIndex:1008; appliedIndex:1006; }","duration":"195.989208ms","start":"2026-02-27T20:28:29.791539Z","end":"2026-02-27T20:28:29.987528Z","steps":["trace[1061372732] 'read index received' (duration: 23.47µs)","trace[1061372732] 'applied index is now lower than readState.Index' (duration: 195.965088ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:28:29.987614Z","caller":"traceutil/trace.go:171","msg":"trace[1136101488] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"565.352606ms","start":"2026-02-27T20:28:29.422254Z","end":"2026-02-27T20:28:29.987607Z","steps":["trace[1136101488] 'process raft request' (duration: 565.204792ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:29.987679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:29.422238Z","time spent":"565.398006ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:28:29.987682Z","caller":"traceutil/trace.go:171","msg":"trace[1265750221] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"565.779636ms","start":"2026-02-27T20:28:29.421825Z","end":"2026-02-27T20:28:29.987605Z","steps":["trace[1265750221] 'process raft request' (duration: 93.42746ms)","trace[1265750221] 'compare' (duration: 471.689211ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:28:29.987892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.326845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:28:29.987982Z","caller":"traceutil/trace.go:171","msg":"trace[546478846] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:969; }","duration":"196.456329ms","start":"2026-02-27T20:28:29.791504Z","end":"2026-02-27T20:28:29.98796Z","steps":["trace[546478846] 'agreement among raft nodes before linearized reading' (duration: 196.277955ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:29.988742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:29.421804Z","time spent":"566.024881ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:28:30.498076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.450347ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T20:28:30.498177Z","caller":"traceutil/trace.go:171","msg":"trace[1827818044] linearizableReadLoop","detail":"{readStateIndex:1010; appliedIndex:1009; }","duration":"201.386562ms","start":"2026-02-27T20:28:30.296775Z","end":"2026-02-27T20:28:30.498161Z","steps":["trace[1827818044] 'read index received' (duration: 31.971µs)","trace[1827818044] 'applied index is now lower than readState.Index' (duration: 201.352661ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:28:30.498228Z","caller":"traceutil/trace.go:171","msg":"trace[279707012] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"471.892596ms","start":"2026-02-27T20:28:30.026283Z","end":"2026-02-27T20:28:30.498175Z","steps":["trace[279707012] 'process raft request' (duration: 165.258754ms)","trace[279707012] 'compare' (duration: 306.217552ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:28:30.498337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.558995ms","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-27T20:28:30.498367Z","caller":"traceutil/trace.go:171","msg":"trace[2002159338] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:970; }","duration":"201.625367ms","start":"2026-02-27T20:28:30.296732Z","end":"2026-02-27T20:28:30.498357Z","steps":["trace[2002159338] 'agreement among raft nodes before linearized reading' (duration: 201.472654ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:30.498493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:30.026267Z","time spent":"472.024788ms","remote":"127.0.0.1:50608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:28:30.956527Z","caller":"traceutil/trace.go:171","msg":"trace[1857475251] linearizableReadLoop","detail":"{readStateIndex:1011; appliedIndex:1010; }","duration":"453.880563ms","start":"2026-02-27T20:28:30.502619Z","end":"2026-02-27T20:28:30.956499Z","steps":["trace[1857475251] 'read index received' (duration: 453.32847ms)","trace[1857475251] 'applied index is now lower than readState.Index' (duration: 549.763µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:28:30.956836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.407968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:28:30.956906Z","caller":"traceutil/trace.go:171","msg":"trace[1659883079] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:971; }","duration":"165.560941ms","start":"2026-02-27T20:28:30.791326Z","end":"2026-02-27T20:28:30.956887Z","steps":["trace[1659883079] 'agreement among raft nodes before linearized reading' (duration: 165.440908ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:28:30.957112Z","caller":"traceutil/trace.go:171","msg":"trace[1437317791] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"454.66021ms","start":"2026-02-27T20:28:30.502441Z","end":"2026-02-27T20:28:30.957101Z","steps":["trace[1437317791] 'process raft request' (duration: 453.73ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:30.957201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:30.502424Z","time spent":"454.720721ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:28:30.957381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.760173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-27T20:28:30.957415Z","caller":"traceutil/trace.go:171","msg":"trace[441863185] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:971; }","duration":"454.793823ms","start":"2026-02-27T20:28:30.502613Z","end":"2026-02-27T20:28:30.957407Z","steps":["trace[441863185] 'agreement among raft nodes before linearized reading' (duration: 454.676501ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:30.957451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:30.502583Z","time spent":"454.851495ms","remote":"127.0.0.1:50608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-02-27T20:28:31.540606Z","caller":"traceutil/trace.go:171","msg":"trace[2046071733] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"114.42254ms","start":"2026-02-27T20:28:31.426152Z","end":"2026-02-27T20:28:31.540575Z","steps":["trace[2046071733] 'process raft request' (duration: 114.238846ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:28:31.542699Z","caller":"traceutil/trace.go:171","msg":"trace[160943805] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"116.234331ms","start":"2026-02-27T20:28:31.426429Z","end":"2026-02-27T20:28:31.542664Z","steps":["trace[160943805] 'process raft request' (duration: 116.19226ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:28:31.542906Z","caller":"traceutil/trace.go:171","msg":"trace[1994273321] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"116.488716ms","start":"2026-02-27T20:28:31.426401Z","end":"2026-02-27T20:28:31.542889Z","steps":["trace[1994273321] 'process raft request' (duration: 116.081867ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:34.291386Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794897559906107257,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T20:28:34.374337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.406693389s","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-27T20:28:34.374432Z","caller":"traceutil/trace.go:171","msg":"trace[2035429453] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:983; }","duration":"1.406888784s","start":"2026-02-27T20:28:32.96752Z","end":"2026-02-27T20:28:34.374409Z","steps":["trace[2035429453] 'range keys from in-memory index tree' (duration: 1.406411393s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:34.374491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:32.967469Z","time spent":"1.407007486s","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-27T20:28:34.374975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.555735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-27T20:28:34.375105Z","caller":"traceutil/trace.go:171","msg":"trace[457946514] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:983; }","duration":"829.732429ms","start":"2026-02-27T20:28:33.545349Z","end":"2026-02-27T20:28:34.375081Z","steps":["trace[457946514] 'range keys from in-memory index tree' (duration: 829.34384ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:34.375172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.124668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-27T20:28:34.375237Z","caller":"traceutil/trace.go:171","msg":"trace[67447490] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:983; }","duration":"827.211822ms","start":"2026-02-27T20:28:33.54801Z","end":"2026-02-27T20:28:34.375222Z","steps":["trace[67447490] 'range keys from in-memory index tree' (duration: 826.992846ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:34.375231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.492387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-27T20:28:34.375286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:33.547999Z","time spent":"827.275273ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-27T20:28:34.375292Z","caller":"traceutil/trace.go:171","msg":"trace[629421170] linearizableReadLoop","detail":"{readStateIndex:1024; appliedIndex:1023; }","duration":"583.931681ms","start":"2026-02-27T20:28:33.791341Z","end":"2026-02-27T20:28:34.375273Z","steps":["trace[629421170] 'read index received' (duration: 539.940413ms)","trace[629421170] 'applied index is now lower than readState.Index' (duration: 43.989888ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:28:34.375307Z","caller":"traceutil/trace.go:171","msg":"trace[1862160724] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:983; }","duration":"827.61061ms","start":"2026-02-27T20:28:33.547678Z","end":"2026-02-27T20:28:34.375288Z","steps":["trace[1862160724] 'range keys from in-memory index tree' (duration: 827.282372ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:34.375249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:33.545327Z","time spent":"829.880743ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-27T20:28:34.375354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:33.547668Z","time spent":"827.678032ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-27T20:28:34.375612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.264278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:28:34.37567Z","caller":"traceutil/trace.go:171","msg":"trace[70555778] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:984; }","duration":"584.352451ms","start":"2026-02-27T20:28:33.791303Z","end":"2026-02-27T20:28:34.375655Z","steps":["trace[70555778] 'agreement among raft nodes before linearized reading' (duration: 584.266189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:34.375685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.505852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-27T20:28:34.375719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:33.791291Z","time spent":"584.416912ms","remote":"127.0.0.1:50582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-27T20:28:34.375734Z","caller":"traceutil/trace.go:171","msg":"trace[332038081] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:984; }","duration":"552.618324ms","start":"2026-02-27T20:28:33.8231Z","end":"2026-02-27T20:28:34.375719Z","steps":["trace[332038081] 'agreement among raft nodes before linearized reading' (duration: 552.516212ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:34.375791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:33.823072Z","time spent":"552.691086ms","remote":"127.0.0.1:50670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":6,"response size":31,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"info","ts":"2026-02-27T20:28:34.375964Z","caller":"traceutil/trace.go:171","msg":"trace[1205442465] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"744.64253ms","start":"2026-02-27T20:28:33.6313Z","end":"2026-02-27T20:28:34.375942Z","steps":["trace[1205442465] 'process raft request' (duration: 700.002048ms)","trace[1205442465] 'compare' (duration: 43.019315ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:28:34.376028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.585427ms","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-27T20:28:34.376075Z","caller":"traceutil/trace.go:171","msg":"trace[1282466143] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:984; }","duration":"374.679259ms","start":"2026-02-27T20:28:34.001384Z","end":"2026-02-27T20:28:34.376064Z","steps":["trace[1282466143] 'agreement among raft nodes before linearized reading' (duration: 374.562946ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:34.376112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:34.001365Z","time spent":"374.740541ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-27T20:28:34.376197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:33.63128Z","time spent":"744.740183ms","remote":"127.0.0.1:50764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5957,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:28:34.551096Z","caller":"traceutil/trace.go:171","msg":"trace[976108462] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"163.176797ms","start":"2026-02-27T20:28:34.387895Z","end":"2026-02-27T20:28:34.551072Z","steps":["trace[976108462] 'process raft request' (duration: 163.136486ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:28:34.551316Z","caller":"traceutil/trace.go:171","msg":"trace[952812641] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"163.453122ms","start":"2026-02-27T20:28:34.387813Z","end":"2026-02-27T20:28:34.551266Z","steps":["trace[952812641] 'process raft request' (duration: 163.133765ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:28:34.551547Z","caller":"traceutil/trace.go:171","msg":"trace[1965701234] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"163.610256ms","start":"2026-02-27T20:28:34.387861Z","end":"2026-02-27T20:28:34.551471Z","steps":["trace[1965701234] 'process raft request' (duration: 163.137675ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:28:34.551586Z","caller":"traceutil/trace.go:171","msg":"trace[1172176607] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"169.631674ms","start":"2026-02-27T20:28:34.381935Z","end":"2026-02-27T20:28:34.551566Z","steps":["trace[1172176607] 'process raft request' (duration: 145.841189ms)","trace[1172176607] 'compare' (duration: 22.938396ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:28:34.551741Z","caller":"traceutil/trace.go:171","msg":"trace[1243449098] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"168.965959ms","start":"2026-02-27T20:28:34.382751Z","end":"2026-02-27T20:28:34.551717Z","steps":["trace[1243449098] 'process raft request' (duration: 168.119769ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:28:35.992454Z","caller":"traceutil/trace.go:171","msg":"trace[1893998130] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"145.991852ms","start":"2026-02-27T20:28:35.846435Z","end":"2026-02-27T20:28:35.992427Z","steps":["trace[1893998130] 'process raft request' (duration: 145.851809ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:28:35.995998Z","caller":"traceutil/trace.go:171","msg":"trace[236080313] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"146.478624ms","start":"2026-02-27T20:28:35.84951Z","end":"2026-02-27T20:28:35.995989Z","steps":["trace[236080313] 'process raft request' (duration: 146.420152ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:28:51.048805Z","caller":"traceutil/trace.go:171","msg":"trace[675032206] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"359.067035ms","start":"2026-02-27T20:28:50.689708Z","end":"2026-02-27T20:28:51.048775Z","steps":["trace[675032206] 'process raft request' (duration: 358.775748ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:51.048954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:50.689683Z","time spent":"359.196708ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:28:51.049239Z","caller":"traceutil/trace.go:171","msg":"trace[1247923058] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"359.454155ms","start":"2026-02-27T20:28:50.689773Z","end":"2026-02-27T20:28:51.049227Z","steps":["trace[1247923058] 'process raft request' (duration: 358.920272ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:51.049371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:50.689758Z","time spent":"359.573247ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:28:51.049483Z","caller":"traceutil/trace.go:171","msg":"trace[761629047] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"358.978444ms","start":"2026-02-27T20:28:50.690323Z","end":"2026-02-27T20:28:51.049301Z","steps":["trace[761629047] 'process raft request' (duration: 358.629365ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:51.049795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:28:50.690308Z","time spent":"359.368813ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:29:43.449574Z","caller":"traceutil/trace.go:171","msg":"trace[94557308] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"102.665948ms","start":"2026-02-27T20:29:43.346884Z","end":"2026-02-27T20:29:43.44955Z","steps":["trace[94557308] 'process raft request' (duration: 102.548115ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:30:00.331548Z","caller":"traceutil/trace.go:171","msg":"trace[13691804] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"113.447774ms","start":"2026-02-27T20:30:00.218073Z","end":"2026-02-27T20:30:00.331521Z","steps":["trace[13691804] 'process raft request' (duration: 113.033125ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:30:33.95205Z","caller":"traceutil/trace.go:171","msg":"trace[509728820] linearizableReadLoop","detail":"{readStateIndex:1560; appliedIndex:1558; }","duration":"233.576533ms","start":"2026-02-27T20:30:33.718451Z","end":"2026-02-27T20:30:33.952028Z","steps":["trace[509728820] 'read index received' (duration: 5.054807ms)","trace[509728820] 'applied index is now lower than readState.Index' (duration: 228.520486ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:30:33.952178Z","caller":"traceutil/trace.go:171","msg":"trace[753000159] transaction","detail":"{read_only:false; response_revision:1496; number_of_response:1; }","duration":"264.851113ms","start":"2026-02-27T20:30:33.687314Z","end":"2026-02-27T20:30:33.952166Z","steps":["trace[753000159] 'process raft request' (duration: 199.532797ms)","trace[753000159] 'compare' (duration: 64.926546ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:30:33.952301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.90364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T20:30:33.95236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.89691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-27T20:30:33.952399Z","caller":"traceutil/trace.go:171","msg":"trace[1306614193] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1496; }","duration":"221.036474ms","start":"2026-02-27T20:30:33.731337Z","end":"2026-02-27T20:30:33.952374Z","steps":["trace[1306614193] 'agreement among raft nodes before linearized reading' (duration: 220.87013ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:30:33.952444Z","caller":"traceutil/trace.go:171","msg":"trace[371192100] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1496; }","duration":"234.014403ms","start":"2026-02-27T20:30:33.718408Z","end":"2026-02-27T20:30:33.952423Z","steps":["trace[371192100] 'agreement among raft nodes before linearized reading' (duration: 233.820689ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:30:33.952604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.395412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:30:33.95264Z","caller":"traceutil/trace.go:171","msg":"trace[1626344294] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1496; }","duration":"162.455924ms","start":"2026-02-27T20:30:33.790175Z","end":"2026-02-27T20:30:33.952631Z","steps":["trace[1626344294] 'agreement among raft nodes before linearized reading' (duration: 162.382852ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:31:31.606941Z","caller":"traceutil/trace.go:171","msg":"trace[911291368] transaction","detail":"{read_only:false; response_revision:1752; number_of_response:1; }","duration":"122.572746ms","start":"2026-02-27T20:31:31.484322Z","end":"2026-02-27T20:31:31.606895Z","steps":["trace[911291368] 'process raft request' (duration: 41.89592ms)","trace[911291368] 'compare' (duration: 80.43679ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:31:31.792224Z","caller":"traceutil/trace.go:171","msg":"trace[211678685] transaction","detail":"{read_only:false; response_revision:1753; number_of_response:1; }","duration":"181.420789ms","start":"2026-02-27T20:31:31.610774Z","end":"2026-02-27T20:31:31.792195Z","steps":["trace[211678685] 'process raft request' (duration: 131.228757ms)","trace[211678685] 'compare' (duration: 50.054839ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:31:47.085201Z","caller":"traceutil/trace.go:171","msg":"trace[406539264] transaction","detail":"{read_only:false; response_revision:1825; number_of_response:1; }","duration":"145.465056ms","start":"2026-02-27T20:31:46.939703Z","end":"2026-02-27T20:31:47.085168Z","steps":["trace[406539264] 'process raft request' (duration: 145.140699ms)"],"step_count":1}