{"level":"warn","ts":"2026-02-03T17:25:39.124808Z","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-03T17:25:39.125145Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.154: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.19.213.154:2380","--initial-cluster=instance=https://199.19.213.154:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.154:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.154: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-03T17:25:39.125255Z","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-03T17:25:39.12527Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.154:2380"]} {"level":"info","ts":"2026-02-03T17:25:39.125309Z","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-03T17:25:39.126255Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.154:2379"]} {"level":"info","ts":"2026-02-03T17:25:39.126372Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.154:2380"],"listen-peer-urls":["https://199.19.213.154:2380"],"advertise-client-urls":["https://199.19.213.154:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.154:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.154: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-03T17:25:39.136978Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"10.21211ms"} {"level":"info","ts":"2026-02-03T17:25:39.15659Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f4aebb100319a62d","cluster-id":"23a505f69026554e"} {"level":"info","ts":"2026-02-03T17:25:39.156671Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d switched to configuration voters=()"} {"level":"info","ts":"2026-02-03T17:25:39.15669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d became follower at term 0"} {"level":"info","ts":"2026-02-03T17:25:39.156711Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f4aebb100319a62d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-03T17:25:39.156717Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d became follower at term 1"} {"level":"info","ts":"2026-02-03T17:25:39.156746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d switched to configuration voters=(17631235268647953965)"} {"level":"warn","ts":"2026-02-03T17:25:39.175196Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-03T17:25:39.179798Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-03T17:25:39.181911Z","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-03T17:25:39.18844Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f4aebb100319a62d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-03T17:25:39.189116Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f4aebb100319a62d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-03T17:25:39.189249Z","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-03T17:25:39.189288Z","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-03T17:25:39.189295Z","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-03T17:25:39.189771Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d switched to configuration voters=(17631235268647953965)"} {"level":"info","ts":"2026-02-03T17:25:39.189964Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"23a505f69026554e","local-member-id":"f4aebb100319a62d","added-peer-id":"f4aebb100319a62d","added-peer-peer-urls":["https://199.19.213.154:2380"]} {"level":"info","ts":"2026-02-03T17:25:39.200057Z","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-03T17:25:39.200221Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.154:2380"} {"level":"info","ts":"2026-02-03T17:25:39.200259Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.154:2380"} {"level":"info","ts":"2026-02-03T17:25:39.206073Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f4aebb100319a62d","initial-advertise-peer-urls":["https://199.19.213.154:2380"],"listen-peer-urls":["https://199.19.213.154:2380"],"advertise-client-urls":["https://199.19.213.154:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.154:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-03T17:25:39.206103Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-03T17:25:40.058297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d is starting a new election at term 1"} {"level":"info","ts":"2026-02-03T17:25:40.058373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d became pre-candidate at term 1"} {"level":"info","ts":"2026-02-03T17:25:40.058393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d received MsgPreVoteResp from f4aebb100319a62d at term 1"} {"level":"info","ts":"2026-02-03T17:25:40.058489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d became candidate at term 2"} {"level":"info","ts":"2026-02-03T17:25:40.0585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d received MsgVoteResp from f4aebb100319a62d at term 2"} {"level":"info","ts":"2026-02-03T17:25:40.058509Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d became leader at term 2"} {"level":"info","ts":"2026-02-03T17:25:40.058516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f4aebb100319a62d elected leader f4aebb100319a62d at term 2"} {"level":"info","ts":"2026-02-03T17:25:40.059705Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T17:25:40.062453Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f4aebb100319a62d","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.154:2379]}","request-path":"/0/members/f4aebb100319a62d/attributes","cluster-id":"23a505f69026554e","publish-timeout":"7s"} {"level":"info","ts":"2026-02-03T17:25:40.062475Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-03T17:25:40.062706Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-03T17:25:40.063473Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"23a505f69026554e","local-member-id":"f4aebb100319a62d","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T17:25:40.063512Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T17:25:40.063538Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T17:25:40.068445Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.154:2379"} {"level":"info","ts":"2026-02-03T17:25:40.068904Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-03T17:25:40.068914Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-03T17:25:40.080048Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-03T17:25:54.500563Z","caller":"traceutil/trace.go:171","msg":"trace[2014675929] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"449.342867ms","start":"2026-02-03T17:25:54.0512Z","end":"2026-02-03T17:25:54.500543Z","steps":["trace[2014675929] 'process raft request' (duration: 449.241335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:25:54.501122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:25:54.05119Z","time spent":"449.454768ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:25:54.502783Z","caller":"traceutil/trace.go:171","msg":"trace[1747505715] linearizableReadLoop","detail":"{readStateIndex:380; appliedIndex:379; }","duration":"184.297937ms","start":"2026-02-03T17:25:54.318471Z","end":"2026-02-03T17:25:54.502768Z","steps":["trace[1747505715] 'read index received' (duration: 184.037072ms)","trace[1747505715] 'applied index is now lower than readState.Index' (duration: 260.055µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:25:54.502942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.458249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4260"} {"level":"info","ts":"2026-02-03T17:25:54.503129Z","caller":"traceutil/trace.go:171","msg":"trace[929531755] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:368; }","duration":"184.634282ms","start":"2026-02-03T17:25:54.31845Z","end":"2026-02-03T17:25:54.503084Z","steps":["trace[929531755] 'agreement among raft nodes before linearized reading' (duration: 184.429699ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:25:54.503595Z","caller":"traceutil/trace.go:171","msg":"trace[238781134] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"451.073438ms","start":"2026-02-03T17:25:54.052507Z","end":"2026-02-03T17:25:54.50358Z","steps":["trace[238781134] 'process raft request' (duration: 450.206653ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:25:54.503695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:25:54.052498Z","time spent":"451.134399ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:26:11.004997Z","caller":"traceutil/trace.go:171","msg":"trace[3032276] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"108.278419ms","start":"2026-02-03T17:26:10.896506Z","end":"2026-02-03T17:26:11.004785Z","steps":["trace[3032276] 'process raft request' (duration: 108.152157ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:26:12.847303Z","caller":"traceutil/trace.go:171","msg":"trace[607614688] linearizableReadLoop","detail":"{readStateIndex:493; appliedIndex:492; }","duration":"138.95367ms","start":"2026-02-03T17:26:12.708338Z","end":"2026-02-03T17:26:12.847291Z","steps":["trace[607614688] 'read index received' (duration: 121.146188ms)","trace[607614688] 'applied index is now lower than readState.Index' (duration: 17.806832ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:26:12.847511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.167635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-03T17:26:12.847531Z","caller":"traceutil/trace.go:171","msg":"trace[815028121] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:478; }","duration":"139.220106ms","start":"2026-02-03T17:26:12.708305Z","end":"2026-02-03T17:26:12.847525Z","steps":["trace[815028121] 'agreement among raft nodes before linearized reading' (duration: 139.139274ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:26:12.847589Z","caller":"traceutil/trace.go:171","msg":"trace[1798939955] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"223.944735ms","start":"2026-02-03T17:26:12.623639Z","end":"2026-02-03T17:26:12.847584Z","steps":["trace[1798939955] 'process raft request' (duration: 205.778616ms)","trace[1798939955] 'compare' (duration: 17.65796ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:26:12.861076Z","caller":"traceutil/trace.go:171","msg":"trace[705994251] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"133.753094ms","start":"2026-02-03T17:26:12.727317Z","end":"2026-02-03T17:26:12.86107Z","steps":["trace[705994251] 'process raft request' (duration: 133.715623ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:26:17.849715Z","caller":"traceutil/trace.go:171","msg":"trace[1230716693] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"321.126997ms","start":"2026-02-03T17:26:17.528571Z","end":"2026-02-03T17:26:17.849698Z","steps":["trace[1230716693] 'process raft request' (duration: 320.706369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:17.849809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:17.528559Z","time spent":"321.203018ms","remote":"127.0.0.1:34562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:26:39.320146Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11974398664993331653,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-03T17:26:40.32298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.919683ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-03T17:26:40.323058Z","caller":"traceutil/trace.go:171","msg":"trace[1436377400] linearizableReadLoop","detail":"{readStateIndex:708; appliedIndex:707; }","duration":"1.503574588s","start":"2026-02-03T17:26:38.819468Z","end":"2026-02-03T17:26:40.323043Z","steps":["trace[1436377400] 'read index received' (duration: 844.449702ms)","trace[1436377400] 'applied index is now lower than readState.Index' (duration: 659.123936ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:26:40.32363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.504152109s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:26:40.323653Z","caller":"traceutil/trace.go:171","msg":"trace[973101647] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:685; }","duration":"1.504210311s","start":"2026-02-03T17:26:38.819435Z","end":"2026-02-03T17:26:40.323646Z","steps":["trace[973101647] 'agreement among raft nodes before linearized reading' (duration: 1.50415951s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:40.323675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:38.819422Z","time spent":"1.504248051s","remote":"127.0.0.1:34366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-03T17:26:40.323859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.456999051s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-03T17:26:40.323877Z","caller":"traceutil/trace.go:171","msg":"trace[1913835430] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:685; }","duration":"1.457035672s","start":"2026-02-03T17:26:38.866836Z","end":"2026-02-03T17:26:40.323872Z","steps":["trace[1913835430] 'agreement among raft nodes before linearized reading' (duration: 1.45695307s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:40.323894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:38.866827Z","time spent":"1.457062732s","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-03T17:26:40.324141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.644379ms","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-03T17:26:40.324161Z","caller":"traceutil/trace.go:171","msg":"trace[1409629725] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:685; }","duration":"142.697641ms","start":"2026-02-03T17:26:40.181457Z","end":"2026-02-03T17:26:40.324154Z","steps":["trace[1409629725] 'agreement among raft nodes before linearized reading' (duration: 142.652ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:40.324272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.247911473s","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-03T17:26:40.324295Z","caller":"traceutil/trace.go:171","msg":"trace[2145970403] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:685; }","duration":"1.247949603s","start":"2026-02-03T17:26:39.076341Z","end":"2026-02-03T17:26:40.32429Z","steps":["trace[2145970403] 'agreement among raft nodes before linearized reading' (duration: 1.247895172s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:40.324312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:39.076334Z","time spent":"1.247974243s","remote":"127.0.0.1:34640","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-03T17:26:40.324415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.325294604s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-03T17:26:40.324431Z","caller":"traceutil/trace.go:171","msg":"trace[598710263] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:685; }","duration":"1.325333455s","start":"2026-02-03T17:26:38.999092Z","end":"2026-02-03T17:26:40.324426Z","steps":["trace[598710263] 'agreement among raft nodes before linearized reading' (duration: 1.325290765s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:40.324447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:38.99908Z","time spent":"1.325363026s","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-03T17:26:52.732946Z","caller":"traceutil/trace.go:171","msg":"trace[1683370727] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"332.032661ms","start":"2026-02-03T17:26:52.400902Z","end":"2026-02-03T17:26:52.732935Z","steps":["trace[1683370727] 'process raft request' (duration: 331.633483ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:26:52.732708Z","caller":"traceutil/trace.go:171","msg":"trace[487363999] linearizableReadLoop","detail":"{readStateIndex:751; appliedIndex:750; }","duration":"331.007271ms","start":"2026-02-03T17:26:52.40168Z","end":"2026-02-03T17:26:52.732687Z","steps":["trace[487363999] 'read index received' (duration: 330.781645ms)","trace[487363999] 'applied index is now lower than readState.Index' (duration: 224.596µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:26:52.733182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:52.400856Z","time spent":"332.157564ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:26:52.733328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.646824ms","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-03T17:26:52.733459Z","caller":"traceutil/trace.go:171","msg":"trace[341120685] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:725; }","duration":"331.734796ms","start":"2026-02-03T17:26:52.401612Z","end":"2026-02-03T17:26:52.733346Z","steps":["trace[341120685] 'agreement among raft nodes before linearized reading' (duration: 331.499451ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:52.73349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:52.401605Z","time spent":"331.878058ms","remote":"127.0.0.1:34640","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-03T17:26:52.734723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.705362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-03T17:26:52.734799Z","caller":"traceutil/trace.go:171","msg":"trace[1361302656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:725; }","duration":"306.792113ms","start":"2026-02-03T17:26:52.427995Z","end":"2026-02-03T17:26:52.734788Z","steps":["trace[1361302656] 'agreement among raft nodes before linearized reading' (duration: 306.62925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:52.734874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:52.427989Z","time spent":"306.874025ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-03T17:26:52.735072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.59649ms","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-03T17:26:52.73514Z","caller":"traceutil/trace.go:171","msg":"trace[970870527] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:725; }","duration":"307.711422ms","start":"2026-02-03T17:26:52.427419Z","end":"2026-02-03T17:26:52.73513Z","steps":["trace[970870527] 'agreement among raft nodes before linearized reading' (duration: 307.567759ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:52.735205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:52.427413Z","time spent":"307.782613ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-03T17:26:52.735381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.461869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-03T17:26:52.735658Z","caller":"traceutil/trace.go:171","msg":"trace[178500758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:725; }","duration":"310.509189ms","start":"2026-02-03T17:26:52.424898Z","end":"2026-02-03T17:26:52.735407Z","steps":["trace[178500758] 'agreement among raft nodes before linearized reading' (duration: 309.003688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:52.735708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:52.424891Z","time spent":"310.805486ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-03T17:26:57.010254Z","caller":"traceutil/trace.go:171","msg":"trace[1688108408] linearizableReadLoop","detail":"{readStateIndex:764; appliedIndex:763; }","duration":"260.470553ms","start":"2026-02-03T17:26:56.749764Z","end":"2026-02-03T17:26:57.010234Z","steps":["trace[1688108408] 'read index received' (duration: 260.330551ms)","trace[1688108408] 'applied index is now lower than readState.Index' (duration: 139.372µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:26:57.012612Z","caller":"traceutil/trace.go:171","msg":"trace[756006054] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"347.717143ms","start":"2026-02-03T17:26:56.664884Z","end":"2026-02-03T17:26:57.012601Z","steps":["trace[756006054] 'process raft request' (duration: 345.257023ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:57.012708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:56.664866Z","time spent":"347.777614ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:26:57.011248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.337771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-03T17:26:57.012823Z","caller":"traceutil/trace.go:171","msg":"trace[471226099] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:737; }","duration":"263.093857ms","start":"2026-02-03T17:26:56.749723Z","end":"2026-02-03T17:26:57.012816Z","steps":["trace[471226099] 'agreement among raft nodes before linearized reading' (duration: 261.189348ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:57.012946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.271846ms","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-03T17:26:57.012968Z","caller":"traceutil/trace.go:171","msg":"trace[1292552217] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:737; }","duration":"253.333567ms","start":"2026-02-03T17:26:56.759627Z","end":"2026-02-03T17:26:57.012961Z","steps":["trace[1292552217] 'agreement among raft nodes before linearized reading' (duration: 253.245245ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:57.013047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.740884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:26:57.013075Z","caller":"traceutil/trace.go:171","msg":"trace[1229437100] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:737; }","duration":"190.806785ms","start":"2026-02-03T17:26:56.822261Z","end":"2026-02-03T17:26:57.013068Z","steps":["trace[1229437100] 'agreement among raft nodes before linearized reading' (duration: 190.762284ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:57.015241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.62416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-03T17:26:57.015508Z","caller":"traceutil/trace.go:171","msg":"trace[1941052540] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:737; }","duration":"249.904996ms","start":"2026-02-03T17:26:56.765587Z","end":"2026-02-03T17:26:57.015492Z","steps":["trace[1941052540] 'agreement among raft nodes before linearized reading' (duration: 249.60334ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:57.015764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.540821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-03T17:26:57.015786Z","caller":"traceutil/trace.go:171","msg":"trace[25612474] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:737; }","duration":"251.583311ms","start":"2026-02-03T17:26:56.764197Z","end":"2026-02-03T17:26:57.01578Z","steps":["trace[25612474] 'agreement among raft nodes before linearized reading' (duration: 251.52808ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:57.016127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.97117ms","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-03T17:26:57.01615Z","caller":"traceutil/trace.go:171","msg":"trace[1571272787] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:737; }","duration":"255.048982ms","start":"2026-02-03T17:26:56.761093Z","end":"2026-02-03T17:26:57.016142Z","steps":["trace[1571272787] 'agreement among raft nodes before linearized reading' (duration: 254.997721ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:26:57.131559Z","caller":"traceutil/trace.go:171","msg":"trace[1057321062] linearizableReadLoop","detail":"{readStateIndex:766; appliedIndex:765; }","duration":"106.351782ms","start":"2026-02-03T17:26:57.025195Z","end":"2026-02-03T17:26:57.131546Z","steps":["trace[1057321062] 'read index received' (duration: 96.95528ms)","trace[1057321062] 'applied index is now lower than readState.Index' (duration: 9.395902ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:26:57.131676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.466825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:26:57.131709Z","caller":"traceutil/trace.go:171","msg":"trace[1766631125] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:742; }","duration":"106.585486ms","start":"2026-02-03T17:26:57.025116Z","end":"2026-02-03T17:26:57.131701Z","steps":["trace[1766631125] 'agreement among raft nodes before linearized reading' (duration: 106.495375ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:26:57.131916Z","caller":"traceutil/trace.go:171","msg":"trace[622008617] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"104.934653ms","start":"2026-02-03T17:26:57.02697Z","end":"2026-02-03T17:26:57.131905Z","steps":["trace[622008617] 'process raft request' (duration: 104.520264ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:26:57.132173Z","caller":"traceutil/trace.go:171","msg":"trace[1987853931] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"107.895104ms","start":"2026-02-03T17:26:57.02427Z","end":"2026-02-03T17:26:57.132165Z","steps":["trace[1987853931] 'process raft request' (duration: 107.174618ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:26:57.132242Z","caller":"traceutil/trace.go:171","msg":"trace[1589552974] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"103.596746ms","start":"2026-02-03T17:26:57.02864Z","end":"2026-02-03T17:26:57.132237Z","steps":["trace[1589552974] 'process raft request' (duration: 102.888261ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:26:57.132291Z","caller":"traceutil/trace.go:171","msg":"trace[1615839852] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"105.156497ms","start":"2026-02-03T17:26:57.027129Z","end":"2026-02-03T17:26:57.132286Z","steps":["trace[1615839852] 'process raft request' (duration: 104.380871ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:05.420328Z","caller":"traceutil/trace.go:171","msg":"trace[2136345988] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"188.465606ms","start":"2026-02-03T17:27:05.231838Z","end":"2026-02-03T17:27:05.420303Z","steps":["trace[2136345988] 'process raft request' (duration: 188.398274ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:05.420508Z","caller":"traceutil/trace.go:171","msg":"trace[869313524] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"189.010927ms","start":"2026-02-03T17:27:05.231486Z","end":"2026-02-03T17:27:05.420497Z","steps":["trace[869313524] 'process raft request' (duration: 188.61335ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:05.420628Z","caller":"traceutil/trace.go:171","msg":"trace[2145506023] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"187.239022ms","start":"2026-02-03T17:27:05.233379Z","end":"2026-02-03T17:27:05.420618Z","steps":["trace[2145506023] 'process raft request' (duration: 186.893454ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:07.565525Z","caller":"traceutil/trace.go:171","msg":"trace[667398329] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"121.857589ms","start":"2026-02-03T17:27:07.443643Z","end":"2026-02-03T17:27:07.565501Z","steps":["trace[667398329] 'process raft request' (duration: 121.608584ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:07.613902Z","caller":"traceutil/trace.go:171","msg":"trace[873318679] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"168.412664ms","start":"2026-02-03T17:27:07.445474Z","end":"2026-02-03T17:27:07.613886Z","steps":["trace[873318679] 'process raft request' (duration: 168.288792ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:07.614035Z","caller":"traceutil/trace.go:171","msg":"trace[707843392] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"167.317312ms","start":"2026-02-03T17:27:07.446712Z","end":"2026-02-03T17:27:07.614029Z","steps":["trace[707843392] 'process raft request' (duration: 167.145629ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:13.675443Z","caller":"traceutil/trace.go:171","msg":"trace[2133609844] linearizableReadLoop","detail":"{readStateIndex:866; appliedIndex:865; }","duration":"285.463936ms","start":"2026-02-03T17:27:13.38996Z","end":"2026-02-03T17:27:13.675424Z","steps":["trace[2133609844] 'read index received' (duration: 283.923035ms)","trace[2133609844] 'applied index is now lower than readState.Index' (duration: 1.539581ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:27:13.675805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.825524ms","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-03T17:27:13.675841Z","caller":"traceutil/trace.go:171","msg":"trace[1323962087] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:833; }","duration":"285.882575ms","start":"2026-02-03T17:27:13.389947Z","end":"2026-02-03T17:27:13.67583Z","steps":["trace[1323962087] 'agreement among raft nodes before linearized reading' (duration: 285.728411ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:15.628585Z","caller":"traceutil/trace.go:171","msg":"trace[1709664875] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"289.925088ms","start":"2026-02-03T17:27:15.338646Z","end":"2026-02-03T17:27:15.628571Z","steps":["trace[1709664875] 'process raft request' (duration: 289.852366ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:16.380621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.736409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-03T17:27:16.380674Z","caller":"traceutil/trace.go:171","msg":"trace[1948719860] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:840; }","duration":"688.817241ms","start":"2026-02-03T17:27:15.691845Z","end":"2026-02-03T17:27:16.380662Z","steps":["trace[1948719860] 'range keys from in-memory index tree' (duration: 688.635517ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:16.380695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:15.691832Z","time spent":"688.858822ms","remote":"127.0.0.1:34640","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-03T17:27:16.380805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.001456ms","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-03T17:27:16.380817Z","caller":"traceutil/trace.go:171","msg":"trace[1915219023] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:840; }","duration":"673.038426ms","start":"2026-02-03T17:27:15.707775Z","end":"2026-02-03T17:27:16.380813Z","steps":["trace[1915219023] 'range keys from in-memory index tree' (duration: 672.945084ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:16.380829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:15.70776Z","time spent":"673.066087ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-03T17:27:16.380901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.851471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-03T17:27:16.380914Z","caller":"traceutil/trace.go:171","msg":"trace[1646654478] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:840; }","duration":"668.874612ms","start":"2026-02-03T17:27:15.712035Z","end":"2026-02-03T17:27:16.38091Z","steps":["trace[1646654478] 'range keys from in-memory index tree' (duration: 668.781929ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:16.380925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:15.71203Z","time spent":"668.891552ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-03T17:27:16.38099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.270955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-03T17:27:16.381002Z","caller":"traceutil/trace.go:171","msg":"trace[1141719716] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:840; }","duration":"662.291937ms","start":"2026-02-03T17:27:15.718706Z","end":"2026-02-03T17:27:16.380998Z","steps":["trace[1141719716] 'range keys from in-memory index tree' (duration: 662.231805ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:16.381017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:15.718701Z","time spent":"662.310997ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-03T17:27:16.381105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.00624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:27:16.381117Z","caller":"traceutil/trace.go:171","msg":"trace[1760062975] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:840; }","duration":"563.03931ms","start":"2026-02-03T17:27:15.818074Z","end":"2026-02-03T17:27:16.381114Z","steps":["trace[1760062975] 'range keys from in-memory index tree' (duration: 562.967749ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:16.381128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:15.818062Z","time spent":"563.063771ms","remote":"127.0.0.1:34356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-03T17:27:18.672118Z","caller":"traceutil/trace.go:171","msg":"trace[576252237] linearizableReadLoop","detail":"{readStateIndex:880; appliedIndex:879; }","duration":"214.335747ms","start":"2026-02-03T17:27:18.457769Z","end":"2026-02-03T17:27:18.672105Z","steps":["trace[576252237] 'read index received' (duration: 214.228894ms)","trace[576252237] 'applied index is now lower than readState.Index' (duration: 106.313µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:27:18.672314Z","caller":"traceutil/trace.go:171","msg":"trace[1090276689] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"261.005214ms","start":"2026-02-03T17:27:18.411303Z","end":"2026-02-03T17:27:18.672308Z","steps":["trace[1090276689] 'process raft request' (duration: 260.732318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:18.672457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.677523ms","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-03T17:27:18.672474Z","caller":"traceutil/trace.go:171","msg":"trace[849452096] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:847; }","duration":"214.704214ms","start":"2026-02-03T17:27:18.457765Z","end":"2026-02-03T17:27:18.672469Z","steps":["trace[849452096] 'agreement among raft nodes before linearized reading' (duration: 214.633292ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:18.672562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.767665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-03T17:27:18.672574Z","caller":"traceutil/trace.go:171","msg":"trace[889748153] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:847; }","duration":"213.789845ms","start":"2026-02-03T17:27:18.45878Z","end":"2026-02-03T17:27:18.67257Z","steps":["trace[889748153] 'agreement among raft nodes before linearized reading' (duration: 213.753584ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:18.672737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.375973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-03T17:27:18.672752Z","caller":"traceutil/trace.go:171","msg":"trace[431282306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:847; }","duration":"204.410843ms","start":"2026-02-03T17:27:18.468337Z","end":"2026-02-03T17:27:18.672748Z","steps":["trace[431282306] 'agreement among raft nodes before linearized reading' (duration: 204.334832ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:18.818258Z","caller":"traceutil/trace.go:171","msg":"trace[2101295533] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"141.152815ms","start":"2026-02-03T17:27:18.677089Z","end":"2026-02-03T17:27:18.818242Z","steps":["trace[2101295533] 'process raft request' (duration: 141.128055ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:18.818566Z","caller":"traceutil/trace.go:171","msg":"trace[414001268] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"142.317681ms","start":"2026-02-03T17:27:18.676243Z","end":"2026-02-03T17:27:18.818561Z","steps":["trace[414001268] 'process raft request' (duration: 141.886521ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:27.117163Z","caller":"traceutil/trace.go:171","msg":"trace[2126360464] linearizableReadLoop","detail":"{readStateIndex:904; appliedIndex:903; }","duration":"329.189334ms","start":"2026-02-03T17:27:26.787959Z","end":"2026-02-03T17:27:27.117149Z","steps":["trace[2126360464] 'read index received' (duration: 329.06831ms)","trace[2126360464] 'applied index is now lower than readState.Index' (duration: 120.544µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:27:27.117403Z","caller":"traceutil/trace.go:171","msg":"trace[1823459505] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"372.193136ms","start":"2026-02-03T17:27:26.745204Z","end":"2026-02-03T17:27:27.117397Z","steps":["trace[1823459505] 'process raft request' (duration: 371.860498ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:27.117496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:26.745194Z","time spent":"372.224946ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:27:27.117654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.697724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-03T17:27:27.11767Z","caller":"traceutil/trace.go:171","msg":"trace[1165804140] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:869; }","duration":"329.734915ms","start":"2026-02-03T17:27:26.787931Z","end":"2026-02-03T17:27:27.117665Z","steps":["trace[1165804140] 'agreement among raft nodes before linearized reading' (duration: 329.660903ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:27.117684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:26.78792Z","time spent":"329.760425ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-03T17:27:27.117771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.708978ms","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-03T17:27:27.117783Z","caller":"traceutil/trace.go:171","msg":"trace[185860962] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:869; }","duration":"279.741149ms","start":"2026-02-03T17:27:26.838038Z","end":"2026-02-03T17:27:27.117779Z","steps":["trace[185860962] 'agreement among raft nodes before linearized reading' (duration: 279.706208ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:27.117968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.87424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-03T17:27:27.117987Z","caller":"traceutil/trace.go:171","msg":"trace[1005788591] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:869; }","duration":"139.91704ms","start":"2026-02-03T17:27:26.978066Z","end":"2026-02-03T17:27:27.117983Z","steps":["trace[1005788591] 'agreement among raft nodes before linearized reading' (duration: 139.863539ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:27.118071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.419073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-03T17:27:27.118087Z","caller":"traceutil/trace.go:171","msg":"trace[536615083] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:869; }","duration":"262.449354ms","start":"2026-02-03T17:27:26.855634Z","end":"2026-02-03T17:27:27.118084Z","steps":["trace[536615083] 'agreement among raft nodes before linearized reading' (duration: 262.410703ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:27.594436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.975004ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:27:27.594572Z","caller":"traceutil/trace.go:171","msg":"trace[171875238] linearizableReadLoop","detail":"{readStateIndex:907; appliedIndex:904; }","duration":"309.644233ms","start":"2026-02-03T17:27:27.284918Z","end":"2026-02-03T17:27:27.594563Z","steps":["trace[171875238] 'read index received' (duration: 236.998002ms)","trace[171875238] 'applied index is now lower than readState.Index' (duration: 72.645721ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:27:27.59464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.718465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-03T17:27:27.594652Z","caller":"traceutil/trace.go:171","msg":"trace[1343622373] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:872; }","duration":"309.755335ms","start":"2026-02-03T17:27:27.284893Z","end":"2026-02-03T17:27:27.594648Z","steps":["trace[1343622373] 'agreement among raft nodes before linearized reading' (duration: 309.694994ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:27.59468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:27.28488Z","time spent":"309.795746ms","remote":"127.0.0.1:34738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"info","ts":"2026-02-03T17:27:27.5948Z","caller":"traceutil/trace.go:171","msg":"trace[411468777] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"472.913432ms","start":"2026-02-03T17:27:27.121867Z","end":"2026-02-03T17:27:27.594781Z","steps":["trace[411468777] 'process raft request' (duration: 93.531519ms)","trace[411468777] 'compare' (duration: 378.890562ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:27:27.594863Z","caller":"traceutil/trace.go:171","msg":"trace[2107319302] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"472.906151ms","start":"2026-02-03T17:27:27.121951Z","end":"2026-02-03T17:27:27.594857Z","steps":["trace[2107319302] 'process raft request' (duration: 472.551863ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:27.594897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:27.121947Z","time spent":"472.932972ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:27:27.594898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:27.121856Z","time spent":"472.990333ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:27:27.594961Z","caller":"traceutil/trace.go:171","msg":"trace[205654835] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"470.334978ms","start":"2026-02-03T17:27:27.124622Z","end":"2026-02-03T17:27:27.594957Z","steps":["trace[205654835] 'process raft request' (duration: 469.9244ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:27.594989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:27.124615Z","time spent":"470.35852ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:27:28.221775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.81904ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:27:28.221857Z","caller":"traceutil/trace.go:171","msg":"trace[189690767] linearizableReadLoop","detail":"{readStateIndex:908; appliedIndex:907; }","duration":"490.541124ms","start":"2026-02-03T17:27:27.731302Z","end":"2026-02-03T17:27:28.221843Z","steps":["trace[189690767] 'read index received' (duration: 172.548079ms)","trace[189690767] 'applied index is now lower than readState.Index' (duration: 317.991885ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:27:28.222255Z","caller":"traceutil/trace.go:171","msg":"trace[243280026] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"785.809641ms","start":"2026-02-03T17:27:27.436206Z","end":"2026-02-03T17:27:28.222016Z","steps":["trace[243280026] 'process raft request' (duration: 467.680544ms)","trace[243280026] 'compare' (duration: 317.506834ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:27:28.222342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:27.436191Z","time spent":"786.120767ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:27:28.222555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.248868ms","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-03T17:27:28.222586Z","caller":"traceutil/trace.go:171","msg":"trace[547277112] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:873; }","duration":"491.300419ms","start":"2026-02-03T17:27:27.731276Z","end":"2026-02-03T17:27:28.222576Z","steps":["trace[547277112] 'agreement among raft nodes before linearized reading' (duration: 491.199047ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:28.222614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:27.731264Z","time spent":"491.34356ms","remote":"127.0.0.1:34640","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-03T17:27:28.222732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.452996ms","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-03T17:27:28.22276Z","caller":"traceutil/trace.go:171","msg":"trace[1952460940] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:873; }","duration":"460.505457ms","start":"2026-02-03T17:27:27.762246Z","end":"2026-02-03T17:27:28.222751Z","steps":["trace[1952460940] 'agreement among raft nodes before linearized reading' (duration: 460.465096ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:28.222786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:27.762231Z","time spent":"460.547948ms","remote":"127.0.0.1:34660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-03T17:27:28.222946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.464587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:27:28.222971Z","caller":"traceutil/trace.go:171","msg":"trace[1583062771] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:873; }","duration":"404.510159ms","start":"2026-02-03T17:27:27.818453Z","end":"2026-02-03T17:27:28.222963Z","steps":["trace[1583062771] 'agreement among raft nodes before linearized reading' (duration: 404.474748ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:28.222994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:27.818441Z","time spent":"404.548099ms","remote":"127.0.0.1:34356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-03T17:27:29.401578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.047086ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-03T17:27:29.401697Z","caller":"traceutil/trace.go:171","msg":"trace[1110403018] linearizableReadLoop","detail":"{readStateIndex:910; appliedIndex:909; }","duration":"278.830229ms","start":"2026-02-03T17:27:29.122851Z","end":"2026-02-03T17:27:29.401681Z","steps":["trace[1110403018] 'read index received' (duration: 34.714822ms)","trace[1110403018] 'applied index is now lower than readState.Index' (duration: 244.113877ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:27:29.401838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.976822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-03T17:27:29.401863Z","caller":"traceutil/trace.go:171","msg":"trace[685418659] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:874; }","duration":"279.042074ms","start":"2026-02-03T17:27:29.122812Z","end":"2026-02-03T17:27:29.401854Z","steps":["trace[685418659] 'agreement among raft nodes before linearized reading' (duration: 278.914171ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:31.32138Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11974398664993332144,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-03T17:27:31.373896Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.088424679s","expected-duration":"1s"} {"level":"info","ts":"2026-02-03T17:27:31.374172Z","caller":"traceutil/trace.go:171","msg":"trace[2073595327] linearizableReadLoop","detail":"{readStateIndex:915; appliedIndex:914; }","duration":"553.245379ms","start":"2026-02-03T17:27:30.82091Z","end":"2026-02-03T17:27:31.374156Z","steps":["trace[2073595327] 'read index received' (duration: 553.132157ms)","trace[2073595327] 'applied index is now lower than readState.Index' (duration: 112.662µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:27:31.374266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.342061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:27:31.374287Z","caller":"traceutil/trace.go:171","msg":"trace[1272108662] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:879; }","duration":"553.435933ms","start":"2026-02-03T17:27:30.820845Z","end":"2026-02-03T17:27:31.374281Z","steps":["trace[1272108662] 'agreement among raft nodes before linearized reading' (duration: 553.362681ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:31.374304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:30.820783Z","time spent":"553.516524ms","remote":"127.0.0.1:34356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-03T17:27:31.374417Z","caller":"traceutil/trace.go:171","msg":"trace[1676823966] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"1.08899559s","start":"2026-02-03T17:27:30.285415Z","end":"2026-02-03T17:27:31.374411Z","steps":["trace[1676823966] 'process raft request' (duration: 1.088624093s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:31.374461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:30.285406Z","time spent":"1.089022491s","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:27:31.594597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.890087ms","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-03T17:27:31.59464Z","caller":"traceutil/trace.go:171","msg":"trace[80142631] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:879; }","duration":"173.968289ms","start":"2026-02-03T17:27:31.420661Z","end":"2026-02-03T17:27:31.594629Z","steps":["trace[80142631] 'range keys from in-memory index tree' (duration: 173.780985ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:31.801691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.517193ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:27:31.801781Z","caller":"traceutil/trace.go:171","msg":"trace[2014774855] linearizableReadLoop","detail":"{readStateIndex:918; appliedIndex:917; }","duration":"136.345037ms","start":"2026-02-03T17:27:31.66542Z","end":"2026-02-03T17:27:31.801765Z","steps":["trace[2014774855] 'read index received' (duration: 33.584809ms)","trace[2014774855] 'applied index is now lower than readState.Index' (duration: 102.759108ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:27:31.801964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.539382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-03T17:27:31.801991Z","caller":"traceutil/trace.go:171","msg":"trace[983591298] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:882; }","duration":"136.580443ms","start":"2026-02-03T17:27:31.665401Z","end":"2026-02-03T17:27:31.801981Z","steps":["trace[983591298] 'agreement among raft nodes before linearized reading' (duration: 136.406068ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:31.802273Z","caller":"traceutil/trace.go:171","msg":"trace[1465578929] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"168.267452ms","start":"2026-02-03T17:27:31.633993Z","end":"2026-02-03T17:27:31.802261Z","steps":["trace[1465578929] 'process raft request' (duration: 65.073475ms)","trace[1465578929] 'compare' (duration: 102.40176ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:27:33.892487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.352932ms","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-03T17:27:33.89254Z","caller":"traceutil/trace.go:171","msg":"trace[1196855980] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:885; }","duration":"513.431903ms","start":"2026-02-03T17:27:33.379092Z","end":"2026-02-03T17:27:33.892524Z","steps":["trace[1196855980] 'range keys from in-memory index tree' (duration: 513.253669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:33.892566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:33.379088Z","time spent":"513.470804ms","remote":"127.0.0.1:34640","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-03T17:27:33.892652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.837225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4740"} {"level":"info","ts":"2026-02-03T17:27:33.892699Z","caller":"traceutil/trace.go:171","msg":"trace[1876848480] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:885; }","duration":"501.911037ms","start":"2026-02-03T17:27:33.390776Z","end":"2026-02-03T17:27:33.892687Z","steps":["trace[1876848480] 'range keys from in-memory index tree' (duration: 501.732494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:33.892722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:33.390768Z","time spent":"501.948978ms","remote":"127.0.0.1:34546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4763,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-03T17:27:33.892826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.865092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.154\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-03T17:27:33.892843Z","caller":"traceutil/trace.go:171","msg":"trace[152564856] range","detail":"{range_begin:/registry/masterleases/199.19.213.154; range_end:; response_count:1; response_revision:885; }","duration":"513.906292ms","start":"2026-02-03T17:27:33.378932Z","end":"2026-02-03T17:27:33.892838Z","steps":["trace[152564856] 'range keys from in-memory index tree' (duration: 513.784471ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:33.892856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:33.378918Z","time spent":"513.934483ms","remote":"127.0.0.1:34392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.154\" "} {"level":"warn","ts":"2026-02-03T17:27:33.892906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.565485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-03T17:27:33.892922Z","caller":"traceutil/trace.go:171","msg":"trace[2122044671] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:885; }","duration":"260.629527ms","start":"2026-02-03T17:27:33.632288Z","end":"2026-02-03T17:27:33.892917Z","steps":["trace[2122044671] 'range keys from in-memory index tree' (duration: 260.458273ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:33.933987Z","caller":"traceutil/trace.go:171","msg":"trace[451343961] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"295.033602ms","start":"2026-02-03T17:27:33.638936Z","end":"2026-02-03T17:27:33.93397Z","steps":["trace[451343961] 'process raft request' (duration: 294.94017ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:33.934327Z","caller":"traceutil/trace.go:171","msg":"trace[638701454] linearizableReadLoop","detail":"{readStateIndex:922; appliedIndex:922; }","duration":"264.473495ms","start":"2026-02-03T17:27:33.669848Z","end":"2026-02-03T17:27:33.934321Z","steps":["trace[638701454] 'read index received' (duration: 264.470495ms)","trace[638701454] 'applied index is now lower than readState.Index' (duration: 2.52µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:27:33.934464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.603228ms","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-03T17:27:33.934485Z","caller":"traceutil/trace.go:171","msg":"trace[367375123] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:886; }","duration":"264.66459ms","start":"2026-02-03T17:27:33.669815Z","end":"2026-02-03T17:27:33.93448Z","steps":["trace[367375123] 'agreement among raft nodes before linearized reading' (duration: 264.549698ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:33.990754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.529035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-03T17:27:33.990806Z","caller":"traceutil/trace.go:171","msg":"trace[2077177983] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:888; }","duration":"182.616747ms","start":"2026-02-03T17:27:33.80818Z","end":"2026-02-03T17:27:33.990797Z","steps":["trace[2077177983] 'agreement among raft nodes before linearized reading' (duration: 182.483774ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:33.991097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.714267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-03T17:27:33.991117Z","caller":"traceutil/trace.go:171","msg":"trace[213254665] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:888; }","duration":"161.746687ms","start":"2026-02-03T17:27:33.829366Z","end":"2026-02-03T17:27:33.991112Z","steps":["trace[213254665] 'agreement among raft nodes before linearized reading' (duration: 161.700496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:33.99118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.741502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:27:33.991196Z","caller":"traceutil/trace.go:171","msg":"trace[1742959744] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:888; }","duration":"170.771133ms","start":"2026-02-03T17:27:33.820421Z","end":"2026-02-03T17:27:33.991193Z","steps":["trace[1742959744] 'agreement among raft nodes before linearized reading' (duration: 170.747012ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:34.403029Z","caller":"traceutil/trace.go:171","msg":"trace[1881933802] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"461.543668ms","start":"2026-02-03T17:27:33.941464Z","end":"2026-02-03T17:27:34.403007Z","steps":["trace[1881933802] 'process raft request' (duration: 461.491807ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:34.403163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:33.941457Z","time spent":"461.65086ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":799,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-03T17:27:34.403542Z","caller":"traceutil/trace.go:171","msg":"trace[1574156518] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"462.256244ms","start":"2026-02-03T17:27:33.941274Z","end":"2026-02-03T17:27:34.403531Z","steps":["trace[1574156518] 'process raft request' (duration: 461.285904ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:34.403606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:33.941259Z","time spent":"462.308415ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:27:34.403909Z","caller":"traceutil/trace.go:171","msg":"trace[714518120] linearizableReadLoop","detail":"{readStateIndex:927; appliedIndex:927; }","duration":"409.551032ms","start":"2026-02-03T17:27:33.994346Z","end":"2026-02-03T17:27:34.403897Z","steps":["trace[714518120] 'read index received' (duration: 409.546642ms)","trace[714518120] 'applied index is now lower than readState.Index' (duration: 3.57µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:27:34.403997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.641164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:27:34.404024Z","caller":"traceutil/trace.go:171","msg":"trace[894940088] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:890; }","duration":"409.697305ms","start":"2026-02-03T17:27:33.994319Z","end":"2026-02-03T17:27:34.404016Z","steps":["trace[894940088] 'agreement among raft nodes before linearized reading' (duration: 409.638783ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:34.404049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:33.994308Z","time spent":"409.735496ms","remote":"127.0.0.1:34366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-03T17:27:34.674624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.933987ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:27:34.674855Z","caller":"traceutil/trace.go:171","msg":"trace[700478305] linearizableReadLoop","detail":"{readStateIndex:931; appliedIndex:927; }","duration":"270.908057ms","start":"2026-02-03T17:27:34.403932Z","end":"2026-02-03T17:27:34.67484Z","steps":["trace[700478305] 'read index received' (duration: 99.605503ms)","trace[700478305] 'applied index is now lower than readState.Index' (duration: 171.301734ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:27:34.674949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.680565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:27:34.674975Z","caller":"traceutil/trace.go:171","msg":"trace[587671653] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:894; }","duration":"528.743267ms","start":"2026-02-03T17:27:34.146223Z","end":"2026-02-03T17:27:34.674967Z","steps":["trace[587671653] 'agreement among raft nodes before linearized reading' (duration: 528.661375ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:34.675007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:34.146211Z","time spent":"528.788417ms","remote":"127.0.0.1:34740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" "} {"level":"info","ts":"2026-02-03T17:27:34.675279Z","caller":"traceutil/trace.go:171","msg":"trace[1840125787] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"675.58457ms","start":"2026-02-03T17:27:33.999682Z","end":"2026-02-03T17:27:34.675267Z","steps":["trace[1840125787] 'process raft request' (duration: 503.907527ms)","trace[1840125787] 'compare' (duration: 170.772174ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:27:34.675339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:33.99967Z","time spent":"675.637921ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:27:34.675466Z","caller":"traceutil/trace.go:171","msg":"trace[619926867] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"674.768482ms","start":"2026-02-03T17:27:34.000689Z","end":"2026-02-03T17:27:34.675457Z","steps":["trace[619926867] 'process raft request' (duration: 674.011796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:34.675516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:34.000683Z","time spent":"674.805994ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:27:34.67564Z","caller":"traceutil/trace.go:171","msg":"trace[1312251318] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"661.863058ms","start":"2026-02-03T17:27:34.013768Z","end":"2026-02-03T17:27:34.675631Z","steps":["trace[1312251318] 'process raft request' (duration: 660.981859ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:34.675687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:34.013753Z","time spent":"661.908308ms","remote":"127.0.0.1:34392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:27:34.675799Z","caller":"traceutil/trace.go:171","msg":"trace[2138207519] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"594.486816ms","start":"2026-02-03T17:27:34.081304Z","end":"2026-02-03T17:27:34.675791Z","steps":["trace[2138207519] 'process raft request' (duration: 593.488365ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:34.675844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:34.081283Z","time spent":"594.536577ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:27:38.156057Z","caller":"traceutil/trace.go:171","msg":"trace[713765639] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"146.300892ms","start":"2026-02-03T17:27:38.009728Z","end":"2026-02-03T17:27:38.156029Z","steps":["trace[713765639] 'process raft request' (duration: 146.136698ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:38.302531Z","caller":"traceutil/trace.go:171","msg":"trace[73602067] linearizableReadLoop","detail":"{readStateIndex:954; appliedIndex:953; }","duration":"284.110858ms","start":"2026-02-03T17:27:38.018401Z","end":"2026-02-03T17:27:38.302512Z","steps":["trace[73602067] 'read index received' (duration: 139.90868ms)","trace[73602067] 'applied index is now lower than readState.Index' (duration: 144.201538ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:27:38.30263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.219561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:27:38.302647Z","caller":"traceutil/trace.go:171","msg":"trace[1297803102] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:917; }","duration":"284.278952ms","start":"2026-02-03T17:27:38.018363Z","end":"2026-02-03T17:27:38.302642Z","steps":["trace[1297803102] 'agreement among raft nodes before linearized reading' (duration: 284.21012ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:38.302819Z","caller":"traceutil/trace.go:171","msg":"trace[92130937] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"290.972999ms","start":"2026-02-03T17:27:38.011838Z","end":"2026-02-03T17:27:38.302811Z","steps":["trace[92130937] 'process raft request' (duration: 258.429531ms)","trace[92130937] 'compare' (duration: 32.17968ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:27:48.737618Z","caller":"traceutil/trace.go:171","msg":"trace[1323521894] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"743.939844ms","start":"2026-02-03T17:27:47.993604Z","end":"2026-02-03T17:27:48.737544Z","steps":["trace[1323521894] 'process raft request' (duration: 743.790981ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:48.738156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:47.993584Z","time spent":"744.414913ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:27:48.949938Z","caller":"traceutil/trace.go:171","msg":"trace[1160262430] linearizableReadLoop","detail":"{readStateIndex:1027; appliedIndex:1026; }","duration":"652.423626ms","start":"2026-02-03T17:27:48.297496Z","end":"2026-02-03T17:27:48.94992Z","steps":["trace[1160262430] 'read index received' (duration: 440.188902ms)","trace[1160262430] 'applied index is now lower than readState.Index' (duration: 212.233874ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:27:48.95018Z","caller":"traceutil/trace.go:171","msg":"trace[1492014996] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"726.984625ms","start":"2026-02-03T17:27:48.223179Z","end":"2026-02-03T17:27:48.950163Z","steps":["trace[1492014996] 'process raft request' (duration: 726.600468ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:48.951231Z","caller":"traceutil/trace.go:171","msg":"trace[102374964] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"287.069419ms","start":"2026-02-03T17:27:48.664148Z","end":"2026-02-03T17:27:48.951218Z","steps":["trace[102374964] 'process raft request' (duration: 285.733843ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:48.95138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.868713ms","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-03T17:27:48.952332Z","caller":"traceutil/trace.go:171","msg":"trace[438912017] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:989; }","duration":"654.856423ms","start":"2026-02-03T17:27:48.297462Z","end":"2026-02-03T17:27:48.952318Z","steps":["trace[438912017] 'agreement among raft nodes before linearized reading' (duration: 653.810543ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:48.952427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:48.29745Z","time spent":"654.962994ms","remote":"127.0.0.1:34640","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-03T17:27:48.95169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.168816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-03T17:27:48.952721Z","caller":"traceutil/trace.go:171","msg":"trace[1109735179] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:989; }","duration":"610.208526ms","start":"2026-02-03T17:27:48.342489Z","end":"2026-02-03T17:27:48.952698Z","steps":["trace[1109735179] 'agreement among raft nodes before linearized reading' (duration: 609.109145ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:48.953028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:48.34248Z","time spent":"610.531202ms","remote":"127.0.0.1:34640","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-03T17:27:48.951728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.16761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:27:48.953722Z","caller":"traceutil/trace.go:171","msg":"trace[359207366] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:989; }","duration":"132.187799ms","start":"2026-02-03T17:27:48.821519Z","end":"2026-02-03T17:27:48.953707Z","steps":["trace[359207366] 'agreement among raft nodes before linearized reading' (duration: 130.191971ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:48.951792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.470657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-03T17:27:48.954398Z","caller":"traceutil/trace.go:171","msg":"trace[1375018258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:989; }","duration":"142.094387ms","start":"2026-02-03T17:27:48.812292Z","end":"2026-02-03T17:27:48.954386Z","steps":["trace[1375018258] 'agreement among raft nodes before linearized reading' (duration: 139.450066ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:48.95185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.90147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-03T17:27:48.954781Z","caller":"traceutil/trace.go:171","msg":"trace[1314021223] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:989; }","duration":"210.853467ms","start":"2026-02-03T17:27:48.743916Z","end":"2026-02-03T17:27:48.954769Z","steps":["trace[1314021223] 'agreement among raft nodes before linearized reading' (duration: 207.88949ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:48.95191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.51168ms","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-03T17:27:48.955163Z","caller":"traceutil/trace.go:171","msg":"trace[1275429285] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:989; }","duration":"498.781813ms","start":"2026-02-03T17:27:48.456369Z","end":"2026-02-03T17:27:48.955151Z","steps":["trace[1275429285] 'agreement among raft nodes before linearized reading' (duration: 495.494219ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:48.955259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:48.456357Z","time spent":"498.885234ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-03T17:27:48.956287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:48.223153Z","time spent":"728.83083ms","remote":"127.0.0.1:34546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5898,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:27:49.067586Z","caller":"traceutil/trace.go:171","msg":"trace[410890888] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"101.460074ms","start":"2026-02-03T17:27:48.966106Z","end":"2026-02-03T17:27:49.067566Z","steps":["trace[410890888] 'process raft request' (duration: 100.862852ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:49.067935Z","caller":"traceutil/trace.go:171","msg":"trace[558773550] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"101.84635ms","start":"2026-02-03T17:27:48.966079Z","end":"2026-02-03T17:27:49.067926Z","steps":["trace[558773550] 'process raft request' (duration: 100.604566ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:49.068464Z","caller":"traceutil/trace.go:171","msg":"trace[1679591332] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"101.086166ms","start":"2026-02-03T17:27:48.967367Z","end":"2026-02-03T17:27:49.068453Z","steps":["trace[1679591332] 'process raft request' (duration: 99.654129ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:35.176024Z","caller":"traceutil/trace.go:171","msg":"trace[2065319217] linearizableReadLoop","detail":"{readStateIndex:1227; appliedIndex:1226; }","duration":"123.603364ms","start":"2026-02-03T17:28:35.052398Z","end":"2026-02-03T17:28:35.176001Z","steps":["trace[2065319217] 'read index received' (duration: 123.34914ms)","trace[2065319217] 'applied index is now lower than readState.Index' (duration: 253.284µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:28:35.176279Z","caller":"traceutil/trace.go:171","msg":"trace[1865132324] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"137.537431ms","start":"2026-02-03T17:28:35.03873Z","end":"2026-02-03T17:28:35.176267Z","steps":["trace[1865132324] 'process raft request' (duration: 137.071432ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:35.176486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.066924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:28:35.176526Z","caller":"traceutil/trace.go:171","msg":"trace[985827160] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1178; }","duration":"124.137465ms","start":"2026-02-03T17:28:35.05238Z","end":"2026-02-03T17:28:35.176517Z","steps":["trace[985827160] 'agreement among raft nodes before linearized reading' (duration: 124.052473ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:29:11.996532Z","caller":"traceutil/trace.go:171","msg":"trace[2047678921] linearizableReadLoop","detail":"{readStateIndex:1388; appliedIndex:1387; }","duration":"172.878382ms","start":"2026-02-03T17:29:11.823617Z","end":"2026-02-03T17:29:11.996495Z","steps":["trace[2047678921] 'read index received' (duration: 134.398592ms)","trace[2047678921] 'applied index is now lower than readState.Index' (duration: 38.47874ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:29:11.99668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.055655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:29:11.996713Z","caller":"traceutil/trace.go:171","msg":"trace[1764529640] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1332; }","duration":"173.139167ms","start":"2026-02-03T17:29:11.823563Z","end":"2026-02-03T17:29:11.996702Z","steps":["trace[1764529640] 'agreement among raft nodes before linearized reading' (duration: 173.035665ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:29:11.996798Z","caller":"traceutil/trace.go:171","msg":"trace[275017073] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"204.400821ms","start":"2026-02-03T17:29:11.792365Z","end":"2026-02-03T17:29:11.996765Z","steps":["trace[275017073] 'process raft request' (duration: 165.629006ms)","trace[275017073] 'compare' (duration: 38.366688ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:31:00.688186Z","caller":"traceutil/trace.go:171","msg":"trace[128117550] transaction","detail":"{read_only:false; response_revision:1806; number_of_response:1; }","duration":"105.410545ms","start":"2026-02-03T17:31:00.582745Z","end":"2026-02-03T17:31:00.688155Z","steps":["trace[128117550] 'process raft request' (duration: 33.083086ms)","trace[128117550] 'compare' (duration: 72.124645ms)"],"step_count":2}