{"level":"warn","ts":"2026-06-28T19:55:29.102559Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-28T19:55:29.102656Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.246:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.246:2380","--initial-cluster=instance=https://199.204.45.246:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.246:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.246: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-06-28T19:55:29.102736Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-28T19:55:29.102747Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-06-28T19:55:29.102768Z","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-06-28T19:55:29.103577Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"]} {"level":"info","ts":"2026-06-28T19:55:29.103668Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.246: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-06-28T19:55:29.109159Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.212029ms"} {"level":"info","ts":"2026-06-28T19:55:29.116223Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ab97322ebdd79664","cluster-id":"87bc89380c3dc2c"} {"level":"info","ts":"2026-06-28T19:55:29.116301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=()"} {"level":"info","ts":"2026-06-28T19:55:29.116319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 0"} {"level":"info","ts":"2026-06-28T19:55:29.116373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ab97322ebdd79664 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-28T19:55:29.116384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 1"} {"level":"info","ts":"2026-06-28T19:55:29.116485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"warn","ts":"2026-06-28T19:55:29.126186Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-28T19:55:29.12751Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-28T19:55:29.128537Z","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-06-28T19:55:29.130839Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ab97322ebdd79664","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-28T19:55:29.135041Z","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-06-28T19:55:29.135721Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ab97322ebdd79664","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-28T19:55:29.135905Z","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-06-28T19:55:29.135997Z","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-06-28T19:55:29.136006Z","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-06-28T19:55:29.139769Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"info","ts":"2026-06-28T19:55:29.139895Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","added-peer-id":"ab97322ebdd79664","added-peer-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-06-28T19:55:29.140021Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-06-28T19:55:29.140042Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-06-28T19:55:29.140166Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ab97322ebdd79664","initial-advertise-peer-urls":["https://199.204.45.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-28T19:55:29.140198Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-28T19:55:29.717412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 is starting a new election at term 1"} {"level":"info","ts":"2026-06-28T19:55:29.717477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-28T19:55:29.71751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgPreVoteResp from ab97322ebdd79664 at term 1"} {"level":"info","ts":"2026-06-28T19:55:29.717529Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became candidate at term 2"} {"level":"info","ts":"2026-06-28T19:55:29.71754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgVoteResp from ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-06-28T19:55:29.717556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became leader at term 2"} {"level":"info","ts":"2026-06-28T19:55:29.717569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ab97322ebdd79664 elected leader ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-06-28T19:55:29.718703Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-28T19:55:29.718798Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-28T19:55:29.718892Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-28T19:55:29.720232Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","cluster-version":"3.5"} {"level":"info","ts":"2026-06-28T19:55:29.720378Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-28T19:55:29.720414Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-28T19:55:29.72064Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-28T19:55:29.720712Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-28T19:55:29.721646Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-28T19:55:29.722628Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ab97322ebdd79664","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.246:2379]}","request-path":"/0/members/ab97322ebdd79664/attributes","cluster-id":"87bc89380c3dc2c","publish-timeout":"7s"} {"level":"info","ts":"2026-06-28T19:55:29.722626Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.246:2379"} {"level":"info","ts":"2026-06-28T19:55:32.282454Z","caller":"traceutil/trace.go:171","msg":"trace[1084194752] transaction","detail":"{read_only:false; response_revision:103; number_of_response:1; }","duration":"178.853475ms","start":"2026-06-28T19:55:32.103548Z","end":"2026-06-28T19:55:32.282401Z","steps":["trace[1084194752] 'process raft request' (duration: 167.120575ms)","trace[1084194752] 'compare' (duration: 11.614777ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:55:37.306613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.167801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-06-28T19:55:37.306551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.057221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-06-28T19:55:37.306692Z","caller":"traceutil/trace.go:171","msg":"trace[610755720] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:276; }","duration":"925.298614ms","start":"2026-06-28T19:55:36.381378Z","end":"2026-06-28T19:55:37.306677Z","steps":["trace[610755720] 'range keys from in-memory index tree' (duration: 925.053128ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:55:37.306715Z","caller":"traceutil/trace.go:171","msg":"trace[850644005] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:276; }","duration":"164.281227ms","start":"2026-06-28T19:55:37.142414Z","end":"2026-06-28T19:55:37.306696Z","steps":["trace[850644005] 'range keys from in-memory index tree' (duration: 163.903067ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:55:37.306729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:55:36.381359Z","time spent":"925.361565ms","remote":"127.0.0.1:40638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-06-28T19:55:38.005813Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836961493574999687,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-28T19:55:38.2083Z","caller":"traceutil/trace.go:171","msg":"trace[720154186] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"898.139324ms","start":"2026-06-28T19:55:37.310136Z","end":"2026-06-28T19:55:38.208276Z","steps":["trace[720154186] 'process raft request' (duration: 897.97979ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:55:38.208985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:55:37.310114Z","time spent":"898.269337ms","remote":"127.0.0.1:40816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-28T19:55:38.210353Z","caller":"traceutil/trace.go:171","msg":"trace[616766778] linearizableReadLoop","detail":"{readStateIndex:291; appliedIndex:289; }","duration":"705.294123ms","start":"2026-06-28T19:55:37.505035Z","end":"2026-06-28T19:55:38.210329Z","steps":["trace[616766778] 'read index received' (duration: 703.096069ms)","trace[616766778] 'applied index is now lower than readState.Index' (duration: 2.196844ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:55:38.210485Z","caller":"traceutil/trace.go:171","msg":"trace[67860601] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"900.122043ms","start":"2026-06-28T19:55:37.310292Z","end":"2026-06-28T19:55:38.210414Z","steps":["trace[67860601] 'process raft request' (duration: 899.945708ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:55:38.210561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.520128ms","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-06-28T19:55:38.210658Z","caller":"traceutil/trace.go:171","msg":"trace[2009075769] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:278; }","duration":"705.59639ms","start":"2026-06-28T19:55:37.504999Z","end":"2026-06-28T19:55:38.210596Z","steps":["trace[2009075769] 'agreement among raft nodes before linearized reading' (duration: 705.446567ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:55:38.210702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:55:37.504984Z","time spent":"705.706673ms","remote":"127.0.0.1:40816","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-06-28T19:55:38.210748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:55:37.310278Z","time spent":"900.345488ms","remote":"127.0.0.1:40736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":194,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-28T19:55:38.210544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.671375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-28T19:55:38.211013Z","caller":"traceutil/trace.go:171","msg":"trace[1143238946] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:278; }","duration":"666.212058ms","start":"2026-06-28T19:55:37.544779Z","end":"2026-06-28T19:55:38.210992Z","steps":["trace[1143238946] 'agreement among raft nodes before linearized reading' (duration: 665.692585ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:55:38.211057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:55:37.544758Z","time spent":"666.285999ms","remote":"127.0.0.1:40816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-28T19:56:03.053504Z","caller":"traceutil/trace.go:171","msg":"trace[1609980222] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"103.793762ms","start":"2026-06-28T19:56:02.949682Z","end":"2026-06-28T19:56:03.053476Z","steps":["trace[1609980222] 'compare' (duration: 94.453166ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:56:03.578234Z","caller":"traceutil/trace.go:171","msg":"trace[2014194833] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"520.366506ms","start":"2026-06-28T19:56:03.057843Z","end":"2026-06-28T19:56:03.578209Z","steps":["trace[2014194833] 'process raft request' (duration: 520.237173ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:56:03.578402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:56:03.057822Z","time spent":"520.486728ms","remote":"127.0.0.1:40906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-28T19:56:04.705225Z","caller":"traceutil/trace.go:171","msg":"trace[1095409862] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"222.64722ms","start":"2026-06-28T19:56:04.482538Z","end":"2026-06-28T19:56:04.705185Z","steps":["trace[1095409862] 'process raft request' (duration: 222.465095ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:56:06.188783Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836961493575000125,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-28T19:56:06.219144Z","caller":"traceutil/trace.go:171","msg":"trace[1461280113] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"851.268513ms","start":"2026-06-28T19:56:05.367844Z","end":"2026-06-28T19:56:06.219113Z","steps":["trace[1461280113] 'process raft request' (duration: 821.055727ms)","trace[1461280113] 'compare' (duration: 29.346322ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:56:06.219335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:56:05.367817Z","time spent":"851.426737ms","remote":"127.0.0.1:40596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29452,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-28T19:56:06.220332Z","caller":"traceutil/trace.go:171","msg":"trace[356069264] linearizableReadLoop","detail":"{readStateIndex:538; appliedIndex:535; }","duration":"531.637011ms","start":"2026-06-28T19:56:05.688653Z","end":"2026-06-28T19:56:06.22029Z","steps":["trace[356069264] 'read index received' (duration: 500.237464ms)","trace[356069264] 'applied index is now lower than readState.Index' (duration: 31.383176ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:56:06.219553Z","caller":"traceutil/trace.go:171","msg":"trace[1042991437] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"851.396987ms","start":"2026-06-28T19:56:05.368131Z","end":"2026-06-28T19:56:06.219528Z","steps":["trace[1042991437] 'process raft request' (duration: 850.629947ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:56:06.221143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:56:05.368111Z","time spent":"852.960577ms","remote":"127.0.0.1:40596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-28T19:56:06.221212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.461773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:56:06.221292Z","caller":"traceutil/trace.go:171","msg":"trace[641519919] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:516; }","duration":"532.669789ms","start":"2026-06-28T19:56:05.688605Z","end":"2026-06-28T19:56:06.221275Z","steps":["trace[641519919] 'agreement among raft nodes before linearized reading' (duration: 531.731264ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:56:06.22134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:56:05.688585Z","time spent":"532.743841ms","remote":"127.0.0.1:40554","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-06-28T19:56:06.221808Z","caller":"traceutil/trace.go:171","msg":"trace[3464713] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"820.439942ms","start":"2026-06-28T19:56:05.401252Z","end":"2026-06-28T19:56:06.221692Z","steps":["trace[3464713] 'process raft request' (duration: 818.143381ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:56:06.221913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:56:05.401221Z","time spent":"820.636837ms","remote":"127.0.0.1:40596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-28T19:56:06.224348Z","caller":"etcdserver/v3_server.go:874","msg":"ignored out-of-date read index response; local node read indexes queueing up and waiting to be in sync with leader","sent-request-id":10836961493575000165,"received-request-id":10836961493575000125} {"level":"info","ts":"2026-06-28T19:56:12.084427Z","caller":"traceutil/trace.go:171","msg":"trace[1753377614] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"251.996821ms","start":"2026-06-28T19:56:11.832401Z","end":"2026-06-28T19:56:12.084398Z","steps":["trace[1753377614] 'process raft request' (duration: 248.483819ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:56:12.40738Z","caller":"traceutil/trace.go:171","msg":"trace[1014520446] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"573.123125ms","start":"2026-06-28T19:56:11.834236Z","end":"2026-06-28T19:56:12.407359Z","steps":["trace[1014520446] 'process raft request' (duration: 523.977601ms)","trace[1014520446] 'compare' (duration: 49.074062ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:56:12.407548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:56:11.834212Z","time spent":"573.224357ms","remote":"127.0.0.1:40816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-28T19:56:12.829564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.728677ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-28T19:56:12.829662Z","caller":"traceutil/trace.go:171","msg":"trace[1396156354] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:609; }","duration":"459.317497ms","start":"2026-06-28T19:56:12.370328Z","end":"2026-06-28T19:56:12.829646Z","steps":["trace[1396156354] 'read index received' (duration: 281.368875ms)","trace[1396156354] 'applied index is now lower than readState.Index' (duration: 177.947502ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:56:12.829779Z","caller":"traceutil/trace.go:171","msg":"trace[1954294550] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"690.897814ms","start":"2026-06-28T19:56:12.138867Z","end":"2026-06-28T19:56:12.829765Z","steps":["trace[1954294550] 'process raft request' (duration: 512.772536ms)","trace[1954294550] 'compare' (duration: 177.590534ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:56:12.829887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:56:12.138849Z","time spent":"691.007198ms","remote":"127.0.0.1:41068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-28T19:56:12.829983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.309713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-28T19:56:12.830043Z","caller":"traceutil/trace.go:171","msg":"trace[1075078792] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:587; }","duration":"458.414065ms","start":"2026-06-28T19:56:12.371616Z","end":"2026-06-28T19:56:12.83003Z","steps":["trace[1075078792] 'agreement among raft nodes before linearized reading' (duration: 458.2258ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:56:12.830081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:56:12.371598Z","time spent":"458.477007ms","remote":"127.0.0.1:40816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-28T19:56:12.830108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.956197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:56:12.830164Z","caller":"traceutil/trace.go:171","msg":"trace[917220470] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:587; }","duration":"142.036849ms","start":"2026-06-28T19:56:12.688108Z","end":"2026-06-28T19:56:12.830145Z","steps":["trace[917220470] 'agreement among raft nodes before linearized reading' (duration: 141.968358ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:56:12.830218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.891153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-06-28T19:56:12.830258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.067316ms","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-06-28T19:56:12.830263Z","caller":"traceutil/trace.go:171","msg":"trace[198861981] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:587; }","duration":"459.947405ms","start":"2026-06-28T19:56:12.370301Z","end":"2026-06-28T19:56:12.830249Z","steps":["trace[198861981] 'agreement among raft nodes before linearized reading' (duration: 459.843072ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:56:12.830281Z","caller":"traceutil/trace.go:171","msg":"trace[403810335] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:587; }","duration":"458.136048ms","start":"2026-06-28T19:56:12.372137Z","end":"2026-06-28T19:56:12.830273Z","steps":["trace[403810335] 'agreement among raft nodes before linearized reading' (duration: 458.078376ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:56:12.83031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:56:12.372125Z","time spent":"458.170708ms","remote":"127.0.0.1:40816","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-06-28T19:56:12.830311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:56:12.37029Z","time spent":"460.012106ms","remote":"127.0.0.1:40816","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-06-28T19:56:12.83011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.127689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-kndjq\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-06-28T19:56:12.830363Z","caller":"traceutil/trace.go:171","msg":"trace[1389853774] range","detail":"{range_begin:/registry/pods/kube-system/cilium-kndjq; range_end:; response_count:1; response_revision:587; }","duration":"330.419086ms","start":"2026-06-28T19:56:12.499931Z","end":"2026-06-28T19:56:12.83035Z","steps":["trace[1389853774] 'agreement among raft nodes before linearized reading' (duration: 330.111678ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:56:12.830414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:56:12.499905Z","time spent":"330.496668ms","remote":"127.0.0.1:40714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-kndjq\" "} {"level":"warn","ts":"2026-06-28T19:56:13.783007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.767932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-28T19:56:13.783098Z","caller":"traceutil/trace.go:171","msg":"trace[1565036633] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:593; }","duration":"852.883575ms","start":"2026-06-28T19:56:12.930193Z","end":"2026-06-28T19:56:13.783076Z","steps":["trace[1565036633] 'range keys from in-memory index tree' (duration: 852.704781ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:56:13.783264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:56:12.930188Z","time spent":"852.997668ms","remote":"127.0.0.1:40592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-06-28T19:56:14.189173Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836961493575000372,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-28T19:56:14.329267Z","caller":"traceutil/trace.go:171","msg":"trace[1626422518] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:617; }","duration":"640.715703ms","start":"2026-06-28T19:56:13.688528Z","end":"2026-06-28T19:56:14.329243Z","steps":["trace[1626422518] 'read index received' (duration: 640.039956ms)","trace[1626422518] 'applied index is now lower than readState.Index' (duration: 674.627µs)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:56:14.329391Z","caller":"traceutil/trace.go:171","msg":"trace[2049418403] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"1.027425329s","start":"2026-06-28T19:56:13.301921Z","end":"2026-06-28T19:56:14.329346Z","steps":["trace[2049418403] 'process raft request' (duration: 1.026768282s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:56:14.329523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.390908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-06-28T19:56:14.329566Z","caller":"traceutil/trace.go:171","msg":"trace[2123419767] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:594; }","duration":"544.48739ms","start":"2026-06-28T19:56:13.785068Z","end":"2026-06-28T19:56:14.329555Z","steps":["trace[2123419767] 'agreement among raft nodes before linearized reading' (duration: 544.315646ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:56:14.329562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.068293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-28T19:56:14.329603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:56:13.785049Z","time spent":"544.545682ms","remote":"127.0.0.1:40832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-06-28T19:56:14.329625Z","caller":"traceutil/trace.go:171","msg":"trace[1743867411] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:594; }","duration":"641.188336ms","start":"2026-06-28T19:56:13.688423Z","end":"2026-06-28T19:56:14.329611Z","steps":["trace[1743867411] 'agreement among raft nodes before linearized reading' (duration: 641.020212ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:56:14.329662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:56:13.6884Z","time spent":"641.252858ms","remote":"127.0.0.1:40554","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-06-28T19:56:14.329782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:56:13.301895Z","time spent":"1.027640095s","remote":"127.0.0.1:40634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036297,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-28T19:56:15.699379Z","caller":"traceutil/trace.go:171","msg":"trace[1325622038] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"172.236313ms","start":"2026-06-28T19:56:15.527104Z","end":"2026-06-28T19:56:15.699341Z","steps":["trace[1325622038] 'process raft request' (duration: 171.996217ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:56:16.365686Z","caller":"traceutil/trace.go:171","msg":"trace[95561243] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"356.689829ms","start":"2026-06-28T19:56:16.008975Z","end":"2026-06-28T19:56:16.365664Z","steps":["trace[95561243] 'process raft request' (duration: 356.110634ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:56:16.365821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:56:16.008951Z","time spent":"356.807481ms","remote":"127.0.0.1:40620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":763,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-28T19:56:26.64358Z","caller":"traceutil/trace.go:171","msg":"trace[52325997] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"188.336268ms","start":"2026-06-28T19:56:26.455222Z","end":"2026-06-28T19:56:26.643558Z","steps":["trace[52325997] 'process raft request' (duration: 188.191694ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:56:29.187125Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836961493575000670,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-28T19:56:29.530346Z","caller":"traceutil/trace.go:171","msg":"trace[284548220] linearizableReadLoop","detail":"{readStateIndex:780; appliedIndex:779; }","duration":"843.534281ms","start":"2026-06-28T19:56:28.686789Z","end":"2026-06-28T19:56:29.530323Z","steps":["trace[284548220] 'read index received' (duration: 843.352776ms)","trace[284548220] 'applied index is now lower than readState.Index' (duration: 180.585µs)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:56:29.530563Z","caller":"traceutil/trace.go:171","msg":"trace[434641561] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"878.451079ms","start":"2026-06-28T19:56:28.651935Z","end":"2026-06-28T19:56:29.530386Z","steps":["trace[434641561] 'process raft request' (duration: 878.253634ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:56:29.530635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.688706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-28T19:56:29.530695Z","caller":"traceutil/trace.go:171","msg":"trace[454388710] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:752; }","duration":"541.802029ms","start":"2026-06-28T19:56:28.988879Z","end":"2026-06-28T19:56:29.530681Z","steps":["trace[454388710] 'agreement among raft nodes before linearized reading' (duration: 541.668276ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:56:29.530699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.203296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-28T19:56:29.530685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.83771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-06-28T19:56:29.530735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:56:28.988854Z","time spent":"541.87372ms","remote":"127.0.0.1:40816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-28T19:56:29.530737Z","caller":"traceutil/trace.go:171","msg":"trace[1845771196] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:752; }","duration":"543.277288ms","start":"2026-06-28T19:56:28.987449Z","end":"2026-06-28T19:56:29.530726Z","steps":["trace[1845771196] 'agreement among raft nodes before linearized reading' (duration: 543.200056ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:56:29.530771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:56:28.987429Z","time spent":"543.335789ms","remote":"127.0.0.1:40816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-28T19:56:29.530772Z","caller":"traceutil/trace.go:171","msg":"trace[575389445] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:752; }","duration":"549.974464ms","start":"2026-06-28T19:56:28.980778Z","end":"2026-06-28T19:56:29.530752Z","steps":["trace[575389445] 'agreement among raft nodes before linearized reading' (duration: 549.712977ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:56:29.530819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:56:28.980755Z","time spent":"550.054036ms","remote":"127.0.0.1:40816","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-06-28T19:56:29.530613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.060656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-28T19:56:29.530869Z","caller":"traceutil/trace.go:171","msg":"trace[1457546731] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:752; }","duration":"542.352063ms","start":"2026-06-28T19:56:28.988508Z","end":"2026-06-28T19:56:29.530861Z","steps":["trace[1457546731] 'agreement among raft nodes before linearized reading' (duration: 541.985783ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:56:29.530903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:56:28.988497Z","time spent":"542.399035ms","remote":"127.0.0.1:40816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-28T19:56:29.530954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:56:28.651905Z","time spent":"878.80741ms","remote":"127.0.0.1:40816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-28T19:56:29.531009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.222409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:56:29.531066Z","caller":"traceutil/trace.go:171","msg":"trace[1014518838] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:752; }","duration":"844.306091ms","start":"2026-06-28T19:56:28.686751Z","end":"2026-06-28T19:56:29.531057Z","steps":["trace[1014518838] 'agreement among raft nodes before linearized reading' (duration: 843.697995ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:56:29.531096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:56:28.686729Z","time spent":"844.361463ms","remote":"127.0.0.1:40554","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-06-28T19:56:29.727328Z","caller":"traceutil/trace.go:171","msg":"trace[814358886] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"110.504727ms","start":"2026-06-28T19:56:29.616796Z","end":"2026-06-28T19:56:29.727301Z","steps":["trace[814358886] 'process raft request' (duration: 110.131368ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:57:04.772398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.966865ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-28T19:57:04.772492Z","caller":"traceutil/trace.go:171","msg":"trace[378587260] linearizableReadLoop","detail":"{readStateIndex:1005; appliedIndex:1004; }","duration":"737.761414ms","start":"2026-06-28T19:57:04.034715Z","end":"2026-06-28T19:57:04.772476Z","steps":["trace[378587260] 'read index received' (duration: 389.603095ms)","trace[378587260] 'applied index is now lower than readState.Index' (duration: 348.157179ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:57:04.772565Z","caller":"traceutil/trace.go:171","msg":"trace[605413049] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"790.80082ms","start":"2026-06-28T19:57:03.981748Z","end":"2026-06-28T19:57:04.772549Z","steps":["trace[605413049] 'process raft request' (duration: 442.61186ms)","trace[605413049] 'compare' (duration: 347.76187ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:57:04.772637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:57:03.981736Z","time spent":"790.867311ms","remote":"127.0.0.1:40816","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-06-28T19:57:04.772658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.940108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-06-28T19:57:04.772694Z","caller":"traceutil/trace.go:171","msg":"trace[471530585] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:967; }","duration":"737.99873ms","start":"2026-06-28T19:57:04.034686Z","end":"2026-06-28T19:57:04.772685Z","steps":["trace[471530585] 'agreement among raft nodes before linearized reading' (duration: 737.877757ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:57:04.772727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:57:04.034668Z","time spent":"738.048251ms","remote":"127.0.0.1:40816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-28T19:57:04.772725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.441727ms","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-06-28T19:57:04.772807Z","caller":"traceutil/trace.go:171","msg":"trace[969720971] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:967; }","duration":"737.5427ms","start":"2026-06-28T19:57:04.035247Z","end":"2026-06-28T19:57:04.77279Z","steps":["trace[969720971] 'agreement among raft nodes before linearized reading' (duration: 737.357625ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:57:04.772864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:57:04.035241Z","time spent":"737.614481ms","remote":"127.0.0.1:40816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-28T19:57:05.716219Z","caller":"traceutil/trace.go:171","msg":"trace[1843851740] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"939.528945ms","start":"2026-06-28T19:57:04.776658Z","end":"2026-06-28T19:57:05.716187Z","steps":["trace[1843851740] 'process raft request' (duration: 938.868629ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:57:05.716352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:57:04.776647Z","time spent":"939.646698ms","remote":"127.0.0.1:40816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-28T19:57:05.718044Z","caller":"traceutil/trace.go:171","msg":"trace[1427253888] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"940.722044ms","start":"2026-06-28T19:57:04.777296Z","end":"2026-06-28T19:57:05.718018Z","steps":["trace[1427253888] 'process raft request' (duration: 940.54323ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:57:05.718072Z","caller":"traceutil/trace.go:171","msg":"trace[1794024475] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"876.674094ms","start":"2026-06-28T19:57:04.841372Z","end":"2026-06-28T19:57:05.718046Z","steps":["trace[1794024475] 'process raft request' (duration: 876.533561ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:57:05.718211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:57:04.777276Z","time spent":"940.847927ms","remote":"127.0.0.1:40816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-28T19:57:05.718226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:57:04.841356Z","time spent":"876.775717ms","remote":"127.0.0.1:40816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-28T19:57:27.516762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.743122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-28T19:57:27.516846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.805478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:57:27.516851Z","caller":"traceutil/trace.go:171","msg":"trace[1332190681] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1039; }","duration":"828.860495ms","start":"2026-06-28T19:57:26.68797Z","end":"2026-06-28T19:57:27.516831Z","steps":["trace[1332190681] 'range keys from in-memory index tree' (duration: 828.673611ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:57:27.516886Z","caller":"traceutil/trace.go:171","msg":"trace[173707744] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1039; }","duration":"184.32533ms","start":"2026-06-28T19:57:27.332546Z","end":"2026-06-28T19:57:27.516871Z","steps":["trace[173707744] 'count revisions from in-memory index tree' (duration: 183.666804ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:57:27.51692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:57:26.687954Z","time spent":"828.931177ms","remote":"127.0.0.1:40554","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-06-28T19:57:27.516762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.636138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-28T19:57:27.51712Z","caller":"traceutil/trace.go:171","msg":"trace[224101531] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1039; }","duration":"653.022478ms","start":"2026-06-28T19:57:26.864085Z","end":"2026-06-28T19:57:27.517108Z","steps":["trace[224101531] 'range keys from in-memory index tree' (duration: 652.479925ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:57:27.517166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:57:26.86407Z","time spent":"653.078209ms","remote":"127.0.0.1:40816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-28T19:57:27.81917Z","caller":"traceutil/trace.go:171","msg":"trace[1796075029] linearizableReadLoop","detail":"{readStateIndex:1082; appliedIndex:1081; }","duration":"289.050779ms","start":"2026-06-28T19:57:27.530094Z","end":"2026-06-28T19:57:27.819145Z","steps":["trace[1796075029] 'read index received' (duration: 288.844974ms)","trace[1796075029] 'applied index is now lower than readState.Index' (duration: 204.955µs)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:57:27.819399Z","caller":"traceutil/trace.go:171","msg":"trace[1835032150] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"297.554262ms","start":"2026-06-28T19:57:27.52183Z","end":"2026-06-28T19:57:27.819384Z","steps":["trace[1835032150] 'process raft request' (duration: 297.173563ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:57:27.819476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.274626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:57:27.819524Z","caller":"traceutil/trace.go:171","msg":"trace[529983125] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1040; }","duration":"131.362718ms","start":"2026-06-28T19:57:27.688148Z","end":"2026-06-28T19:57:27.819511Z","steps":["trace[529983125] 'agreement among raft nodes before linearized reading' (duration: 131.280256ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:57:27.819699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.596162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-28T19:57:27.819731Z","caller":"traceutil/trace.go:171","msg":"trace[2056584604] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1040; }","duration":"289.639672ms","start":"2026-06-28T19:57:27.530079Z","end":"2026-06-28T19:57:27.819719Z","steps":["trace[2056584604] 'agreement among raft nodes before linearized reading' (duration: 289.590221ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:57:30.021831Z","caller":"traceutil/trace.go:171","msg":"trace[785785249] linearizableReadLoop","detail":"{readStateIndex:1090; appliedIndex:1089; }","duration":"114.236356ms","start":"2026-06-28T19:57:29.907569Z","end":"2026-06-28T19:57:30.021806Z","steps":["trace[785785249] 'read index received' (duration: 90.632388ms)","trace[785785249] 'applied index is now lower than readState.Index' (duration: 23.602788ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:57:30.021938Z","caller":"traceutil/trace.go:171","msg":"trace[1567541428] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"123.444628ms","start":"2026-06-28T19:57:29.898462Z","end":"2026-06-28T19:57:30.021907Z","steps":["trace[1567541428] 'process raft request' (duration: 99.714857ms)","trace[1567541428] 'compare' (duration: 23.506955ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:57:30.022082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.473183ms","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-06-28T19:57:30.022126Z","caller":"traceutil/trace.go:171","msg":"trace[328415945] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1047; }","duration":"114.573374ms","start":"2026-06-28T19:57:29.907542Z","end":"2026-06-28T19:57:30.022116Z","steps":["trace[328415945] 'agreement among raft nodes before linearized reading' (duration: 114.39891ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:57:31.961501Z","caller":"traceutil/trace.go:171","msg":"trace[1851159523] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"119.299719ms","start":"2026-06-28T19:57:31.842174Z","end":"2026-06-28T19:57:31.961474Z","steps":["trace[1851159523] 'process raft request' (duration: 119.142505ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:57:32.195957Z","caller":"traceutil/trace.go:171","msg":"trace[1300749535] linearizableReadLoop","detail":"{readStateIndex:1096; appliedIndex:1095; }","duration":"118.822287ms","start":"2026-06-28T19:57:32.077116Z","end":"2026-06-28T19:57:32.195938Z","steps":["trace[1300749535] 'read index received' (duration: 118.633662ms)","trace[1300749535] 'applied index is now lower than readState.Index' (duration: 187.515µs)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:57:32.196074Z","caller":"traceutil/trace.go:171","msg":"trace[484094530] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"169.171037ms","start":"2026-06-28T19:57:32.026893Z","end":"2026-06-28T19:57:32.196064Z","steps":["trace[484094530] 'process raft request' (duration: 168.87269ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:57:32.196258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.136194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-28T19:57:32.196296Z","caller":"traceutil/trace.go:171","msg":"trace[1999672883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1053; }","duration":"119.225876ms","start":"2026-06-28T19:57:32.07706Z","end":"2026-06-28T19:57:32.196286Z","steps":["trace[1999672883] 'agreement among raft nodes before linearized reading' (duration: 119.111573ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:57:32.196733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.627276ms","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-06-28T19:57:32.19684Z","caller":"traceutil/trace.go:171","msg":"trace[443640993] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1053; }","duration":"104.928433ms","start":"2026-06-28T19:57:32.091892Z","end":"2026-06-28T19:57:32.196821Z","steps":["trace[443640993] 'agreement among raft nodes before linearized reading' (duration: 104.4023ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:57:32.337203Z","caller":"traceutil/trace.go:171","msg":"trace[828631666] linearizableReadLoop","detail":"{readStateIndex:1099; appliedIndex:1096; }","duration":"132.218988ms","start":"2026-06-28T19:57:32.204962Z","end":"2026-06-28T19:57:32.337181Z","steps":["trace[828631666] 'read index received' (duration: 14.59884ms)","trace[828631666] 'applied index is now lower than readState.Index' (duration: 117.618908ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:57:32.337403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.229556ms","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-06-28T19:57:32.337441Z","caller":"traceutil/trace.go:171","msg":"trace[132443526] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1056; }","duration":"124.285627ms","start":"2026-06-28T19:57:32.213147Z","end":"2026-06-28T19:57:32.337432Z","steps":["trace[132443526] 'agreement among raft nodes before linearized reading' (duration: 124.155884ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:57:32.337396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.416572ms","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-06-28T19:57:32.337495Z","caller":"traceutil/trace.go:171","msg":"trace[1267269431] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1056; }","duration":"132.533656ms","start":"2026-06-28T19:57:32.20495Z","end":"2026-06-28T19:57:32.337483Z","steps":["trace[1267269431] 'agreement among raft nodes before linearized reading' (duration: 132.350662ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:57:32.337268Z","caller":"traceutil/trace.go:171","msg":"trace[1637813526] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"136.013069ms","start":"2026-06-28T19:57:32.201234Z","end":"2026-06-28T19:57:32.337247Z","steps":["trace[1637813526] 'process raft request' (duration: 135.832064ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:57:45.920284Z","caller":"traceutil/trace.go:171","msg":"trace[1380340730] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"138.256224ms","start":"2026-06-28T19:57:45.782Z","end":"2026-06-28T19:57:45.920256Z","steps":["trace[1380340730] 'process raft request' (duration: 116.537682ms)","trace[1380340730] 'compare' (duration: 21.589059ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:57:48.808492Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836961493575001597,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-28T19:57:49.309579Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836961493575001597,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-28T19:57:49.580226Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.323682449s","expected-duration":"1s"} {"level":"info","ts":"2026-06-28T19:57:49.580715Z","caller":"traceutil/trace.go:171","msg":"trace[2044805272] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"1.324221352s","start":"2026-06-28T19:57:48.25646Z","end":"2026-06-28T19:57:49.580681Z","steps":["trace[2044805272] 'process raft request' (duration: 1.324046778s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:57:49.580883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:57:48.256442Z","time spent":"1.324361075s","remote":"127.0.0.1:40816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-28T19:57:49.58905Z","caller":"traceutil/trace.go:171","msg":"trace[1205714622] linearizableReadLoop","detail":"{readStateIndex:1209; appliedIndex:1207; }","duration":"1.281169058s","start":"2026-06-28T19:57:48.30786Z","end":"2026-06-28T19:57:49.589029Z","steps":["trace[1205714622] 'read index received' (duration: 1.272646923s)","trace[1205714622] 'applied index is now lower than readState.Index' (duration: 8.520975ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:57:49.589185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.281316561s","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-06-28T19:57:49.589212Z","caller":"traceutil/trace.go:171","msg":"trace[1228690094] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1159; }","duration":"1.281349572s","start":"2026-06-28T19:57:48.307854Z","end":"2026-06-28T19:57:49.589204Z","steps":["trace[1228690094] 'agreement among raft nodes before linearized reading' (duration: 1.281243139s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:57:49.589225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.252640752s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-28T19:57:49.589232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:57:48.307805Z","time spent":"1.281422763s","remote":"127.0.0.1:40816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-28T19:57:49.589255Z","caller":"traceutil/trace.go:171","msg":"trace[1029446198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1159; }","duration":"1.252702363s","start":"2026-06-28T19:57:48.336544Z","end":"2026-06-28T19:57:49.589247Z","steps":["trace[1029446198] 'agreement among raft nodes before linearized reading' (duration: 1.25259972s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:57:49.589285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:57:48.33653Z","time spent":"1.252745944s","remote":"127.0.0.1:40816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-28T19:57:49.589406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.324923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-28T19:57:49.589424Z","caller":"traceutil/trace.go:171","msg":"trace[171242432] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1159; }","duration":"239.346594ms","start":"2026-06-28T19:57:49.350072Z","end":"2026-06-28T19:57:49.589419Z","steps":["trace[171242432] 'agreement among raft nodes before linearized reading' (duration: 239.308913ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:57:49.589533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.089233164s","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-06-28T19:57:49.58959Z","caller":"traceutil/trace.go:171","msg":"trace[1085687637] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1159; }","duration":"1.089296515s","start":"2026-06-28T19:57:48.500262Z","end":"2026-06-28T19:57:49.589559Z","steps":["trace[1085687637] 'agreement among raft nodes before linearized reading' (duration: 1.089202483s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:57:49.589632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:57:48.500248Z","time spent":"1.089373878s","remote":"127.0.0.1:40816","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-06-28T19:57:49.589605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.13650464s","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-06-28T19:57:49.589663Z","caller":"traceutil/trace.go:171","msg":"trace[860229274] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1159; }","duration":"1.136597433s","start":"2026-06-28T19:57:48.45306Z","end":"2026-06-28T19:57:49.589657Z","steps":["trace[860229274] 'agreement among raft nodes before linearized reading' (duration: 1.13650679s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:57:49.589684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:57:48.453041Z","time spent":"1.136634794s","remote":"127.0.0.1:40816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-28T19:58:01.841403Z","caller":"traceutil/trace.go:171","msg":"trace[222414907] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"111.685765ms","start":"2026-06-28T19:58:01.729694Z","end":"2026-06-28T19:58:01.84138Z","steps":["trace[222414907] 'process raft request' (duration: 111.624573ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:58:01.841858Z","caller":"traceutil/trace.go:171","msg":"trace[493607885] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"111.756116ms","start":"2026-06-28T19:58:01.729641Z","end":"2026-06-28T19:58:01.841397Z","steps":["trace[493607885] 'process raft request' (duration: 107.533175ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:58:06.435274Z","caller":"traceutil/trace.go:171","msg":"trace[1992239189] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"315.828199ms","start":"2026-06-28T19:58:06.119431Z","end":"2026-06-28T19:58:06.435259Z","steps":["trace[1992239189] 'process raft request' (duration: 315.44126ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:06.435362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:06.119414Z","time spent":"315.902521ms","remote":"127.0.0.1:40816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-28T19:58:06.835602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.995648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:58:06.835688Z","caller":"traceutil/trace.go:171","msg":"trace[84120925] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1237; }","duration":"147.136201ms","start":"2026-06-28T19:58:06.688532Z","end":"2026-06-28T19:58:06.835668Z","steps":["trace[84120925] 'range keys from in-memory index tree' (duration: 146.888645ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:06.835715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.261038ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:58:06.835859Z","caller":"traceutil/trace.go:171","msg":"trace[1441673104] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1237; }","duration":"343.428042ms","start":"2026-06-28T19:58:06.492411Z","end":"2026-06-28T19:58:06.835839Z","steps":["trace[1441673104] 'range keys from in-memory index tree' (duration: 343.240087ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:58:07.257724Z","caller":"traceutil/trace.go:171","msg":"trace[773578678] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"163.021183ms","start":"2026-06-28T19:58:07.094684Z","end":"2026-06-28T19:58:07.257706Z","steps":["trace[773578678] 'process raft request' (duration: 162.895979ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:08.314416Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836961493575001780,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-28T19:58:08.428307Z","caller":"traceutil/trace.go:171","msg":"trace[1373079464] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"669.62046ms","start":"2026-06-28T19:58:07.758663Z","end":"2026-06-28T19:58:08.428283Z","steps":["trace[1373079464] 'process raft request' (duration: 669.484827ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:08.42844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:07.758647Z","time spent":"669.725972ms","remote":"127.0.0.1:40816","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-06-28T19:58:08.579376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.21597ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-28T19:58:08.579481Z","caller":"traceutil/trace.go:171","msg":"trace[2071245109] linearizableReadLoop","detail":"{readStateIndex:1295; appliedIndex:1293; }","duration":"766.035283ms","start":"2026-06-28T19:58:07.813427Z","end":"2026-06-28T19:58:08.579462Z","steps":["trace[2071245109] 'read index received' (duration: 614.65865ms)","trace[2071245109] 'applied index is now lower than readState.Index' (duration: 151.375203ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:58:08.579829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.388491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-06-28T19:58:08.579827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.383383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-28T19:58:08.57983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.36585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-28T19:58:08.579831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.669867ms","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-06-28T19:58:08.579869Z","caller":"traceutil/trace.go:171","msg":"trace[2123548825] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1241; }","duration":"766.438562ms","start":"2026-06-28T19:58:07.81342Z","end":"2026-06-28T19:58:08.579859Z","steps":["trace[2123548825] 'agreement among raft nodes before linearized reading' (duration: 766.139715ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:58:08.579888Z","caller":"traceutil/trace.go:171","msg":"trace[663061806] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1241; }","duration":"141.486164ms","start":"2026-06-28T19:58:08.438387Z","end":"2026-06-28T19:58:08.579873Z","steps":["trace[663061806] 'agreement among raft nodes before linearized reading' (duration: 141.29605ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:58:08.579895Z","caller":"traceutil/trace.go:171","msg":"trace[1810190040] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1241; }","duration":"691.437893ms","start":"2026-06-28T19:58:07.888442Z","end":"2026-06-28T19:58:08.57988Z","steps":["trace[1810190040] 'agreement among raft nodes before linearized reading' (duration: 691.267249ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:08.579912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:07.813359Z","time spent":"766.545256ms","remote":"127.0.0.1:40816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-28T19:58:08.579918Z","caller":"traceutil/trace.go:171","msg":"trace[528276105] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1241; }","duration":"703.80119ms","start":"2026-06-28T19:58:07.876095Z","end":"2026-06-28T19:58:08.579896Z","steps":["trace[528276105] 'agreement among raft nodes before linearized reading' (duration: 703.527663ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:08.579931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:07.888413Z","time spent":"691.510675ms","remote":"127.0.0.1:40816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-28T19:58:08.579965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:07.876076Z","time spent":"703.881282ms","remote":"127.0.0.1:40816","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-06-28T19:58:08.831166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.446389ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-28T19:58:08.831381Z","caller":"traceutil/trace.go:171","msg":"trace[595166240] linearizableReadLoop","detail":"{readStateIndex:1299; appliedIndex:1296; }","duration":"145.718497ms","start":"2026-06-28T19:58:08.685647Z","end":"2026-06-28T19:58:08.831366Z","steps":["trace[595166240] 'read index received' (duration: 39.98479ms)","trace[595166240] 'applied index is now lower than readState.Index' (duration: 105.732887ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:58:08.831371Z","caller":"traceutil/trace.go:171","msg":"trace[614338392] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"246.168737ms","start":"2026-06-28T19:58:08.585173Z","end":"2026-06-28T19:58:08.831341Z","steps":["trace[614338392] 'process raft request' (duration: 140.476622ms)","trace[614338392] 'compare' (duration: 105.331277ms)"],"step_count":2} {"level":"warn","ts":"2026-06-28T19:58:08.831652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.993303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:58:08.831707Z","caller":"traceutil/trace.go:171","msg":"trace[1485105494] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"244.303583ms","start":"2026-06-28T19:58:08.587391Z","end":"2026-06-28T19:58:08.831694Z","steps":["trace[1485105494] 'process raft request' (duration: 243.943924ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:58:08.831725Z","caller":"traceutil/trace.go:171","msg":"trace[665790821] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1245; }","duration":"146.101366ms","start":"2026-06-28T19:58:08.685606Z","end":"2026-06-28T19:58:08.831708Z","steps":["trace[665790821] 'agreement among raft nodes before linearized reading' (duration: 145.982473ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:58:08.831756Z","caller":"traceutil/trace.go:171","msg":"trace[1920024245] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"244.792964ms","start":"2026-06-28T19:58:08.586923Z","end":"2026-06-28T19:58:08.831716Z","steps":["trace[1920024245] 'process raft request' (duration: 244.376694ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:58:09.559908Z","caller":"traceutil/trace.go:171","msg":"trace[100321756] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"119.63438ms","start":"2026-06-28T19:58:09.440252Z","end":"2026-06-28T19:58:09.559887Z","steps":["trace[100321756] 'process raft request' (duration: 119.462576ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:09.841705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.780447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:58:09.841778Z","caller":"traceutil/trace.go:171","msg":"trace[1116416330] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1246; }","duration":"152.90767ms","start":"2026-06-28T19:58:09.68885Z","end":"2026-06-28T19:58:09.841758Z","steps":["trace[1116416330] 'range keys from in-memory index tree' (duration: 152.673734ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T19:58:09.841955Z","caller":"traceutil/trace.go:171","msg":"trace[1171159404] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"109.1803ms","start":"2026-06-28T19:58:09.732746Z","end":"2026-06-28T19:58:09.841927Z","steps":["trace[1171159404] 'process raft request' (duration: 50.290236ms)","trace[1171159404] 'compare' (duration: 58.74217ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:58:17.941714Z","caller":"traceutil/trace.go:171","msg":"trace[1461989564] linearizableReadLoop","detail":"{readStateIndex:1369; appliedIndex:1368; }","duration":"253.022311ms","start":"2026-06-28T19:58:17.688666Z","end":"2026-06-28T19:58:17.941688Z","steps":["trace[1461989564] 'read index received' (duration: 252.890878ms)","trace[1461989564] 'applied index is now lower than readState.Index' (duration: 130.233µs)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:58:17.94175Z","caller":"traceutil/trace.go:171","msg":"trace[1154912645] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"275.310637ms","start":"2026-06-28T19:58:17.666414Z","end":"2026-06-28T19:58:17.941725Z","steps":["trace[1154912645] 'process raft request' (duration: 275.133313ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:17.941847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.161835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:58:17.941921Z","caller":"traceutil/trace.go:171","msg":"trace[2049246313] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1314; }","duration":"253.245277ms","start":"2026-06-28T19:58:17.688653Z","end":"2026-06-28T19:58:17.941898Z","steps":["trace[2049246313] 'agreement among raft nodes before linearized reading' (duration: 253.112604ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:18.443273Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836961493575001922,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-28T19:58:19.359609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"906.391591ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-28T19:58:19.36005Z","caller":"traceutil/trace.go:171","msg":"trace[345797486] linearizableReadLoop","detail":"{readStateIndex:1371; appliedIndex:1369; }","duration":"1.416949124s","start":"2026-06-28T19:58:17.943077Z","end":"2026-06-28T19:58:19.360026Z","steps":["trace[345797486] 'read index received' (duration: 510.103122ms)","trace[345797486] 'applied index is now lower than readState.Index' (duration: 906.844322ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T19:58:19.360301Z","caller":"traceutil/trace.go:171","msg":"trace[1931273043] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"1.49425492s","start":"2026-06-28T19:58:17.865952Z","end":"2026-06-28T19:58:19.360207Z","steps":["trace[1931273043] 'process raft request' (duration: 1.493771528s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:19.360433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.132289283s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:58:19.360551Z","caller":"traceutil/trace.go:171","msg":"trace[887885880] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1315; }","duration":"1.132466047s","start":"2026-06-28T19:58:18.228057Z","end":"2026-06-28T19:58:19.360523Z","steps":["trace[887885880] 'agreement among raft nodes before linearized reading' (duration: 1.132282823s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:19.360522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:17.865898Z","time spent":"1.494455735s","remote":"127.0.0.1:40816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-28T19:58:19.360612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:18.228036Z","time spent":"1.132563829s","remote":"127.0.0.1:32876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-28T19:58:19.360852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.922794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-28T19:58:19.360878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.42748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-28T19:58:19.360878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.417801185s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:58:19.360913Z","caller":"traceutil/trace.go:171","msg":"trace[19142798] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1315; }","duration":"488.996796ms","start":"2026-06-28T19:58:18.8719Z","end":"2026-06-28T19:58:19.360897Z","steps":["trace[19142798] 'agreement among raft nodes before linearized reading' (duration: 488.821852ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:19.360899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.899078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"warn","ts":"2026-06-28T19:58:19.360981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:18.871893Z","time spent":"489.080128ms","remote":"127.0.0.1:40816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-28T19:58:19.360975Z","caller":"traceutil/trace.go:171","msg":"trace[1437858273] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1315; }","duration":"1.417933918s","start":"2026-06-28T19:58:17.942993Z","end":"2026-06-28T19:58:19.360927Z","steps":["trace[1437858273] 'agreement among raft nodes before linearized reading' (duration: 1.417639181s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:19.360901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.289958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-28T19:58:19.361055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:17.942976Z","time spent":"1.418069622s","remote":"127.0.0.1:40540","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-06-28T19:58:19.361179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.513401ms","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-06-28T19:58:19.361048Z","caller":"traceutil/trace.go:171","msg":"trace[1185202117] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1315; }","duration":"445.216735ms","start":"2026-06-28T19:58:18.915794Z","end":"2026-06-28T19:58:19.361011Z","steps":["trace[1185202117] 'agreement among raft nodes before linearized reading' (duration: 444.799285ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:19.361197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.102547799s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T19:58:19.361239Z","caller":"traceutil/trace.go:171","msg":"trace[1066757907] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1315; }","duration":"660.611914ms","start":"2026-06-28T19:58:18.700613Z","end":"2026-06-28T19:58:19.361225Z","steps":["trace[1066757907] 'agreement among raft nodes before linearized reading' (duration: 660.388479ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:19.361264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:18.915771Z","time spent":"445.4774ms","remote":"127.0.0.1:40816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-28T19:58:19.361284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:18.700592Z","time spent":"660.683436ms","remote":"127.0.0.1:40816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-28T19:58:19.361291Z","caller":"traceutil/trace.go:171","msg":"trace[1876833380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1315; }","duration":"488.733658ms","start":"2026-06-28T19:58:18.872504Z","end":"2026-06-28T19:58:19.361237Z","steps":["trace[1876833380] 'agreement among raft nodes before linearized reading' (duration: 488.188745ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:19.361392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"890.819958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-28T19:58:19.361435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.106815341s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-06-28T19:58:19.361442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:18.872482Z","time spent":"488.942014ms","remote":"127.0.0.1:40816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-28T19:58:19.361267Z","caller":"traceutil/trace.go:171","msg":"trace[1185976315] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1315; }","duration":"1.102656282s","start":"2026-06-28T19:58:18.258592Z","end":"2026-06-28T19:58:19.361249Z","steps":["trace[1185976315] 'agreement among raft nodes before linearized reading' (duration: 1.102517209s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:19.361584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:18.258581Z","time spent":"1.10298949s","remote":"127.0.0.1:40740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"info","ts":"2026-06-28T19:58:19.361451Z","caller":"traceutil/trace.go:171","msg":"trace[494505183] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1315; }","duration":"890.914281ms","start":"2026-06-28T19:58:18.470523Z","end":"2026-06-28T19:58:19.361437Z","steps":["trace[494505183] 'agreement among raft nodes before linearized reading' (duration: 890.770427ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:19.361713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:18.470503Z","time spent":"891.197387ms","remote":"127.0.0.1:40816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-28T19:58:19.360924Z","caller":"traceutil/trace.go:171","msg":"trace[1524788462] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1315; }","duration":"620.500011ms","start":"2026-06-28T19:58:18.740406Z","end":"2026-06-28T19:58:19.360906Z","steps":["trace[1524788462] 'agreement among raft nodes before linearized reading' (duration: 620.43819ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:19.361824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:18.74039Z","time spent":"621.422824ms","remote":"127.0.0.1:40842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"info","ts":"2026-06-28T19:58:19.361476Z","caller":"traceutil/trace.go:171","msg":"trace[451984072] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1315; }","duration":"1.106890123s","start":"2026-06-28T19:58:18.254574Z","end":"2026-06-28T19:58:19.361464Z","steps":["trace[451984072] 'agreement among raft nodes before linearized reading' (duration: 1.106773161s)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:19.361941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:18.254562Z","time spent":"1.107362884s","remote":"127.0.0.1:40816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-06-28T19:58:19.361182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.500588ms","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-06-28T19:58:19.362266Z","caller":"traceutil/trace.go:171","msg":"trace[634306465] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1315; }","duration":"490.965023ms","start":"2026-06-28T19:58:18.871278Z","end":"2026-06-28T19:58:19.362243Z","steps":["trace[634306465] 'agreement among raft nodes before linearized reading' (duration: 489.289523ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T19:58:19.362326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T19:58:18.871262Z","time spent":"491.045825ms","remote":"127.0.0.1:40816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-28T20:00:07.029842Z","caller":"traceutil/trace.go:171","msg":"trace[1021563597] transaction","detail":"{read_only:false; response_revision:1812; number_of_response:1; }","duration":"103.69718ms","start":"2026-06-28T20:00:06.92612Z","end":"2026-06-28T20:00:07.029817Z","steps":["trace[1021563597] 'process raft request' (duration: 103.511106ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T20:00:41.98735Z","caller":"traceutil/trace.go:171","msg":"trace[1044649444] transaction","detail":"{read_only:false; response_revision:1971; number_of_response:1; }","duration":"145.529344ms","start":"2026-06-28T20:00:41.841799Z","end":"2026-06-28T20:00:41.987328Z","steps":["trace[1044649444] 'process raft request' (duration: 145.36478ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T20:01:14.831161Z","caller":"traceutil/trace.go:171","msg":"trace[1153664622] transaction","detail":"{read_only:false; response_revision:2125; number_of_response:1; }","duration":"107.652677ms","start":"2026-06-28T20:01:14.723482Z","end":"2026-06-28T20:01:14.831135Z","steps":["trace[1153664622] 'process raft request' (duration: 107.512203ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:02:00.794737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.714804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T20:02:00.795374Z","caller":"traceutil/trace.go:171","msg":"trace[2103318570] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2363; }","duration":"107.40221ms","start":"2026-06-28T20:02:00.687952Z","end":"2026-06-28T20:02:00.795354Z","steps":["trace[2103318570] 'range keys from in-memory index tree' (duration: 106.610362ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:02:05.93475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.021593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T20:02:05.934877Z","caller":"traceutil/trace.go:171","msg":"trace[209269239] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:2390; }","duration":"195.180596ms","start":"2026-06-28T20:02:05.739642Z","end":"2026-06-28T20:02:05.934823Z","steps":["trace[209269239] 'count revisions from in-memory index tree' (duration: 194.9336ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:02:05.935121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.639151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050252"} {"level":"info","ts":"2026-06-28T20:02:05.935573Z","caller":"traceutil/trace.go:171","msg":"trace[227994685] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:2390; }","duration":"283.825855ms","start":"2026-06-28T20:02:05.651416Z","end":"2026-06-28T20:02:05.935242Z","steps":["trace[227994685] 'range keys from in-memory index tree' (duration: 283.165159ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T20:02:06.670938Z","caller":"traceutil/trace.go:171","msg":"trace[197680007] linearizableReadLoop","detail":"{readStateIndex:2494; appliedIndex:2493; }","duration":"273.03893ms","start":"2026-06-28T20:02:06.397876Z","end":"2026-06-28T20:02:06.670915Z","steps":["trace[197680007] 'read index received' (duration: 272.783814ms)","trace[197680007] 'applied index is now lower than readState.Index' (duration: 254.186µs)"],"step_count":2} {"level":"info","ts":"2026-06-28T20:02:06.671073Z","caller":"traceutil/trace.go:171","msg":"trace[408135935] transaction","detail":"{read_only:false; response_revision:2391; number_of_response:1; }","duration":"297.911606ms","start":"2026-06-28T20:02:06.373129Z","end":"2026-06-28T20:02:06.671041Z","steps":["trace[408135935] 'process raft request' (duration: 297.62654ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:02:06.671131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.240735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-public/\" range_end:\"/registry/secrets/kube-public0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T20:02:06.671269Z","caller":"traceutil/trace.go:171","msg":"trace[631886888] range","detail":"{range_begin:/registry/secrets/kube-public/; range_end:/registry/secrets/kube-public0; response_count:0; response_revision:2391; }","duration":"273.418899ms","start":"2026-06-28T20:02:06.397834Z","end":"2026-06-28T20:02:06.671253Z","steps":["trace[631886888] 'agreement among raft nodes before linearized reading' (duration: 273.248455ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:02:06.671395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.970369ms","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-06-28T20:02:06.671464Z","caller":"traceutil/trace.go:171","msg":"trace[1787601543] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2391; }","duration":"149.084472ms","start":"2026-06-28T20:02:06.522362Z","end":"2026-06-28T20:02:06.671446Z","steps":["trace[1787601543] 'agreement among raft nodes before linearized reading' (duration: 148.867697ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:02:06.671602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.378919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-06-28T20:02:06.671705Z","caller":"traceutil/trace.go:171","msg":"trace[767034654] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2391; }","duration":"138.521163ms","start":"2026-06-28T20:02:06.533162Z","end":"2026-06-28T20:02:06.671683Z","steps":["trace[767034654] 'agreement among raft nodes before linearized reading' (duration: 138.367809ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T20:02:06.905385Z","caller":"traceutil/trace.go:171","msg":"trace[188694857] transaction","detail":"{read_only:false; response_revision:2393; number_of_response:1; }","duration":"229.132538ms","start":"2026-06-28T20:02:06.676227Z","end":"2026-06-28T20:02:06.905359Z","steps":["trace[188694857] 'process raft request' (duration: 223.972036ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T20:02:06.905405Z","caller":"traceutil/trace.go:171","msg":"trace[1277630595] transaction","detail":"{read_only:false; response_revision:2395; number_of_response:1; }","duration":"229.070316ms","start":"2026-06-28T20:02:06.676317Z","end":"2026-06-28T20:02:06.905387Z","steps":["trace[1277630595] 'process raft request' (duration: 229.016935ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T20:02:06.905526Z","caller":"traceutil/trace.go:171","msg":"trace[2143128860] transaction","detail":"{read_only:false; response_revision:2394; number_of_response:1; }","duration":"229.279151ms","start":"2026-06-28T20:02:06.676241Z","end":"2026-06-28T20:02:06.90552Z","steps":["trace[2143128860] 'process raft request' (duration: 229.032975ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T20:02:06.905547Z","caller":"traceutil/trace.go:171","msg":"trace[1982288989] linearizableReadLoop","detail":"{readStateIndex:2501; appliedIndex:2494; }","duration":"219.06366ms","start":"2026-06-28T20:02:06.686466Z","end":"2026-06-28T20:02:06.90553Z","steps":["trace[1982288989] 'read index received' (duration: 12.507684ms)","trace[1982288989] 'applied index is now lower than readState.Index' (duration: 206.555046ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T20:02:06.905593Z","caller":"traceutil/trace.go:171","msg":"trace[1036807573] transaction","detail":"{read_only:false; response_revision:2396; number_of_response:1; }","duration":"228.736898ms","start":"2026-06-28T20:02:06.676847Z","end":"2026-06-28T20:02:06.905584Z","steps":["trace[1036807573] 'process raft request' (duration: 228.516063ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T20:02:06.905636Z","caller":"traceutil/trace.go:171","msg":"trace[201345900] transaction","detail":"{read_only:false; response_revision:2397; number_of_response:1; }","duration":"228.677657ms","start":"2026-06-28T20:02:06.676948Z","end":"2026-06-28T20:02:06.905625Z","steps":["trace[201345900] 'process raft request' (duration: 228.506763ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T20:02:06.905723Z","caller":"traceutil/trace.go:171","msg":"trace[1920211235] transaction","detail":"{read_only:false; response_revision:2398; number_of_response:1; }","duration":"228.460072ms","start":"2026-06-28T20:02:06.677255Z","end":"2026-06-28T20:02:06.905715Z","steps":["trace[1920211235] 'process raft request' (duration: 228.243027ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:02:06.905854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.373457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T20:02:06.905884Z","caller":"traceutil/trace.go:171","msg":"trace[791145024] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2398; }","duration":"219.436229ms","start":"2026-06-28T20:02:06.686439Z","end":"2026-06-28T20:02:06.905876Z","steps":["trace[791145024] 'agreement among raft nodes before linearized reading' (duration: 219.343897ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:02:06.906107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.247587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:363682"} {"level":"info","ts":"2026-06-28T20:02:06.90615Z","caller":"traceutil/trace.go:171","msg":"trace[1742058528] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:2398; }","duration":"189.33666ms","start":"2026-06-28T20:02:06.716799Z","end":"2026-06-28T20:02:06.906136Z","steps":["trace[1742058528] 'agreement among raft nodes before linearized reading' (duration: 189.071614ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:02:07.369384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.654846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:363682"} {"level":"info","ts":"2026-06-28T20:02:07.36961Z","caller":"traceutil/trace.go:171","msg":"trace[1174639966] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:2398; }","duration":"352.769378ms","start":"2026-06-28T20:02:07.016662Z","end":"2026-06-28T20:02:07.369431Z","steps":["trace[1174639966] 'range keys from in-memory index tree' (duration: 352.329368ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:02:07.369655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:02:07.016638Z","time spent":"353.009284ms","remote":"127.0.0.1:40634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":6,"response size":363705,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"warn","ts":"2026-06-28T20:02:10.888926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.613236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:nodeclient\" ","response":"range_response_count:1 size:706"} {"level":"info","ts":"2026-06-28T20:02:10.889026Z","caller":"traceutil/trace.go:171","msg":"trace[1759446644] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:nodeclient; range_end:; response_count:1; response_revision:2409; }","duration":"242.746299ms","start":"2026-06-28T20:02:10.646255Z","end":"2026-06-28T20:02:10.889001Z","steps":["trace[1759446644] 'range keys from in-memory index tree' (duration: 242.530964ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T20:02:10.889193Z","caller":"traceutil/trace.go:171","msg":"trace[779898951] linearizableReadLoop","detail":"{readStateIndex:2514; appliedIndex:2513; }","duration":"200.695497ms","start":"2026-06-28T20:02:10.688473Z","end":"2026-06-28T20:02:10.889168Z","steps":["trace[779898951] 'read index received' (duration: 192.064844ms)","trace[779898951] 'applied index is now lower than readState.Index' (duration: 8.629583ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T20:02:10.889209Z","caller":"traceutil/trace.go:171","msg":"trace[1967650558] transaction","detail":"{read_only:false; response_revision:2410; number_of_response:1; }","duration":"205.242755ms","start":"2026-06-28T20:02:10.683934Z","end":"2026-06-28T20:02:10.889177Z","steps":["trace[1967650558] 'process raft request' (duration: 196.609541ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:02:10.889326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.817001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-28T20:02:10.889414Z","caller":"traceutil/trace.go:171","msg":"trace[1376552748] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2410; }","duration":"200.948684ms","start":"2026-06-28T20:02:10.688446Z","end":"2026-06-28T20:02:10.889394Z","steps":["trace[1376552748] 'agreement among raft nodes before linearized reading' (duration: 200.78394ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:02:10.889444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.425772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-28T20:02:10.889487Z","caller":"traceutil/trace.go:171","msg":"trace[458527842] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2410; }","duration":"175.510325ms","start":"2026-06-28T20:02:10.713966Z","end":"2026-06-28T20:02:10.889476Z","steps":["trace[458527842] 'agreement among raft nodes before linearized reading' (duration: 175.356341ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:02:10.889549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.489218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:selfnodeclient\" ","response":"range_response_count:1 size:718"} {"level":"info","ts":"2026-06-28T20:02:10.8896Z","caller":"traceutil/trace.go:171","msg":"trace[1177250551] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:selfnodeclient; range_end:; response_count:1; response_revision:2409; }","duration":"255.58898ms","start":"2026-06-28T20:02:10.634Z","end":"2026-06-28T20:02:10.889589Z","steps":["trace[1177250551] 'range keys from in-memory index tree' (duration: 255.007847ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T20:02:11.171622Z","caller":"traceutil/trace.go:171","msg":"trace[1319180005] transaction","detail":"{read_only:false; response_revision:2412; number_of_response:1; }","duration":"249.006926ms","start":"2026-06-28T20:02:10.922585Z","end":"2026-06-28T20:02:11.171592Z","steps":["trace[1319180005] 'process raft request' (duration: 217.264458ms)","trace[1319180005] 'compare' (duration: 31.42501ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T20:02:11.171841Z","caller":"traceutil/trace.go:171","msg":"trace[1585704364] transaction","detail":"{read_only:false; response_revision:2414; number_of_response:1; }","duration":"247.264434ms","start":"2026-06-28T20:02:10.924557Z","end":"2026-06-28T20:02:11.171821Z","steps":["trace[1585704364] 'process raft request' (duration: 246.962557ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T20:02:11.171859Z","caller":"traceutil/trace.go:171","msg":"trace[534024306] transaction","detail":"{read_only:false; response_revision:2413; number_of_response:1; }","duration":"248.957174ms","start":"2026-06-28T20:02:10.922608Z","end":"2026-06-28T20:02:11.171565Z","steps":["trace[534024306] 'process raft request' (duration: 248.850832ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T20:02:11.171965Z","caller":"traceutil/trace.go:171","msg":"trace[609122546] linearizableReadLoop","detail":"{readStateIndex:2517; appliedIndex:2515; }","duration":"249.258581ms","start":"2026-06-28T20:02:10.922689Z","end":"2026-06-28T20:02:11.171948Z","steps":["trace[609122546] 'read index received' (duration: 217.112883ms)","trace[609122546] 'applied index is now lower than readState.Index' (duration: 32.144698ms)"],"step_count":2} {"level":"info","ts":"2026-06-28T20:02:11.17207Z","caller":"traceutil/trace.go:171","msg":"trace[1589723379] transaction","detail":"{read_only:false; response_revision:2415; number_of_response:1; }","duration":"246.928827ms","start":"2026-06-28T20:02:10.925128Z","end":"2026-06-28T20:02:11.172057Z","steps":["trace[1589723379] 'process raft request' (duration: 246.616529ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:02:11.172166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.658052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:selfnodeclient\" ","response":"range_response_count:1 size:718"} {"level":"info","ts":"2026-06-28T20:02:11.172241Z","caller":"traceutil/trace.go:171","msg":"trace[1491747736] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:selfnodeclient; range_end:; response_count:1; response_revision:2415; }","duration":"196.761214ms","start":"2026-06-28T20:02:10.975459Z","end":"2026-06-28T20:02:11.172221Z","steps":["trace[1491747736] 'agreement among raft nodes before linearized reading' (duration: 196.637071ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:02:11.172436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.722202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-06-28T20:02:11.172489Z","caller":"traceutil/trace.go:171","msg":"trace[1492572983] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2415; }","duration":"249.797194ms","start":"2026-06-28T20:02:10.922677Z","end":"2026-06-28T20:02:11.172474Z","steps":["trace[1492572983] 'agreement among raft nodes before linearized reading' (duration: 249.640321ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:02:11.172669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.365877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-approver\" ","response":"range_response_count:1 size:700"} {"level":"info","ts":"2026-06-28T20:02:11.172738Z","caller":"traceutil/trace.go:171","msg":"trace[1042793830] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-approver; range_end:; response_count:1; response_revision:2415; }","duration":"194.437459ms","start":"2026-06-28T20:02:10.978281Z","end":"2026-06-28T20:02:11.172719Z","steps":["trace[1042793830] 'agreement among raft nodes before linearized reading' (duration: 194.130962ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T20:02:12.147009Z","caller":"traceutil/trace.go:171","msg":"trace[198485281] linearizableReadLoop","detail":"{readStateIndex:2522; appliedIndex:2521; }","duration":"233.447459ms","start":"2026-06-28T20:02:11.913543Z","end":"2026-06-28T20:02:12.146991Z","steps":["trace[198485281] 'read index received' (duration: 233.351927ms)","trace[198485281] 'applied index is now lower than readState.Index' (duration: 94.642µs)"],"step_count":2} {"level":"info","ts":"2026-06-28T20:02:12.147104Z","caller":"traceutil/trace.go:171","msg":"trace[1402225208] transaction","detail":"{read_only:false; response_revision:2418; number_of_response:1; }","duration":"235.420046ms","start":"2026-06-28T20:02:11.911674Z","end":"2026-06-28T20:02:12.147094Z","steps":["trace[1402225208] 'process raft request' (duration: 235.197991ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:02:12.147181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.614003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:cronjob-controller\" ","response":"range_response_count:1 size:835"} {"level":"info","ts":"2026-06-28T20:02:12.147268Z","caller":"traceutil/trace.go:171","msg":"trace[920853325] range","detail":"{range_begin:/registry/clusterroles/system:controller:cronjob-controller; range_end:; response_count:1; response_revision:2418; }","duration":"233.764597ms","start":"2026-06-28T20:02:11.913482Z","end":"2026-06-28T20:02:12.147246Z","steps":["trace[920853325] 'agreement among raft nodes before linearized reading' (duration: 233.617893ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:02:12.180471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.451439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:daemon-set-controller\" ","response":"range_response_count:1 size:958"} {"level":"info","ts":"2026-06-28T20:02:12.180523Z","caller":"traceutil/trace.go:171","msg":"trace[1066502255] range","detail":"{range_begin:/registry/clusterroles/system:controller:daemon-set-controller; range_end:; response_count:1; response_revision:2419; }","duration":"205.536461ms","start":"2026-06-28T20:02:11.974974Z","end":"2026-06-28T20:02:12.180511Z","steps":["trace[1066502255] 'agreement among raft nodes before linearized reading' (duration: 205.45928ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:02:12.451072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.045834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/ceph-csi-rbd-nodeplugin\" ","response":"range_response_count:1 size:944"} {"level":"info","ts":"2026-06-28T20:02:12.451144Z","caller":"traceutil/trace.go:171","msg":"trace[2022178471] range","detail":"{range_begin:/registry/clusterrolebindings/ceph-csi-rbd-nodeplugin; range_end:; response_count:1; response_revision:2419; }","duration":"223.157607ms","start":"2026-06-28T20:02:12.22797Z","end":"2026-06-28T20:02:12.451128Z","steps":["trace[2022178471] 'range keys from in-memory index tree' (duration: 222.87873ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:02:12.451303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.76378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:deployment-controller\" ","response":"range_response_count:1 size:913"} {"level":"info","ts":"2026-06-28T20:02:12.451381Z","caller":"traceutil/trace.go:171","msg":"trace[1382245505] range","detail":"{range_begin:/registry/clusterroles/system:controller:deployment-controller; range_end:; response_count:1; response_revision:2419; }","duration":"189.883622ms","start":"2026-06-28T20:02:12.261475Z","end":"2026-06-28T20:02:12.451358Z","steps":["trace[1382245505] 'range keys from in-memory index tree' (duration: 189.677587ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T20:02:13.094424Z","caller":"traceutil/trace.go:171","msg":"trace[989517122] linearizableReadLoop","detail":"{readStateIndex:2524; appliedIndex:2523; }","duration":"285.410342ms","start":"2026-06-28T20:02:12.808988Z","end":"2026-06-28T20:02:13.094398Z","steps":["trace[989517122] 'read index received' (duration: 285.31082ms)","trace[989517122] 'applied index is now lower than readState.Index' (duration: 98.552µs)"],"step_count":2} {"level":"info","ts":"2026-06-28T20:02:13.09453Z","caller":"traceutil/trace.go:171","msg":"trace[2034234212] transaction","detail":"{read_only:false; response_revision:2420; number_of_response:1; }","duration":"312.713865ms","start":"2026-06-28T20:02:12.781806Z","end":"2026-06-28T20:02:13.094519Z","steps":["trace[2034234212] 'process raft request' (duration: 312.463819ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:02:13.094702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-28T20:02:12.781794Z","time spent":"312.760586ms","remote":"127.0.0.1:40816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-28T20:02:13.094799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.788142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.246\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-28T20:02:13.094945Z","caller":"traceutil/trace.go:171","msg":"trace[1191843823] range","detail":"{range_begin:/registry/masterleases/199.204.45.246; range_end:; response_count:1; response_revision:2420; }","duration":"285.975545ms","start":"2026-06-28T20:02:12.808946Z","end":"2026-06-28T20:02:13.094921Z","steps":["trace[1191843823] 'agreement among raft nodes before linearized reading' (duration: 285.704059ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T20:02:13.095122Z","caller":"traceutil/trace.go:171","msg":"trace[1198390714] transaction","detail":"{read_only:false; response_revision:2421; number_of_response:1; }","duration":"199.782286ms","start":"2026-06-28T20:02:12.895323Z","end":"2026-06-28T20:02:13.095105Z","steps":["trace[1198390714] 'process raft request' (duration: 199.625622ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:02:13.095138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.487296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/cilium-operator\" ","response":"range_response_count:1 size:785"} {"level":"info","ts":"2026-06-28T20:02:13.095258Z","caller":"traceutil/trace.go:171","msg":"trace[9195537] range","detail":"{range_begin:/registry/clusterrolebindings/cilium-operator; range_end:; response_count:1; response_revision:2421; }","duration":"260.654511ms","start":"2026-06-28T20:02:12.834585Z","end":"2026-06-28T20:02:13.09524Z","steps":["trace[9195537] 'agreement among raft nodes before linearized reading' (duration: 260.469675ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:02:13.095296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.681444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/cilium\" ","response":"range_response_count:1 size:749"} {"level":"info","ts":"2026-06-28T20:02:13.09538Z","caller":"traceutil/trace.go:171","msg":"trace[762626723] range","detail":"{range_begin:/registry/clusterrolebindings/cilium; range_end:; response_count:1; response_revision:2421; }","duration":"247.817217ms","start":"2026-06-28T20:02:12.847542Z","end":"2026-06-28T20:02:13.09536Z","steps":["trace[762626723] 'agreement among raft nodes before linearized reading' (duration: 247.611612ms)"],"step_count":1} {"level":"warn","ts":"2026-06-28T20:02:13.095463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.904865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-28T20:02:13.095627Z","caller":"traceutil/trace.go:171","msg":"trace[663456527] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2421; }","duration":"182.025607ms","start":"2026-06-28T20:02:12.913503Z","end":"2026-06-28T20:02:13.095529Z","steps":["trace[663456527] 'agreement among raft nodes before linearized reading' (duration: 181.641807ms)"],"step_count":1} {"level":"info","ts":"2026-06-28T20:02:38.347078Z","caller":"traceutil/trace.go:171","msg":"trace[284000150] transaction","detail":"{read_only:false; response_revision:2538; number_of_response:1; }","duration":"210.185316ms","start":"2026-06-28T20:02:38.136875Z","end":"2026-06-28T20:02:38.347061Z","steps":["trace[284000150] 'process raft request' (duration: 210.088904ms)"],"step_count":1}