{"level":"warn","ts":"2026-04-28T07:30:31.088252Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T07:30:31.088333Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.205: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.205:2380","--initial-cluster=instance=https://199.204.45.205:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.205:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.205: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-04-28T07:30:31.088395Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T07:30:31.088403Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-04-28T07:30:31.08842Z","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-04-28T07:30:31.089081Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"]} {"level":"info","ts":"2026-04-28T07:30:31.089149Z","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.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.205: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-04-28T07:30:31.092745Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.404943ms"} {"level":"info","ts":"2026-04-28T07:30:31.100656Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"71d28e9ac1bc4e5d","cluster-id":"4a4ae660c7a9a6a7"} {"level":"info","ts":"2026-04-28T07:30:31.100777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=()"} {"level":"info","ts":"2026-04-28T07:30:31.100805Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 0"} {"level":"info","ts":"2026-04-28T07:30:31.100821Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 71d28e9ac1bc4e5d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-28T07:30:31.100832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 1"} {"level":"info","ts":"2026-04-28T07:30:31.100886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"warn","ts":"2026-04-28T07:30:31.110884Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-28T07:30:31.112235Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-28T07:30:31.113132Z","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-04-28T07:30:31.115744Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"71d28e9ac1bc4e5d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-28T07:30:31.119452Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"71d28e9ac1bc4e5d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-28T07:30:31.129363Z","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-04-28T07:30:31.129466Z","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-04-28T07:30:31.129479Z","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-04-28T07:30:31.129758Z","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-04-28T07:30:31.130035Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"71d28e9ac1bc4e5d","initial-advertise-peer-urls":["https://199.204.45.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-28T07:30:31.130078Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-28T07:30:31.130692Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-04-28T07:30:31.130708Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-04-28T07:30:31.146518Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"info","ts":"2026-04-28T07:30:31.146626Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","added-peer-id":"71d28e9ac1bc4e5d","added-peer-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-04-28T07:30:31.601177Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d is starting a new election at term 1"} {"level":"info","ts":"2026-04-28T07:30:31.601238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-28T07:30:31.601295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgPreVoteResp from 71d28e9ac1bc4e5d at term 1"} {"level":"info","ts":"2026-04-28T07:30:31.601314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became candidate at term 2"} {"level":"info","ts":"2026-04-28T07:30:31.601325Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgVoteResp from 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-04-28T07:30:31.601347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became leader at term 2"} {"level":"info","ts":"2026-04-28T07:30:31.60137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 71d28e9ac1bc4e5d elected leader 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-04-28T07:30:31.602367Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T07:30:31.603302Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"71d28e9ac1bc4e5d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.205:2379]}","request-path":"/0/members/71d28e9ac1bc4e5d/attributes","cluster-id":"4a4ae660c7a9a6a7","publish-timeout":"7s"} {"level":"info","ts":"2026-04-28T07:30:31.603367Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T07:30:31.603497Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T07:30:31.603554Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T07:30:31.603574Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T07:30:31.603403Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T07:30:31.603756Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-28T07:30:31.603778Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-28T07:30:31.605034Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.205:2379"} {"level":"info","ts":"2026-04-28T07:30:31.606413Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-28T07:30:36.707622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.307608ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T07:30:36.707791Z","caller":"traceutil/trace.go:171","msg":"trace[272218113] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"204.023011ms","start":"2026-04-28T07:30:36.503747Z","end":"2026-04-28T07:30:36.70777Z","steps":["trace[272218113] 'process raft request' (duration: 63.122997ms)","trace[272218113] 'compare' (duration: 140.170254ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:30:37.005457Z","caller":"traceutil/trace.go:171","msg":"trace[2115674480] linearizableReadLoop","detail":"{readStateIndex:298; appliedIndex:296; }","duration":"485.615255ms","start":"2026-04-28T07:30:36.519813Z","end":"2026-04-28T07:30:37.005428Z","steps":["trace[2115674480] 'read index received' (duration: 47.04209ms)","trace[2115674480] 'applied index is now lower than readState.Index' (duration: 438.571965ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:30:37.005544Z","caller":"traceutil/trace.go:171","msg":"trace[1138110416] transaction","detail":"{read_only:false; number_of_response:0; response_revision:291; }","duration":"500.14479ms","start":"2026-04-28T07:30:36.505352Z","end":"2026-04-28T07:30:37.005497Z","steps":["trace[1138110416] 'process raft request' (duration: 427.617316ms)","trace[1138110416] 'compare' (duration: 72.404651ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:30:37.005772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:30:36.505344Z","time spent":"500.349196ms","remote":"127.0.0.1:46004","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T07:30:37.005868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.80233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cronjob-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:30:37.006152Z","caller":"traceutil/trace.go:171","msg":"trace[1270997309] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cronjob-controller; range_end:; response_count:0; response_revision:291; }","duration":"486.352745ms","start":"2026-04-28T07:30:36.519775Z","end":"2026-04-28T07:30:37.006128Z","steps":["trace[1270997309] 'agreement among raft nodes before linearized reading' (duration: 485.767849ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:30:37.006214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:30:36.519762Z","time spent":"486.434477ms","remote":"127.0.0.1:46052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/kube-system/cronjob-controller\" "} {"level":"info","ts":"2026-04-28T07:30:37.167331Z","caller":"traceutil/trace.go:171","msg":"trace[2068008907] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"455.661029ms","start":"2026-04-28T07:30:36.711647Z","end":"2026-04-28T07:30:37.167308Z","steps":["trace[2068008907] 'process raft request' (duration: 455.504805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:30:37.167459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:30:36.711613Z","time spent":"455.789592ms","remote":"127.0.0.1:45886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":638,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T07:30:37.167416Z","caller":"traceutil/trace.go:171","msg":"trace[1094272969] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"564.971253ms","start":"2026-04-28T07:30:36.602385Z","end":"2026-04-28T07:30:37.167356Z","steps":["trace[1094272969] 'process raft request' (duration: 564.654994ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:30:37.167638Z","caller":"traceutil/trace.go:171","msg":"trace[1266471628] linearizableReadLoop","detail":"{readStateIndex:300; appliedIndex:298; }","duration":"162.05909ms","start":"2026-04-28T07:30:37.005527Z","end":"2026-04-28T07:30:37.167586Z","steps":["trace[1266471628] 'read index received' (duration: 161.426893ms)","trace[1266471628] 'applied index is now lower than readState.Index' (duration: 610.866µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:30:37.168155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.480129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:30:37.168244Z","caller":"traceutil/trace.go:171","msg":"trace[570423344] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:293; }","duration":"640.573991ms","start":"2026-04-28T07:30:36.527632Z","end":"2026-04-28T07:30:37.168206Z","steps":["trace[570423344] 'agreement among raft nodes before linearized reading' (duration: 640.069357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:30:37.168289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:30:36.527621Z","time spent":"640.657053ms","remote":"127.0.0.1:45956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"warn","ts":"2026-04-28T07:30:37.168518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:30:36.602356Z","time spent":"565.341273ms","remote":"127.0.0.1:45916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1934,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T07:30:37.169104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.49736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-04-28T07:30:37.16911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.183253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-04-28T07:30:37.169173Z","caller":"traceutil/trace.go:171","msg":"trace[1429789181] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:293; }","duration":"159.580303ms","start":"2026-04-28T07:30:37.009559Z","end":"2026-04-28T07:30:37.169139Z","steps":["trace[1429789181] 'agreement among raft nodes before linearized reading' (duration: 159.47785ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:30:37.169238Z","caller":"traceutil/trace.go:171","msg":"trace[1164621459] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:293; }","duration":"162.314256ms","start":"2026-04-28T07:30:37.006873Z","end":"2026-04-28T07:30:37.169187Z","steps":["trace[1164621459] 'agreement among raft nodes before linearized reading' (duration: 162.114271ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:30:37.461379Z","caller":"traceutil/trace.go:171","msg":"trace[1438556282] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"286.925857ms","start":"2026-04-28T07:30:37.174419Z","end":"2026-04-28T07:30:37.461345Z","steps":["trace[1438556282] 'process raft request' (duration: 285.571451ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:30:37.828975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.370634ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T07:30:37.829748Z","caller":"traceutil/trace.go:171","msg":"trace[1377028911] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"655.024235ms","start":"2026-04-28T07:30:37.174683Z","end":"2026-04-28T07:30:37.829707Z","steps":["trace[1377028911] 'process raft request' (duration: 536.846669ms)","trace[1377028911] 'compare' (duration: 117.023885ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:30:37.829902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:30:37.174667Z","time spent":"655.19183ms","remote":"127.0.0.1:46052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":186,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T07:30:37.829959Z","caller":"traceutil/trace.go:171","msg":"trace[1903048588] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"654.1312ms","start":"2026-04-28T07:30:37.175805Z","end":"2026-04-28T07:30:37.829936Z","steps":["trace[1903048588] 'process raft request' (duration: 653.959226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:30:37.83006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:30:37.175779Z","time spent":"654.235893ms","remote":"127.0.0.1:46020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3453,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T07:30:37.830065Z","caller":"traceutil/trace.go:171","msg":"trace[627392844] linearizableReadLoop","detail":"{readStateIndex:303; appliedIndex:300; }","duration":"654.240653ms","start":"2026-04-28T07:30:37.175805Z","end":"2026-04-28T07:30:37.830045Z","steps":["trace[627392844] 'read index received' (duration: 284.199203ms)","trace[627392844] 'applied index is now lower than readState.Index' (duration: 370.04042ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:30:37.830206Z","caller":"traceutil/trace.go:171","msg":"trace[844813762] transaction","detail":"{read_only:false; response_revision:300; number_of_response:1; }","duration":"653.951836ms","start":"2026-04-28T07:30:37.176186Z","end":"2026-04-28T07:30:37.830138Z","steps":["trace[844813762] 'process raft request' (duration: 653.74613ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:30:37.830268Z","caller":"traceutil/trace.go:171","msg":"trace[484382551] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"653.246926ms","start":"2026-04-28T07:30:37.177004Z","end":"2026-04-28T07:30:37.830251Z","steps":["trace[484382551] 'process raft request' (duration: 652.993429ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:30:37.830343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:30:37.176997Z","time spent":"653.311819ms","remote":"127.0.0.1:46316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2651,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T07:30:37.830369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:30:37.176176Z","time spent":"654.128922ms","remote":"127.0.0.1:45886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":636,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T07:30:37.830379Z","caller":"traceutil/trace.go:171","msg":"trace[2036596153] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"654.46623ms","start":"2026-04-28T07:30:37.175896Z","end":"2026-04-28T07:30:37.830362Z","steps":["trace[2036596153] 'process raft request' (duration: 653.914865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:30:37.830389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.277957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:30:37.830445Z","caller":"traceutil/trace.go:171","msg":"trace[1727084448] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:301; }","duration":"603.37631ms","start":"2026-04-28T07:30:37.227051Z","end":"2026-04-28T07:30:37.830428Z","steps":["trace[1727084448] 'agreement among raft nodes before linearized reading' (duration: 603.280797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:30:37.830441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.604943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"warn","ts":"2026-04-28T07:30:37.830462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:30:37.175886Z","time spent":"654.536932ms","remote":"127.0.0.1:46020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T07:30:37.8305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:30:37.22703Z","time spent":"603.455141ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-28T07:30:37.83055Z","caller":"traceutil/trace.go:171","msg":"trace[339145818] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:301; }","duration":"654.733317ms","start":"2026-04-28T07:30:37.17577Z","end":"2026-04-28T07:30:37.830503Z","steps":["trace[339145818] 'agreement among raft nodes before linearized reading' (duration: 654.416979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:30:37.830596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:30:37.175757Z","time spent":"654.83016ms","remote":"127.0.0.1:46004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4039,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-28T07:30:37.8307Z","caller":"traceutil/trace.go:171","msg":"trace[237209941] transaction","detail":"{read_only:false; response_revision:299; number_of_response:1; }","duration":"654.705747ms","start":"2026-04-28T07:30:37.175979Z","end":"2026-04-28T07:30:37.830684Z","steps":["trace[237209941] 'process raft request' (duration: 653.879974ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:30:37.83078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:30:37.175967Z","time spent":"654.773678ms","remote":"127.0.0.1:46020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T07:30:37.830894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.913215ms","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-04-28T07:30:37.830939Z","caller":"traceutil/trace.go:171","msg":"trace[1119500389] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:301; }","duration":"352.984037ms","start":"2026-04-28T07:30:37.477942Z","end":"2026-04-28T07:30:37.830926Z","steps":["trace[1119500389] 'agreement among raft nodes before linearized reading' (duration: 352.881014ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:30:37.830984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:30:37.477923Z","time spent":"353.050899ms","remote":"127.0.0.1:46106","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-04-28T07:30:37.831055Z","caller":"traceutil/trace.go:171","msg":"trace[1541951125] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"654.330346ms","start":"2026-04-28T07:30:37.175499Z","end":"2026-04-28T07:30:37.829829Z","steps":["trace[1541951125] 'process raft request' (duration: 654.150651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:30:37.831142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.257157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-28T07:30:37.831235Z","caller":"traceutil/trace.go:171","msg":"trace[1968910445] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:301; }","duration":"592.337979ms","start":"2026-04-28T07:30:37.238849Z","end":"2026-04-28T07:30:37.831187Z","steps":["trace[1968910445] 'agreement among raft nodes before linearized reading' (duration: 592.237887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:30:37.831275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:30:37.238838Z","time spent":"592.428742ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T07:30:37.831153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:30:37.175479Z","time spent":"655.631432ms","remote":"127.0.0.1:46020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T07:30:37.831137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.176882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-04-28T07:30:37.831477Z","caller":"traceutil/trace.go:171","msg":"trace[1497532233] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:301; }","duration":"593.564451ms","start":"2026-04-28T07:30:37.237895Z","end":"2026-04-28T07:30:37.831459Z","steps":["trace[1497532233] 'agreement among raft nodes before linearized reading' (duration: 593.13688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:30:37.83152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:30:37.237869Z","time spent":"593.640655ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-28T07:30:56.471744Z","caller":"traceutil/trace.go:171","msg":"trace[541257387] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"204.795733ms","start":"2026-04-28T07:30:56.266919Z","end":"2026-04-28T07:30:56.471714Z","steps":["trace[541257387] 'process raft request' (duration: 119.283026ms)","trace[541257387] 'compare' (duration: 85.316611ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:30:56.471922Z","caller":"traceutil/trace.go:171","msg":"trace[1160778885] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"202.241753ms","start":"2026-04-28T07:30:56.269642Z","end":"2026-04-28T07:30:56.471884Z","steps":["trace[1160778885] 'process raft request' (duration: 202.078609ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:30:56.471964Z","caller":"traceutil/trace.go:171","msg":"trace[932822777] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"193.781153ms","start":"2026-04-28T07:30:56.278148Z","end":"2026-04-28T07:30:56.471929Z","steps":["trace[932822777] 'process raft request' (duration: 193.63396ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:30:56.472013Z","caller":"traceutil/trace.go:171","msg":"trace[28890712] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"194.823341ms","start":"2026-04-28T07:30:56.277183Z","end":"2026-04-28T07:30:56.472007Z","steps":["trace[28890712] 'process raft request' (duration: 194.571445ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:30:56.472185Z","caller":"traceutil/trace.go:171","msg":"trace[1771172900] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"203.117967ms","start":"2026-04-28T07:30:56.269039Z","end":"2026-04-28T07:30:56.472157Z","steps":["trace[1771172900] 'process raft request' (duration: 202.615884ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:30:56.472225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.031659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-67659f764b\" ","response":"range_response_count:1 size:3855"} {"level":"info","ts":"2026-04-28T07:30:56.472202Z","caller":"traceutil/trace.go:171","msg":"trace[159051330] linearizableReadLoop","detail":"{readStateIndex:473; appliedIndex:470; }","duration":"200.67528ms","start":"2026-04-28T07:30:56.271174Z","end":"2026-04-28T07:30:56.47185Z","steps":["trace[159051330] 'read index received' (duration: 115.098552ms)","trace[159051330] 'applied index is now lower than readState.Index' (duration: 85.575638ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:30:56.473061Z","caller":"traceutil/trace.go:171","msg":"trace[1202905028] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-67659f764b; range_end:; response_count:1; response_revision:463; }","duration":"201.334359ms","start":"2026-04-28T07:30:56.27117Z","end":"2026-04-28T07:30:56.472504Z","steps":["trace[1202905028] 'agreement among raft nodes before linearized reading' (duration: 200.984619ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:31:08.287719Z","caller":"traceutil/trace.go:171","msg":"trace[624989750] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:538; }","duration":"226.634926ms","start":"2026-04-28T07:31:08.06106Z","end":"2026-04-28T07:31:08.287695Z","steps":["trace[624989750] 'read index received' (duration: 186.638218ms)","trace[624989750] 'applied index is now lower than readState.Index' (duration: 39.995798ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:31:08.287804Z","caller":"traceutil/trace.go:171","msg":"trace[199033930] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"230.909653ms","start":"2026-04-28T07:31:08.056864Z","end":"2026-04-28T07:31:08.287774Z","steps":["trace[199033930] 'process raft request' (duration: 190.823521ms)","trace[199033930] 'compare' (duration: 39.790283ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:31:08.287932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.855092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-vpdsg\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-04-28T07:31:08.287982Z","caller":"traceutil/trace.go:171","msg":"trace[413370669] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-vpdsg; range_end:; response_count:1; response_revision:525; }","duration":"226.958496ms","start":"2026-04-28T07:31:08.061009Z","end":"2026-04-28T07:31:08.287968Z","steps":["trace[413370669] 'agreement among raft nodes before linearized reading' (duration: 226.797662ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:31:08.293968Z","caller":"traceutil/trace.go:171","msg":"trace[1833507158] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"232.686991ms","start":"2026-04-28T07:31:08.061261Z","end":"2026-04-28T07:31:08.293948Z","steps":["trace[1833507158] 'process raft request' (duration: 232.061214ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:31:08.294312Z","caller":"traceutil/trace.go:171","msg":"trace[758821372] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"230.43025ms","start":"2026-04-28T07:31:08.063872Z","end":"2026-04-28T07:31:08.294302Z","steps":["trace[758821372] 'process raft request' (duration: 229.579727ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:31:08.294493Z","caller":"traceutil/trace.go:171","msg":"trace[1571888218] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"227.699826ms","start":"2026-04-28T07:31:08.066785Z","end":"2026-04-28T07:31:08.294485Z","steps":["trace[1571888218] 'process raft request' (duration: 226.786392ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:31:08.29463Z","caller":"traceutil/trace.go:171","msg":"trace[567357660] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"227.479471ms","start":"2026-04-28T07:31:08.067144Z","end":"2026-04-28T07:31:08.294624Z","steps":["trace[567357660] 'process raft request' (duration: 226.459133ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:31:10.239677Z","caller":"traceutil/trace.go:171","msg":"trace[997885612] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"331.904902ms","start":"2026-04-28T07:31:09.907746Z","end":"2026-04-28T07:31:10.239651Z","steps":["trace[997885612] 'process raft request' (duration: 310.651242ms)","trace[997885612] 'compare' (duration: 20.996822ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:31:10.240037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:31:09.907731Z","time spent":"332.035396ms","remote":"127.0.0.1:45872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23057,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:31:10.240522Z","caller":"traceutil/trace.go:171","msg":"trace[1817628508] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"321.345045ms","start":"2026-04-28T07:31:09.91886Z","end":"2026-04-28T07:31:10.240205Z","steps":["trace[1817628508] 'process raft request' (duration: 320.704477ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:31:10.240721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:31:09.918818Z","time spent":"321.825108ms","remote":"127.0.0.1:45872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:31:10.241432Z","caller":"traceutil/trace.go:171","msg":"trace[168689709] linearizableReadLoop","detail":"{readStateIndex:588; appliedIndex:585; }","duration":"254.764083ms","start":"2026-04-28T07:31:09.98665Z","end":"2026-04-28T07:31:10.241414Z","steps":["trace[168689709] 'read index received' (duration: 231.740876ms)","trace[168689709] 'applied index is now lower than readState.Index' (duration: 23.022007ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:31:10.241725Z","caller":"traceutil/trace.go:171","msg":"trace[1250281910] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"265.944807ms","start":"2026-04-28T07:31:09.975768Z","end":"2026-04-28T07:31:10.241713Z","steps":["trace[1250281910] 'process raft request' (duration: 265.551106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:31:10.242068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.41222ms","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-04-28T07:31:10.242131Z","caller":"traceutil/trace.go:171","msg":"trace[260886444] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:569; }","duration":"255.489143ms","start":"2026-04-28T07:31:09.986622Z","end":"2026-04-28T07:31:10.242111Z","steps":["trace[260886444] 'agreement among raft nodes before linearized reading' (duration: 255.266697ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:31:10.242471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.808989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-28T07:31:10.242518Z","caller":"traceutil/trace.go:171","msg":"trace[881036288] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:569; }","duration":"231.889251ms","start":"2026-04-28T07:31:10.010615Z","end":"2026-04-28T07:31:10.242504Z","steps":["trace[881036288] 'agreement among raft nodes before linearized reading' (duration: 231.761607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:31:10.242716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.194583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-28T07:31:10.242761Z","caller":"traceutil/trace.go:171","msg":"trace[910598873] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:569; }","duration":"180.463821ms","start":"2026-04-28T07:31:10.062284Z","end":"2026-04-28T07:31:10.242747Z","steps":["trace[910598873] 'agreement among raft nodes before linearized reading' (duration: 180.328908ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:31:10.714769Z","caller":"traceutil/trace.go:171","msg":"trace[366733225] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"127.256623ms","start":"2026-04-28T07:31:10.587488Z","end":"2026-04-28T07:31:10.714745Z","steps":["trace[366733225] 'process raft request' (duration: 110.304142ms)","trace[366733225] 'compare' (duration: 16.841008ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:31:10.7154Z","caller":"traceutil/trace.go:171","msg":"trace[1104872129] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"110.109226ms","start":"2026-04-28T07:31:10.60525Z","end":"2026-04-28T07:31:10.715359Z","steps":["trace[1104872129] 'process raft request' (duration: 109.671213ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:31:11.014504Z","caller":"traceutil/trace.go:171","msg":"trace[1143366550] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:615; }","duration":"172.00217ms","start":"2026-04-28T07:31:10.842464Z","end":"2026-04-28T07:31:11.014466Z","steps":["trace[1143366550] 'read index received' (duration: 94.290655ms)","trace[1143366550] 'applied index is now lower than readState.Index' (duration: 77.709865ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:31:11.015324Z","caller":"traceutil/trace.go:171","msg":"trace[2000587540] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"231.797358ms","start":"2026-04-28T07:31:10.783509Z","end":"2026-04-28T07:31:11.015306Z","steps":["trace[2000587540] 'process raft request' (duration: 153.164048ms)","trace[2000587540] 'compare' (duration: 77.357586ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:31:11.015552Z","caller":"traceutil/trace.go:171","msg":"trace[1656516207] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"180.599894ms","start":"2026-04-28T07:31:10.834943Z","end":"2026-04-28T07:31:11.015542Z","steps":["trace[1656516207] 'process raft request' (duration: 179.394121ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:31:11.015987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.498491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:31:11.016086Z","caller":"traceutil/trace.go:171","msg":"trace[487290209] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:598; }","duration":"173.613725ms","start":"2026-04-28T07:31:10.842448Z","end":"2026-04-28T07:31:11.016062Z","steps":["trace[487290209] 'agreement among raft nodes before linearized reading' (duration: 172.235047ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:31:16.583045Z","caller":"traceutil/trace.go:171","msg":"trace[662622609] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"124.314572ms","start":"2026-04-28T07:31:16.458694Z","end":"2026-04-28T07:31:16.583008Z","steps":["trace[662622609] 'process raft request' (duration: 123.625433ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:31:17.189447Z","caller":"traceutil/trace.go:171","msg":"trace[35672706] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"589.970483ms","start":"2026-04-28T07:31:16.599453Z","end":"2026-04-28T07:31:17.189424Z","steps":["trace[35672706] 'process raft request' (duration: 589.665515ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:31:17.189621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:31:16.599436Z","time spent":"590.088927ms","remote":"127.0.0.1:46020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:31:17.792874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.990357ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T07:31:17.793014Z","caller":"traceutil/trace.go:171","msg":"trace[367452270] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:666; }","duration":"951.747618ms","start":"2026-04-28T07:31:16.841252Z","end":"2026-04-28T07:31:17.793Z","steps":["trace[367452270] 'read index received' (duration: 347.937848ms)","trace[367452270] 'applied index is now lower than readState.Index' (duration: 603.80908ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:31:17.793207Z","caller":"traceutil/trace.go:171","msg":"trace[1627259331] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"1.127495251s","start":"2026-04-28T07:31:16.665674Z","end":"2026-04-28T07:31:17.793169Z","steps":["trace[1627259331] 'process raft request' (duration: 653.158483ms)","trace[1627259331] 'compare' (duration: 473.502345ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:31:17.793415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:31:16.665649Z","time spent":"1.127681756s","remote":"127.0.0.1:46348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T07:31:17.7938Z","caller":"traceutil/trace.go:171","msg":"trace[1986020827] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"897.269036ms","start":"2026-04-28T07:31:16.896479Z","end":"2026-04-28T07:31:17.793748Z","steps":["trace[1986020827] 'process raft request' (duration: 896.468594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:31:17.793811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"952.529699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T07:31:17.793973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:31:16.896455Z","time spent":"897.43485ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:31:17.794242Z","caller":"traceutil/trace.go:171","msg":"trace[1509478938] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:649; }","duration":"952.693993ms","start":"2026-04-28T07:31:16.841201Z","end":"2026-04-28T07:31:17.793895Z","steps":["trace[1509478938] 'agreement among raft nodes before linearized reading' (duration: 951.884171ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:31:17.794303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:31:16.84118Z","time spent":"953.107605ms","remote":"127.0.0.1:45814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T07:31:18.78569Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843037342122582,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T07:31:19.126986Z","caller":"traceutil/trace.go:171","msg":"trace[591391674] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:669; }","duration":"841.974812ms","start":"2026-04-28T07:31:18.284987Z","end":"2026-04-28T07:31:19.126962Z","steps":["trace[591391674] 'read index received' (duration: 840.629935ms)","trace[591391674] 'applied index is now lower than readState.Index' (duration: 1.343707ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:31:19.127354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"842.353352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-28T07:31:19.127415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.885367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-28T07:31:19.127454Z","caller":"traceutil/trace.go:171","msg":"trace[1265665995] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:650; }","duration":"842.495986ms","start":"2026-04-28T07:31:18.284937Z","end":"2026-04-28T07:31:19.127433Z","steps":["trace[1265665995] 'agreement among raft nodes before linearized reading' (duration: 842.199737ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:31:19.127485Z","caller":"traceutil/trace.go:171","msg":"trace[1368238214] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:650; }","duration":"809.998241ms","start":"2026-04-28T07:31:18.317467Z","end":"2026-04-28T07:31:19.127465Z","steps":["trace[1368238214] 'agreement among raft nodes before linearized reading' (duration: 809.772994ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:31:19.127499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:31:18.284905Z","time spent":"842.585888ms","remote":"127.0.0.1:46106","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-04-28T07:31:19.127523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:31:18.317451Z","time spent":"810.065793ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T07:31:19.127513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.752394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-28T07:31:19.127572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.366181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:31:19.127582Z","caller":"traceutil/trace.go:171","msg":"trace[211972274] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:650; }","duration":"809.864747ms","start":"2026-04-28T07:31:18.317701Z","end":"2026-04-28T07:31:19.127566Z","steps":["trace[211972274] 'agreement among raft nodes before linearized reading' (duration: 809.653422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:31:19.127508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.21878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-28T07:31:19.127626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:31:18.317682Z","time spent":"809.93766ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-28T07:31:19.127625Z","caller":"traceutil/trace.go:171","msg":"trace[1597548873] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:650; }","duration":"283.442163ms","start":"2026-04-28T07:31:18.844167Z","end":"2026-04-28T07:31:19.12761Z","steps":["trace[1597548873] 'agreement among raft nodes before linearized reading' (duration: 283.380812ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:31:19.127634Z","caller":"traceutil/trace.go:171","msg":"trace[2120791476] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:650; }","duration":"730.428436ms","start":"2026-04-28T07:31:18.397196Z","end":"2026-04-28T07:31:19.127624Z","steps":["trace[2120791476] 'agreement among raft nodes before linearized reading' (duration: 730.165399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:31:19.127662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:31:18.397172Z","time spent":"730.483166ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-28T07:31:19.128573Z","caller":"traceutil/trace.go:171","msg":"trace[340337829] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"923.182071ms","start":"2026-04-28T07:31:18.205377Z","end":"2026-04-28T07:31:19.128559Z","steps":["trace[340337829] 'process raft request' (duration: 920.55464ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:31:19.128843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:31:18.205343Z","time spent":"923.266062ms","remote":"127.0.0.1:45906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:31:20.962121Z","caller":"traceutil/trace.go:171","msg":"trace[233113261] linearizableReadLoop","detail":"{readStateIndex:702; appliedIndex:701; }","duration":"118.074957ms","start":"2026-04-28T07:31:20.844032Z","end":"2026-04-28T07:31:20.962107Z","steps":["trace[233113261] 'read index received' (duration: 118.030776ms)","trace[233113261] 'applied index is now lower than readState.Index' (duration: 43.361µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:31:20.962233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.19467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:31:20.962233Z","caller":"traceutil/trace.go:171","msg":"trace[328884579] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"213.681604ms","start":"2026-04-28T07:31:20.748526Z","end":"2026-04-28T07:31:20.962208Z","steps":["trace[328884579] 'process raft request' (duration: 213.50309ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:31:20.962282Z","caller":"traceutil/trace.go:171","msg":"trace[1426149494] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:681; }","duration":"118.307644ms","start":"2026-04-28T07:31:20.843961Z","end":"2026-04-28T07:31:20.962268Z","steps":["trace[1426149494] 'agreement among raft nodes before linearized reading' (duration: 118.21422ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:31:35.00392Z","caller":"traceutil/trace.go:171","msg":"trace[269343929] linearizableReadLoop","detail":"{readStateIndex:835; appliedIndex:834; }","duration":"418.827581ms","start":"2026-04-28T07:31:34.585068Z","end":"2026-04-28T07:31:35.003896Z","steps":["trace[269343929] 'read index received' (duration: 418.703878ms)","trace[269343929] 'applied index is now lower than readState.Index' (duration: 122.223µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:31:35.004041Z","caller":"traceutil/trace.go:171","msg":"trace[1769421192] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"606.91072ms","start":"2026-04-28T07:31:34.397098Z","end":"2026-04-28T07:31:35.004009Z","steps":["trace[1769421192] 'process raft request' (duration: 606.632273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:31:35.004296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.17669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.205\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-28T07:31:35.004397Z","caller":"traceutil/trace.go:171","msg":"trace[1818729469] range","detail":"{range_begin:/registry/masterleases/199.204.45.205; range_end:; response_count:1; response_revision:811; }","duration":"419.364086ms","start":"2026-04-28T07:31:34.584997Z","end":"2026-04-28T07:31:35.004361Z","steps":["trace[1818729469] 'agreement among raft nodes before linearized reading' (duration: 419.033866ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:31:35.004436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:31:34.584959Z","time spent":"419.469458ms","remote":"127.0.0.1:45858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.205\" "} {"level":"warn","ts":"2026-04-28T07:31:35.004386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:31:34.39707Z","time spent":"607.159538ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:31:35.00493Z","caller":"traceutil/trace.go:171","msg":"trace[1405288416] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"355.834697ms","start":"2026-04-28T07:31:34.649072Z","end":"2026-04-28T07:31:35.004906Z","steps":["trace[1405288416] 'process raft request' (duration: 355.675142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:31:35.004955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.587869ms","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-04-28T07:31:35.005015Z","caller":"traceutil/trace.go:171","msg":"trace[2045670113] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:812; }","duration":"395.701922ms","start":"2026-04-28T07:31:34.6093Z","end":"2026-04-28T07:31:35.005002Z","steps":["trace[2045670113] 'agreement among raft nodes before linearized reading' (duration: 395.527708ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:31:35.005045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:31:34.609272Z","time spent":"395.765634ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-28T07:31:35.005085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:31:34.64903Z","time spent":"355.96781ms","remote":"127.0.0.1:46078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:31:35.005149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.147363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:31:35.005186Z","caller":"traceutil/trace.go:171","msg":"trace[1447846320] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:812; }","duration":"160.208854ms","start":"2026-04-28T07:31:34.844968Z","end":"2026-04-28T07:31:35.005177Z","steps":["trace[1447846320] 'agreement among raft nodes before linearized reading' (duration: 160.141913ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:32:11.285827Z","caller":"traceutil/trace.go:171","msg":"trace[185640569] linearizableReadLoop","detail":"{readStateIndex:1065; appliedIndex:1064; }","duration":"441.921048ms","start":"2026-04-28T07:32:10.843888Z","end":"2026-04-28T07:32:11.285809Z","steps":["trace[185640569] 'read index received' (duration: 441.754283ms)","trace[185640569] 'applied index is now lower than readState.Index' (duration: 166.205µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:32:11.28591Z","caller":"traceutil/trace.go:171","msg":"trace[660927664] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"571.231235ms","start":"2026-04-28T07:32:10.714652Z","end":"2026-04-28T07:32:11.285884Z","steps":["trace[660927664] 'process raft request' (duration: 571.032159ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:11.285982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.280654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T07:32:11.286003Z","caller":"traceutil/trace.go:171","msg":"trace[1490260274] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1030; }","duration":"348.346065ms","start":"2026-04-28T07:32:10.937652Z","end":"2026-04-28T07:32:11.285998Z","steps":["trace[1490260274] 'agreement among raft nodes before linearized reading' (duration: 348.312194ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:11.286027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:32:10.937627Z","time spent":"348.393197ms","remote":"127.0.0.1:46036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":29,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-04-28T07:32:11.28603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:32:10.714633Z","time spent":"571.336779ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:32:11.285945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.033061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:32:11.286171Z","caller":"traceutil/trace.go:171","msg":"trace[315655765] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1030; }","duration":"442.313928ms","start":"2026-04-28T07:32:10.843849Z","end":"2026-04-28T07:32:11.286163Z","steps":["trace[315655765] 'agreement among raft nodes before linearized reading' (duration: 442.053982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:11.2862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:32:10.843831Z","time spent":"442.361449ms","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T07:32:11.286133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.750555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-28T07:32:11.286257Z","caller":"traceutil/trace.go:171","msg":"trace[1005034470] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1030; }","duration":"109.903399ms","start":"2026-04-28T07:32:11.176346Z","end":"2026-04-28T07:32:11.286249Z","steps":["trace[1005034470] 'agreement among raft nodes before linearized reading' (duration: 109.712554ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:11.873414Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843037342123403,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T07:32:11.967012Z","caller":"traceutil/trace.go:171","msg":"trace[599141127] linearizableReadLoop","detail":"{readStateIndex:1066; appliedIndex:1065; }","duration":"593.932294ms","start":"2026-04-28T07:32:11.373058Z","end":"2026-04-28T07:32:11.966991Z","steps":["trace[599141127] 'read index received' (duration: 593.740749ms)","trace[599141127] 'applied index is now lower than readState.Index' (duration: 190.495µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:32:11.967106Z","caller":"traceutil/trace.go:171","msg":"trace[307149326] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"677.326945ms","start":"2026-04-28T07:32:11.289732Z","end":"2026-04-28T07:32:11.967059Z","steps":["trace[307149326] 'process raft request' (duration: 677.126519ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:11.96734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.654004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-28T07:32:11.96738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:32:11.289717Z","time spent":"677.52619ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:32:11.967423Z","caller":"traceutil/trace.go:171","msg":"trace[852508491] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1031; }","duration":"560.766267ms","start":"2026-04-28T07:32:11.406634Z","end":"2026-04-28T07:32:11.9674Z","steps":["trace[852508491] 'agreement among raft nodes before linearized reading' (duration: 560.553391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:11.967418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.974152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T07:32:11.967438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.92486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-28T07:32:11.967467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:32:11.406618Z","time spent":"560.841168ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-28T07:32:11.967477Z","caller":"traceutil/trace.go:171","msg":"trace[1216279476] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1031; }","duration":"552.020262ms","start":"2026-04-28T07:32:11.415448Z","end":"2026-04-28T07:32:11.967468Z","steps":["trace[1216279476] 'agreement among raft nodes before linearized reading' (duration: 551.90421ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:32:11.967482Z","caller":"traceutil/trace.go:171","msg":"trace[2081462114] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1031; }","duration":"426.064484ms","start":"2026-04-28T07:32:11.5414Z","end":"2026-04-28T07:32:11.967465Z","steps":["trace[2081462114] 'agreement among raft nodes before linearized reading' (duration: 425.976942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:11.967504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:32:11.415425Z","time spent":"552.072783ms","remote":"127.0.0.1:46106","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-04-28T07:32:11.967516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.304938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-28T07:32:11.967539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:32:11.541383Z","time spent":"426.138776ms","remote":"127.0.0.1:46076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-04-28T07:32:11.967594Z","caller":"traceutil/trace.go:171","msg":"trace[998417187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1031; }","duration":"559.4036ms","start":"2026-04-28T07:32:11.408169Z","end":"2026-04-28T07:32:11.967573Z","steps":["trace[998417187] 'agreement among raft nodes before linearized reading' (duration: 559.188945ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:11.967634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:32:11.408161Z","time spent":"559.463791ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T07:32:11.967339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.265392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-28T07:32:11.968262Z","caller":"traceutil/trace.go:171","msg":"trace[1883143440] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1031; }","duration":"595.231288ms","start":"2026-04-28T07:32:11.37301Z","end":"2026-04-28T07:32:11.968241Z","steps":["trace[1883143440] 'agreement among raft nodes before linearized reading' (duration: 594.120459ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:11.968312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:32:11.372987Z","time spent":"595.30928ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-28T07:32:36.100121Z","caller":"traceutil/trace.go:171","msg":"trace[23859840] linearizableReadLoop","detail":"{readStateIndex:1149; appliedIndex:1148; }","duration":"255.542303ms","start":"2026-04-28T07:32:35.844551Z","end":"2026-04-28T07:32:36.100094Z","steps":["trace[23859840] 'read index received' (duration: 255.216013ms)","trace[23859840] 'applied index is now lower than readState.Index' (duration: 324.93µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:32:36.100266Z","caller":"traceutil/trace.go:171","msg":"trace[1016769601] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"731.749002ms","start":"2026-04-28T07:32:35.368479Z","end":"2026-04-28T07:32:36.100228Z","steps":["trace[1016769601] 'process raft request' (duration: 731.369841ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:36.100358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.80054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:32:36.100458Z","caller":"traceutil/trace.go:171","msg":"trace[1019375140] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1109; }","duration":"255.943973ms","start":"2026-04-28T07:32:35.8445Z","end":"2026-04-28T07:32:36.100444Z","steps":["trace[1019375140] 'agreement among raft nodes before linearized reading' (duration: 255.79992ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:36.100468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:32:35.368456Z","time spent":"731.898835ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:32:36.239661Z","caller":"traceutil/trace.go:171","msg":"trace[1486987445] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"133.041097ms","start":"2026-04-28T07:32:36.106595Z","end":"2026-04-28T07:32:36.239636Z","steps":["trace[1486987445] 'process raft request' (duration: 116.551118ms)","trace[1486987445] 'compare' (duration: 16.396767ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:32:36.262798Z","caller":"traceutil/trace.go:171","msg":"trace[2125164821] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"155.3499ms","start":"2026-04-28T07:32:36.10742Z","end":"2026-04-28T07:32:36.26277Z","steps":["trace[2125164821] 'process raft request' (duration: 155.154715ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:32:36.263258Z","caller":"traceutil/trace.go:171","msg":"trace[1269946282] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"155.34951ms","start":"2026-04-28T07:32:36.107881Z","end":"2026-04-28T07:32:36.26323Z","steps":["trace[1269946282] 'process raft request' (duration: 154.844757ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:32:36.263278Z","caller":"traceutil/trace.go:171","msg":"trace[277019062] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"154.872407ms","start":"2026-04-28T07:32:36.108388Z","end":"2026-04-28T07:32:36.263261Z","steps":["trace[277019062] 'process raft request' (duration: 154.824566ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:32:36.263457Z","caller":"traceutil/trace.go:171","msg":"trace[2038177283] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"155.486644ms","start":"2026-04-28T07:32:36.107952Z","end":"2026-04-28T07:32:36.263438Z","steps":["trace[2038177283] 'process raft request' (duration: 155.159355ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:32:36.395899Z","caller":"traceutil/trace.go:171","msg":"trace[270287428] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"101.156269ms","start":"2026-04-28T07:32:36.294713Z","end":"2026-04-28T07:32:36.395869Z","steps":["trace[270287428] 'process raft request' (duration: 70.140935ms)","trace[270287428] 'compare' (duration: 30.885861ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:32:39.502629Z","caller":"traceutil/trace.go:171","msg":"trace[1128527882] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"253.972482ms","start":"2026-04-28T07:32:39.248626Z","end":"2026-04-28T07:32:39.502598Z","steps":["trace[1128527882] 'process raft request' (duration: 253.786798ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:32:39.972951Z","caller":"traceutil/trace.go:171","msg":"trace[1963705116] linearizableReadLoop","detail":"{readStateIndex:1163; appliedIndex:1162; }","duration":"128.712952ms","start":"2026-04-28T07:32:39.844215Z","end":"2026-04-28T07:32:39.972928Z","steps":["trace[1963705116] 'read index received' (duration: 120.574146ms)","trace[1963705116] 'applied index is now lower than readState.Index' (duration: 8.137456ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:32:39.973072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.840665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:32:39.973104Z","caller":"traceutil/trace.go:171","msg":"trace[425524854] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1122; }","duration":"128.923307ms","start":"2026-04-28T07:32:39.84417Z","end":"2026-04-28T07:32:39.973094Z","steps":["trace[425524854] 'agreement among raft nodes before linearized reading' (duration: 128.848696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:52.816907Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843037342123836,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T07:32:53.317693Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843037342123836,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T07:32:53.408312Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.247211795s","expected-duration":"1s"} {"level":"info","ts":"2026-04-28T07:32:53.408657Z","caller":"traceutil/trace.go:171","msg":"trace[1003438634] linearizableReadLoop","detail":"{readStateIndex:1258; appliedIndex:1257; }","duration":"1.09205034s","start":"2026-04-28T07:32:52.316585Z","end":"2026-04-28T07:32:53.408635Z","steps":["trace[1003438634] 'read index received' (duration: 1.091890516s)","trace[1003438634] 'applied index is now lower than readState.Index' (duration: 158.814µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:32:53.40875Z","caller":"traceutil/trace.go:171","msg":"trace[1671603382] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"1.247741258s","start":"2026-04-28T07:32:52.160998Z","end":"2026-04-28T07:32:53.408739Z","steps":["trace[1671603382] 'process raft request' (duration: 1.247498322s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:53.408856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:32:52.160978Z","time spent":"1.247804021s","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:32:53.40904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.055025656s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-28T07:32:53.40909Z","caller":"traceutil/trace.go:171","msg":"trace[207931932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1213; }","duration":"1.055075337s","start":"2026-04-28T07:32:52.354Z","end":"2026-04-28T07:32:53.409075Z","steps":["trace[207931932] 'agreement among raft nodes before linearized reading' (duration: 1.054930923s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:53.409127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:32:52.353964Z","time spent":"1.05515595s","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T07:32:53.409214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"980.624768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:32:53.409253Z","caller":"traceutil/trace.go:171","msg":"trace[630634302] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1213; }","duration":"980.69708ms","start":"2026-04-28T07:32:52.428544Z","end":"2026-04-28T07:32:53.409241Z","steps":["trace[630634302] 'agreement among raft nodes before linearized reading' (duration: 980.639009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:53.409287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:32:52.428527Z","time spent":"980.749182ms","remote":"127.0.0.1:58220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-28T07:32:53.409334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.016895913s","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-04-28T07:32:53.409364Z","caller":"traceutil/trace.go:171","msg":"trace[1634854360] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1213; }","duration":"1.016945054s","start":"2026-04-28T07:32:52.39241Z","end":"2026-04-28T07:32:53.409355Z","steps":["trace[1634854360] 'agreement among raft nodes before linearized reading' (duration: 1.016836911s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:53.40939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:32:52.392398Z","time spent":"1.016986355s","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-28T07:32:53.409471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.461089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T07:32:53.409473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.092860061s","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-04-28T07:32:53.409535Z","caller":"traceutil/trace.go:171","msg":"trace[397794846] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1213; }","duration":"1.092960524s","start":"2026-04-28T07:32:52.316558Z","end":"2026-04-28T07:32:53.409518Z","steps":["trace[397794846] 'agreement among raft nodes before linearized reading' (duration: 1.092754009s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:53.409548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.050533046s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-28T07:32:53.409569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:32:52.316544Z","time spent":"1.093016385s","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-28T07:32:53.409603Z","caller":"traceutil/trace.go:171","msg":"trace[851370611] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1213; }","duration":"1.050621778s","start":"2026-04-28T07:32:52.358967Z","end":"2026-04-28T07:32:53.409588Z","steps":["trace[851370611] 'agreement among raft nodes before linearized reading' (duration: 1.050440133s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:53.409634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:32:52.358954Z","time spent":"1.05067439s","remote":"127.0.0.1:46106","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-04-28T07:32:53.409638Z","caller":"traceutil/trace.go:171","msg":"trace[1467407694] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1213; }","duration":"484.5164ms","start":"2026-04-28T07:32:52.924976Z","end":"2026-04-28T07:32:53.409492Z","steps":["trace[1467407694] 'agreement among raft nodes before linearized reading' (duration: 484.462049ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:53.409667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.037499311s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-28T07:32:53.409678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:32:52.924965Z","time spent":"484.702185ms","remote":"127.0.0.1:58242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"info","ts":"2026-04-28T07:32:53.409697Z","caller":"traceutil/trace.go:171","msg":"trace[1613701285] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1213; }","duration":"1.037551041s","start":"2026-04-28T07:32:52.372137Z","end":"2026-04-28T07:32:53.409688Z","steps":["trace[1613701285] 'agreement among raft nodes before linearized reading' (duration: 1.037472529s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:32:53.409722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:32:52.372126Z","time spent":"1.037590452s","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-28T07:33:05.635972Z","caller":"traceutil/trace.go:171","msg":"trace[1794942920] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"163.790014ms","start":"2026-04-28T07:33:05.472155Z","end":"2026-04-28T07:33:05.635945Z","steps":["trace[1794942920] 'process raft request' (duration: 162.327475ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:33:05.64409Z","caller":"traceutil/trace.go:171","msg":"trace[1848728542] linearizableReadLoop","detail":"{readStateIndex:1304; appliedIndex:1301; }","duration":"160.426034ms","start":"2026-04-28T07:33:05.483647Z","end":"2026-04-28T07:33:05.644074Z","steps":["trace[1848728542] 'read index received' (duration: 150.848049ms)","trace[1848728542] 'applied index is now lower than readState.Index' (duration: 9.576525ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:33:05.644467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.664075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-28T07:33:05.644501Z","caller":"traceutil/trace.go:171","msg":"trace[1911695169] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1256; }","duration":"159.744377ms","start":"2026-04-28T07:33:05.484748Z","end":"2026-04-28T07:33:05.644492Z","steps":["trace[1911695169] 'agreement among raft nodes before linearized reading' (duration: 159.629494ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:33:05.644812Z","caller":"traceutil/trace.go:171","msg":"trace[1130412644] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"172.501787ms","start":"2026-04-28T07:33:05.4723Z","end":"2026-04-28T07:33:05.644802Z","steps":["trace[1130412644] 'process raft request' (duration: 171.661965ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:33:05.645054Z","caller":"traceutil/trace.go:171","msg":"trace[1480030713] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"168.685115ms","start":"2026-04-28T07:33:05.476358Z","end":"2026-04-28T07:33:05.645043Z","steps":["trace[1480030713] 'process raft request' (duration: 167.681008ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:05.646261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.606412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-28T07:33:05.64631Z","caller":"traceutil/trace.go:171","msg":"trace[1307546120] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1256; }","duration":"162.694865ms","start":"2026-04-28T07:33:05.483604Z","end":"2026-04-28T07:33:05.646299Z","steps":["trace[1307546120] 'agreement among raft nodes before linearized reading' (duration: 160.790744ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:33:09.874068Z","caller":"traceutil/trace.go:171","msg":"trace[1951357851] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"177.94903ms","start":"2026-04-28T07:33:09.696087Z","end":"2026-04-28T07:33:09.874036Z","steps":["trace[1951357851] 'process raft request' (duration: 121.265603ms)","trace[1951357851] 'compare' (duration: 56.475351ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:33:10.541592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.632467ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-28T07:33:10.977123Z","caller":"traceutil/trace.go:171","msg":"trace[1741901272] linearizableReadLoop","detail":"{readStateIndex:1342; appliedIndex:1341; }","duration":"395.338728ms","start":"2026-04-28T07:33:10.581763Z","end":"2026-04-28T07:33:10.977101Z","steps":["trace[1741901272] 'read index received' (duration: 395.183134ms)","trace[1741901272] 'applied index is now lower than readState.Index' (duration: 154.674µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:33:10.977185Z","caller":"traceutil/trace.go:171","msg":"trace[2068502968] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"823.895021ms","start":"2026-04-28T07:33:10.153269Z","end":"2026-04-28T07:33:10.977164Z","steps":["trace[2068502968] 'process raft request' (duration: 823.639685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:10.977259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.481413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-28T07:33:10.977294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:10.153246Z","time spent":"823.984074ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T07:33:10.977314Z","caller":"traceutil/trace.go:171","msg":"trace[1011269797] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1293; }","duration":"395.577525ms","start":"2026-04-28T07:33:10.581723Z","end":"2026-04-28T07:33:10.977301Z","steps":["trace[1011269797] 'agreement among raft nodes before linearized reading' (duration: 395.460171ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:10.977355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:10.58168Z","time spent":"395.666429ms","remote":"127.0.0.1:58274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-04-28T07:33:11.21555Z","caller":"traceutil/trace.go:171","msg":"trace[1969119345] linearizableReadLoop","detail":"{readStateIndex:1343; appliedIndex:1342; }","duration":"238.348627ms","start":"2026-04-28T07:33:10.977178Z","end":"2026-04-28T07:33:11.215527Z","steps":["trace[1969119345] 'read index received' (duration: 238.259395ms)","trace[1969119345] 'applied index is now lower than readState.Index' (duration: 88.112µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:33:11.215679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.67498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:33:11.215705Z","caller":"traceutil/trace.go:171","msg":"trace[951112193] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1294; }","duration":"374.748851ms","start":"2026-04-28T07:33:10.84095Z","end":"2026-04-28T07:33:11.215699Z","steps":["trace[951112193] 'agreement among raft nodes before linearized reading' (duration: 374.655569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:11.21573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:10.840938Z","time spent":"374.785072ms","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T07:33:11.216036Z","caller":"traceutil/trace.go:171","msg":"trace[1003295833] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"285.732097ms","start":"2026-04-28T07:33:10.930295Z","end":"2026-04-28T07:33:11.216027Z","steps":["trace[1003295833] 'process raft request' (duration: 285.089189ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:11.809357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.643051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-28T07:33:11.809363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.656524ms","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-04-28T07:33:11.809358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.665555ms","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-04-28T07:33:11.809421Z","caller":"traceutil/trace.go:171","msg":"trace[345364959] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1301; }","duration":"141.766868ms","start":"2026-04-28T07:33:11.667647Z","end":"2026-04-28T07:33:11.809414Z","steps":["trace[345364959] 'range keys from in-memory index tree' (duration: 141.501511ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:33:11.809433Z","caller":"traceutil/trace.go:171","msg":"trace[1509991501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1301; }","duration":"110.733983ms","start":"2026-04-28T07:33:11.698669Z","end":"2026-04-28T07:33:11.809403Z","steps":["trace[1509991501] 'range keys from in-memory index tree' (duration: 110.553388ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:33:11.809435Z","caller":"traceutil/trace.go:171","msg":"trace[1328926544] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1301; }","duration":"112.785968ms","start":"2026-04-28T07:33:11.696637Z","end":"2026-04-28T07:33:11.809423Z","steps":["trace[1328926544] 'range keys from in-memory index tree' (duration: 112.519811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:11.8095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.87251ms","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-04-28T07:33:11.809564Z","caller":"traceutil/trace.go:171","msg":"trace[1425004776] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1301; }","duration":"112.974922ms","start":"2026-04-28T07:33:11.696572Z","end":"2026-04-28T07:33:11.809547Z","steps":["trace[1425004776] 'range keys from in-memory index tree' (duration: 112.712916ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:33:11.989174Z","caller":"traceutil/trace.go:171","msg":"trace[297404811] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"176.715798ms","start":"2026-04-28T07:33:11.812432Z","end":"2026-04-28T07:33:11.989148Z","steps":["trace[297404811] 'process raft request' (duration: 176.531684ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:33:12.060911Z","caller":"traceutil/trace.go:171","msg":"trace[769826517] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"248.367082ms","start":"2026-04-28T07:33:11.812519Z","end":"2026-04-28T07:33:12.060886Z","steps":["trace[769826517] 'process raft request' (duration: 197.324145ms)","trace[769826517] 'compare' (duration: 50.875683ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:33:12.061154Z","caller":"traceutil/trace.go:171","msg":"trace[382191600] linearizableReadLoop","detail":"{readStateIndex:1354; appliedIndex:1350; }","duration":"216.686471ms","start":"2026-04-28T07:33:11.844445Z","end":"2026-04-28T07:33:12.061131Z","steps":["trace[382191600] 'read index received' (duration: 144.554934ms)","trace[382191600] 'applied index is now lower than readState.Index' (duration: 72.130567ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:33:12.061207Z","caller":"traceutil/trace.go:171","msg":"trace[1815577929] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"247.313184ms","start":"2026-04-28T07:33:11.813883Z","end":"2026-04-28T07:33:12.061196Z","steps":["trace[1815577929] 'process raft request' (duration: 247.18944ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:33:12.061229Z","caller":"traceutil/trace.go:171","msg":"trace[214698400] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"248.285271ms","start":"2026-04-28T07:33:11.812926Z","end":"2026-04-28T07:33:12.061212Z","steps":["trace[214698400] 'process raft request' (duration: 247.925201ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:12.061295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.841875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:33:12.061351Z","caller":"traceutil/trace.go:171","msg":"trace[1824702196] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1305; }","duration":"216.940817ms","start":"2026-04-28T07:33:11.844398Z","end":"2026-04-28T07:33:12.061339Z","steps":["trace[1824702196] 'agreement among raft nodes before linearized reading' (duration: 216.855965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:12.061366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.843518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-28T07:33:12.061393Z","caller":"traceutil/trace.go:171","msg":"trace[1179055166] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1305; }","duration":"183.903498ms","start":"2026-04-28T07:33:11.877482Z","end":"2026-04-28T07:33:12.061385Z","steps":["trace[1179055166] 'agreement among raft nodes before linearized reading' (duration: 183.806926ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:33:14.740764Z","caller":"traceutil/trace.go:171","msg":"trace[179011760] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"111.334849ms","start":"2026-04-28T07:33:14.629405Z","end":"2026-04-28T07:33:14.74074Z","steps":["trace[179011760] 'process raft request' (duration: 106.020088ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:33:23.991267Z","caller":"traceutil/trace.go:171","msg":"trace[70416232] linearizableReadLoop","detail":"{readStateIndex:1432; appliedIndex:1431; }","duration":"408.096408ms","start":"2026-04-28T07:33:23.583149Z","end":"2026-04-28T07:33:23.991246Z","steps":["trace[70416232] 'read index received' (duration: 407.900263ms)","trace[70416232] 'applied index is now lower than readState.Index' (duration: 195.395µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:33:23.991393Z","caller":"traceutil/trace.go:171","msg":"trace[1421871537] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"413.095972ms","start":"2026-04-28T07:33:23.578258Z","end":"2026-04-28T07:33:23.991354Z","steps":["trace[1421871537] 'process raft request' (duration: 412.839645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:23.99158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.408407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-28T07:33:23.991627Z","caller":"traceutil/trace.go:171","msg":"trace[1661741535] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1381; }","duration":"408.490019ms","start":"2026-04-28T07:33:23.583124Z","end":"2026-04-28T07:33:23.991614Z","steps":["trace[1661741535] 'agreement among raft nodes before linearized reading' (duration: 408.242522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:23.991663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:23.583117Z","time spent":"408.537401ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-28T07:33:23.991635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:23.578239Z","time spent":"413.269586ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:33:23.991779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.94123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T07:33:23.991808Z","caller":"traceutil/trace.go:171","msg":"trace[1606772861] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1381; }","duration":"149.004441ms","start":"2026-04-28T07:33:23.842796Z","end":"2026-04-28T07:33:23.991801Z","steps":["trace[1606772861] 'agreement among raft nodes before linearized reading' (duration: 148.9622ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:33:24.122076Z","caller":"traceutil/trace.go:171","msg":"trace[1061984168] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"125.65215ms","start":"2026-04-28T07:33:23.996383Z","end":"2026-04-28T07:33:24.122036Z","steps":["trace[1061984168] 'process raft request' (duration: 95.094948ms)","trace[1061984168] 'compare' (duration: 30.227663ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:33:24.666733Z","caller":"traceutil/trace.go:171","msg":"trace[660096189] linearizableReadLoop","detail":"{readStateIndex:1434; appliedIndex:1433; }","duration":"526.145257ms","start":"2026-04-28T07:33:24.14056Z","end":"2026-04-28T07:33:24.666705Z","steps":["trace[660096189] 'read index received' (duration: 472.397237ms)","trace[660096189] 'applied index is now lower than readState.Index' (duration: 53.74204ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:33:24.666816Z","caller":"traceutil/trace.go:171","msg":"trace[48663631] transaction","detail":"{read_only:false; response_revision:1383; number_of_response:1; }","duration":"540.556869ms","start":"2026-04-28T07:33:24.126214Z","end":"2026-04-28T07:33:24.666771Z","steps":["trace[48663631] 'process raft request' (duration: 486.809341ms)","trace[48663631] 'compare' (duration: 52.785203ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:33:24.666964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:24.126186Z","time spent":"540.688823ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T07:33:24.667044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.580519ms","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-04-28T07:33:24.667054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.478786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-28T07:33:24.66718Z","caller":"traceutil/trace.go:171","msg":"trace[1515453925] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1383; }","duration":"526.63678ms","start":"2026-04-28T07:33:24.140503Z","end":"2026-04-28T07:33:24.667139Z","steps":["trace[1515453925] 'agreement among raft nodes before linearized reading' (duration: 526.356002ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:24.667262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.762349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-28T07:33:24.667316Z","caller":"traceutil/trace.go:171","msg":"trace[392310487] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1383; }","duration":"287.857172ms","start":"2026-04-28T07:33:24.379446Z","end":"2026-04-28T07:33:24.667303Z","steps":["trace[392310487] 'agreement among raft nodes before linearized reading' (duration: 287.686528ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:24.667271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:24.14048Z","time spent":"526.779084ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-28T07:33:24.667106Z","caller":"traceutil/trace.go:171","msg":"trace[854528091] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1383; }","duration":"523.673701ms","start":"2026-04-28T07:33:24.143416Z","end":"2026-04-28T07:33:24.66709Z","steps":["trace[854528091] 'agreement among raft nodes before linearized reading' (duration: 523.461425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:24.667502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.438008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-28T07:33:24.667542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.488109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-28T07:33:24.667567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.356186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-28T07:33:24.667592Z","caller":"traceutil/trace.go:171","msg":"trace[1837692815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1383; }","duration":"522.50712ms","start":"2026-04-28T07:33:24.145046Z","end":"2026-04-28T07:33:24.667553Z","steps":["trace[1837692815] 'agreement among raft nodes before linearized reading' (duration: 522.354366ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:33:24.667604Z","caller":"traceutil/trace.go:171","msg":"trace[1793262269] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1383; }","duration":"130.607342ms","start":"2026-04-28T07:33:24.536982Z","end":"2026-04-28T07:33:24.667589Z","steps":["trace[1793262269] 'agreement among raft nodes before linearized reading' (duration: 130.495609ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:24.66759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:24.143402Z","time spent":"524.143713ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-28T07:33:24.667625Z","caller":"traceutil/trace.go:171","msg":"trace[1217991152] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1383; }","duration":"519.443229ms","start":"2026-04-28T07:33:24.148167Z","end":"2026-04-28T07:33:24.66761Z","steps":["trace[1217991152] 'agreement among raft nodes before linearized reading' (duration: 519.14071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:33:24.66764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:24.145026Z","time spent":"522.600923ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T07:33:24.667667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T07:33:24.148154Z","time spent":"519.504871ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-28T07:35:17.540596Z","caller":"traceutil/trace.go:171","msg":"trace[2133459998] linearizableReadLoop","detail":"{readStateIndex:1977; appliedIndex:1976; }","duration":"125.094213ms","start":"2026-04-28T07:35:17.415472Z","end":"2026-04-28T07:35:17.540567Z","steps":["trace[2133459998] 'read index received' (duration: 73.353914ms)","trace[2133459998] 'applied index is now lower than readState.Index' (duration: 51.738879ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T07:35:17.540678Z","caller":"traceutil/trace.go:171","msg":"trace[1720103769] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"135.907881ms","start":"2026-04-28T07:35:17.40473Z","end":"2026-04-28T07:35:17.540638Z","steps":["trace[1720103769] 'process raft request' (duration: 84.06619ms)","trace[1720103769] 'compare' (duration: 51.643446ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T07:35:17.540929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.365973ms","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-04-28T07:35:17.540961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.318868ms","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-04-28T07:35:17.540995Z","caller":"traceutil/trace.go:171","msg":"trace[1579380897] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1903; }","duration":"124.457324ms","start":"2026-04-28T07:35:17.416524Z","end":"2026-04-28T07:35:17.540981Z","steps":["trace[1579380897] 'agreement among raft nodes before linearized reading' (duration: 124.307061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:35:17.540933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.440301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-28T07:35:17.541006Z","caller":"traceutil/trace.go:171","msg":"trace[181682668] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1903; }","duration":"125.394561ms","start":"2026-04-28T07:35:17.415599Z","end":"2026-04-28T07:35:17.540994Z","steps":["trace[181682668] 'agreement among raft nodes before linearized reading' (duration: 125.146084ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:35:17.541122Z","caller":"traceutil/trace.go:171","msg":"trace[311521359] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1903; }","duration":"125.658537ms","start":"2026-04-28T07:35:17.415429Z","end":"2026-04-28T07:35:17.541087Z","steps":["trace[311521359] 'agreement among raft nodes before linearized reading' (duration: 125.253137ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:36:36.457073Z","caller":"traceutil/trace.go:171","msg":"trace[1258167739] transaction","detail":"{read_only:false; response_revision:2295; number_of_response:1; }","duration":"128.277038ms","start":"2026-04-28T07:36:36.328775Z","end":"2026-04-28T07:36:36.457052Z","steps":["trace[1258167739] 'process raft request' (duration: 128.165115ms)"],"step_count":1}